Fax Pro module configuration

Commercial Modules
, ,
1

Hello,

I have problem with fax pro module configuration.

I configured this module according to documentation:
Fax Pro-Admin Guide - PBX GUI - Sangoma Documentation

And I have a problem. When Im sending fax i getting error:
Timed out waiting for initial communication
I dont see any errors in logs
What does this error means? What should i look to find solution?

2

Try to push here Asterisk log(s) for Fax sending test.

3

Here’s logs:

7106 [2024-11-19 11:12:30] VERBOSE[30891] dial.c: Called 48227312501@special-fax-context
7107 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@special-fax-context:1] NoOp(Local/48227312501@special-fax-context-00000005;2, Sending fax to 48227312501) in new stack
7108 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@special-fax-context:2] Set(Local/48227312501@special-fax-context-00000005;2, CHANNEL(hangup_handler_push)=fax-hangup-handler,48227312501,1(1732011149909)) in new stack
7109 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@special-fax-context:3] Goto(Local/48227312501@special-fax-context-00000005;2, from-internal,48227312501,1) in new stack
7110 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx_builtins.c: Goto (from-internal,48227312501,1)
7111 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@from-internal:1] Macro(Local/48227312501@special-fax-context-00000005;2, user-callerid,LIMIT,EXTERNAL,) in new stack
7112 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:1] Set(Local/48227312501@special-fax-context-00000005;2, TOUCH_MONITOR=1732011150.468) in new stack
7113 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:2] Set(Local/48227312501@special-fax-context-00000005;2, CHANCONTEXT=special-fax-context-00000005;2) in new stack
7114 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:3] Progress(Local/48227312501@special-fax-context-00000005;2, ) in new stack
7115 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:4] Set(Local/48227312501@special-fax-context-00000005;2, CHANCONTEXT=special) in new stack
7116 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:5] Set(Local/48227312501@special-fax-context-00000005;2, CHANEXTENCONTEXT=48227312501@special-fax-context-00000005;2) in new stack
7117 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:6] Set(Local/48227312501@special-fax-context-00000005;2, CHANEXTEN=48227312501) in new stack
7118 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:7] Set(Local/48227312501@special-fax-context-00000005;2, CALLERID(number)=102) in new stack
7119 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:8] Set(Local/48227312501@special-fax-context-00000005;2, AMPUSER=102) in new stack
7120 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:9] Set(Local/48227312501@special-fax-context-00000005;2, HOTDESCKCHAN=48227312501@special-fax-context-00000005;2) in new stack
7121 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:10] Set(Local/48227312501@special-fax-context-00000005;2, HOTDESKEXTEN=48227312501@special) in new stack
7122 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:11] Set(Local/48227312501@special-fax-context-00000005;2, HOTDESKCALL=0) in new stack
7123 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:12] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(HOTDESKCALL=1)) in new stack
7124 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(CALLERID(name)=)) in new stack
7125 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:14] GotoIf(Local/48227312501@special-fax-context-00000005;2, 0?report) in new stack
7126 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:15] ExecIf(Local/48227312501@special-fax-context-00000005;2, 1?Set(REALCALLERIDNUM=102)) in new stack
7127 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:16] Set(Local/48227312501@special-fax-context-00000005;2, AMPUSER=102) in new stack
7128 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(Local/48227312501@special-fax-context-00000005;2, 0?limit) in new stack
7129 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:18] Set(Local/48227312501@special-fax-context-00000005;2, AMPUSERCIDNAME=Adrian Pyszny FLY) in new stack
7130 [2024-11-19 11:12:30] VERBOSE[30891] dial.c: Local/48227312501@special-fax-context-00000005;1 is making progress
7131 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:19] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(__CIDMASQUERADING=TRUE)) in new stack
7132 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:20] GotoIf(Local/48227312501@special-fax-context-00000005;2, 0?report) in new stack
7133 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:21] Set(Local/48227312501@special-fax-context-00000005;2, AMPUSERCID=102) in new stack
7134 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:22] Set(Local/48227312501@special-fax-context-00000005;2, __DIAL_OPTIONS=HhTtr) in new stack
7135 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:23] Set(Local/48227312501@special-fax-context-00000005;2, CALLERID(all)=Adrian Pyszny FLY <102>) in new stack
7136 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:24] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(CUSDIAL=)) in new stack
7137 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:25] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(CALLERID(all)=Adrian Pyszny FLY <102>)) in new stack
7138 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:26] GotoIf(Local/48227312501@special-fax-context-00000005;2, 0?limit) in new stack
7139 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:27] ExecIf(Local/48227312501@special-fax-context-00000005;2, 1?Set(GROUP(concurrency_limit)=102)) in new stack
7140 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:28] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(CHANNEL(language)=)) in new stack
7141 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:29] NoOp(Local/48227312501@special-fax-context-00000005;2, Macro Depth is 1) in new stack
7142 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:30] GotoIf(Local/48227312501@special-fax-context-00000005;2, 1?report2:macroerror) in new stack
7143 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx_builtins.c: Goto (macro-user-callerid,s,31)
7144 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:31] GotoIf(Local/48227312501@special-fax-context-00000005;2, 1?continue) in new stack
7145 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx_builtins.c: Goto (macro-user-callerid,s,50)
7146 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:50] Set(Local/48227312501@special-fax-context-00000005;2, CALLERID(number)=102) in new stack
7147 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:51] Set(Local/48227312501@special-fax-context-00000005;2, CALLERID(name)=Adrian Pyszny FLY) in new stack
7148 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:52] GotoIf(Local/48227312501@special-fax-context-00000005;2, 0?cnum) in new stack
7149 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:53] Set(Local/48227312501@special-fax-context-00000005;2, __MCNUM=102) in new stack
7150 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:54] Set(Local/48227312501@special-fax-context-00000005;2, __MCNAME=Adrian Pyszny FLY) in new stack
7151 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:55] Set(Local/48227312501@special-fax-context-00000005;2, __MCEXTEN=102) in new stack
7152 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:56] Set(Local/48227312501@special-fax-context-00000005;2, __MCORGCHAN=Local/48227312501@special-fax-context-00000005;2) in new stack
7153 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:57] Set(Local/48227312501@special-fax-context-00000005;2, CDR(cnam)=Adrian Pyszny FLY) in new stack
7154 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:58] Set(Local/48227312501@special-fax-context-00000005;2, CDR(cnum)=102) in new stack
7155 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-user-callerid:59] Set(Local/48227312501@special-fax-context-00000005;2, CHANNEL(language)=en) in new stack
7156 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@from-internal:2] Gosub(Local/48227312501@special-fax-context-00000005;2, sub-record-check,s,1(out,48227312501,dontcare)) in new stack
7157 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-record-check:1] GotoIf(Local/48227312501@special-fax-context-00000005;2, 0?initialized) in new stack
7158 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-record-check:2] Set(Local/48227312501@special-fax-context-00000005;2, __REC_STATUS=INITIALIZED) in new stack
7159 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-record-check:3] Set(Local/48227312501@special-fax-context-00000005;2, NOW=1732011150) in new stack
7160 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-record-check:4] Set(Local/48227312501@special-fax-context-00000005;2, __DAY=19) in new stack
7161 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-record-check:5] Set(Local/48227312501@special-fax-context-00000005;2, __MONTH=11) in new stack
7162 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-record-check:6] Set(Local/48227312501@special-fax-context-00000005;2, __YEAR=2024) in new stack
7163 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-record-check:7] Set(Local/48227312501@special-fax-context-00000005;2, __TIMESTR=20241119-111230) in new stack
7164 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-record-check:8] Set(Local/48227312501@special-fax-context-00000005;2, __FROMEXTEN=102) in new stack
7165 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-record-check:9] Set(Local/48227312501@special-fax-context-00000005;2, __MON_FMT=wav) in new stack
7166 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-record-check:10] NoOp(Local/48227312501@special-fax-context-00000005;2, Recordings initialized) in new stack
7167 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-record-check:11] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(ARG3=dontcare)) in new stack
7168 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-record-check:12] Set(Local/48227312501@special-fax-context-00000005;2, REC_POLICY_MODE_SAVE=) in new stack
7169 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-record-check:13] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(REC_STATUS=NO)) in new stack
7170 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-record-check:14] GotoIf(Local/48227312501@special-fax-context-00000005;2, 3?checkaction) in new stack
7171 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx_builtins.c: Goto (sub-record-check,s,17)
7172 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-record-check:17] GotoIf(Local/48227312501@special-fax-context-00000005;2, 1?sub-record-check,out,1) in new stack
7173 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx_builtins.c: Goto (sub-record-check,out,1)
7174 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [out@sub-record-check:1] NoOp(Local/48227312501@special-fax-context-00000005;2, Outbound Recording Check from 102 to 48227312501) in new stack
7175 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [out@sub-record-check:2] Set(Local/48227312501@special-fax-context-00000005;2, RECMODE=dontcare) in new stack
7176 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [out@sub-record-check:3] ExecIf(Local/48227312501@special-fax-context-00000005;2, 1?Goto(routewins)) in new stack
7177 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx_builtins.c: Goto (sub-record-check,out,7)
7178 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [out@sub-record-check:7] Gosub(Local/48227312501@special-fax-context-00000005;2, recordcheck,1(dontcare,out,48227312501)) in new stack
7179 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(Local/48227312501@special-fax-context-00000005;2, Starting recording check against dontcare) in new stack
7180 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(Local/48227312501@special-fax-context-00000005;2, dontcare) in new stack
7181 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
7182 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [recordcheck@sub-record-check:3] Return(Local/48227312501@special-fax-context-00000005;2, ) in new stack
7183 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [out@sub-record-check:8] Return(Local/48227312501@special-fax-context-00000005;2, ) in new stack
7184 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@from-internal:3] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0 ?Set(CDR(accountcode)=)) in new stack
7185 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@from-internal:4] Set(Local/48227312501@special-fax-context-00000005;2, _ROUTEID=1) in new stack
7186 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@from-internal:5] Set(Local/48227312501@special-fax-context-00000005;2, _ROUTENAME=out_50) in new stack
7187 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@from-internal:6] Set(Local/48227312501@special-fax-context-00000005;2, MOHCLASS=default) in new stack
7188 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@from-internal:7] Set(Local/48227312501@special-fax-context-00000005;2, _CALLERIDNAMEINTERNAL=Adrian Pyszny FLY) in new stack
7189 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@from-internal:8] Set(Local/48227312501@special-fax-context-00000005;2, _CALLERIDNUMINTERNAL=102) in new stack
7190 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@from-internal:9] Set(Local/48227312501@special-fax-context-00000005;2, _EMAILNOTIFICATION=FALSE) in new stack
7191 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@from-internal:10] Set(Local/48227312501@special-fax-context-00000005;2, _NODEST=) in new stack
7192 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@from-internal:11] Macro(Local/48227312501@special-fax-context-00000005;2, dialout-trunk,3,48227312501,off) in new stack
7193 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:1] Set(Local/48227312501@special-fax-context-00000005;2, DIAL_TRUNK=3) in new stack
7194 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(DIAL_OPTIONS=Hhtr)) in new stack
7195 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:3] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(DIAL_OPTIONS=HhTr)) in new stack
7196 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(DIAL_OPTIONS=Hhtr)) in new stack
7197 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:5] GosubIf(Local/48227312501@special-fax-context-00000005;2, 0?sub-pincheck,s,1()) in new stack
7198 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:6] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(CALLERID(num)=102)) in new stack
7199 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf(Local/48227312501@special-fax-context-00000005;2, 0?disabletrunk,1) in new stack
7200 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:8] Set(Local/48227312501@special-fax-context-00000005;2, DIAL_NUMBER=48227312501) in new stack
7201 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:9] Set(Local/48227312501@special-fax-context-00000005;2, DIAL_TRUNK_OPTIONS=HhTtr) in new stack
7202 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:10] Set(Local/48227312501@special-fax-context-00000005;2, OUTBOUND_GROUP=OUT_3) in new stack
7203 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:11] Set(Local/48227312501@special-fax-context-00000005;2, DIAL_TRUNK_OPTIONS=T) in new stack
7204 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:12] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(DIAL_TRUNK_OPTIONS=)) in new stack
7205 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:13] GotoIf(Local/48227312501@special-fax-context-00000005;2, 0?nomax) in new stack
7206 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:14] GotoIf(Local/48227312501@special-fax-context-00000005;2, 0?chanfull) in new stack
7207 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:15] GotoIf(Local/48227312501@special-fax-context-00000005;2, 0?skipoutcid) in new stack
7208 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:16] Macro(Local/48227312501@special-fax-context-00000005;2, outbound-callerid,3) in new stack
7209 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp(Local/48227312501@special-fax-context-00000005;2, 102) in new stack
7210 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp(Local/48227312501@special-fax-context-00000005;2, ) in new stack
7211 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp(Local/48227312501@special-fax-context-00000005;2, off) in new stack
7212 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(CALLERID(name-pres)=)) in new stack
7213 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(CALLERID(num-pres)=)) in new stack
7214 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:6] Set(Local/48227312501@special-fax-context-00000005;2, HOTDESCKCHAN=48227312501@special-fax-context-00000005;2) in new stack
7215 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:7] Set(Local/48227312501@special-fax-context-00000005;2, HOTDESKEXTEN=48227312501@special) in new stack
7216 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:8] Set(Local/48227312501@special-fax-context-00000005;2, HOTDESKCALL=0) in new stack
7217 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(HOTDESKCALL=1)) in new stack
7218 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(CALLERID(name)=)) in new stack
7219 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:11] Set(Local/48227312501@special-fax-context-00000005;2, ALLOWTHISROUTE=NO) in new stack
7220 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(ALLOWTHISROUTE=YES)) in new stack
7221 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Hangup()) in new stack
7222 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(REALCALLERIDNUM=102)) in new stack
7223 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(AMPUSER=102)) in new stack
7224 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf(Local/48227312501@special-fax-context-00000005;2, 1?normcid) in new stack
7225 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
7226 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:20] Set(Local/48227312501@special-fax-context-00000005;2, USEROUTCID=) in new stack
7227 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:21] Set(Local/48227312501@special-fax-context-00000005;2, EMERGENCYCID=) in new stack
7228 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(SCDEXT=YES)) in new stack
7229 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:23] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(EMERGENCYCID=)) in new stack
7230 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:24] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(CALLERID(name)=)) in new stack
7231 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:25] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(EMERGENCYCID=)) in new stack
7232 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:26] Set(Local/48227312501@special-fax-context-00000005;2, TRUNKOUTCID=324612352) in new stack
7233 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:27] GotoIf(Local/48227312501@special-fax-context-00000005;2, 1?trunkcid) in new stack
7234 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx_builtins.c: Goto (macro-outbound-callerid,s,33)
7235 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf(Local/48227312501@special-fax-context-00000005;2, 1?Set(CALLERID(all)=324612352)) in new stack
7236 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:34] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(CALLERID(all)=)) in new stack
7237 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:35] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(CALLERID(all)=)) in new stack
7238 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(CALLERID(all)=102)) in new stack
7239 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(CALLERID(all)=102)) in new stack
7240 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:38] Set(Local/48227312501@special-fax-context-00000005;2, TIOHIDE=no) in new stack
7241 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:39] ExecIf(Local/48227312501@special-fax-context-00000005;2, 0?Set(CALLERID(name-pres)=prohib_passed_screen)) in new stack
4

and second part:

7242 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:40] ExecIf(“Local/48227312501@special-fax-context-00000005;2”, “0?Set(CALLERID(num-pres)=prohib_passed_screen)”) in new stack
7243 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:41] ExecIf(“Local/48227312501@special-fax-context-00000005;2”, “0?Set(CALLERID(name-pres)=prohib_passed_screen)”) in new stack
7244 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:42] ExecIf(“Local/48227312501@special-fax-context-00000005;2”, “0?Set(CALLERID(num-pres)=prohib_passed_screen)”) in new stack
7245 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:43] Set(“Local/48227312501@special-fax-context-00000005;2”, “CDR(outbound_cnum)=324612352”) in new stack
7246 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-outbound-callerid:44] Set(“Local/48227312501@special-fax-context-00000005;2”, “CDR(outbound_cnam)=”) in new stack
7247 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:17] GosubIf(“Local/48227312501@special-fax-context-00000005;2”, “1?sub-flp-3,s,1()”) in new stack
7248 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-flp-3:1] ExecIf(“Local/48227312501@special-fax-context-00000005;2”, “1?Set(TARGET_FLP_3=048227312501)”) in new stack
7249 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-flp-3:2] GotoIf(“Local/48227312501@special-fax-context-00000005;2”, “1?match”) in new stack
7250 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx_builtins.c: Goto (sub-flp-3,s,4)
7251 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-flp-3:4] Set(“Local/48227312501@special-fax-context-00000005;2”, “DIAL_NUMBER=048227312501”) in new stack
7252 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-flp-3:5] Return(“Local/48227312501@special-fax-context-00000005;2”, “”) in new stack
7253 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:18] Set(“Local/48227312501@special-fax-context-00000005;2”, “OUTNUM=048227312501”) in new stack
7254 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:19] Set(“Local/48227312501@special-fax-context-00000005;2”, “custom=SIP/1-pstn4”) in new stack
7255 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf(“Local/48227312501@special-fax-context-00000005;2”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
7256 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf(“Local/48227312501@special-fax-context-00000005;2”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
7257 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:22] ExecIf(“Local/48227312501@special-fax-context-00000005;2”, “0?AGI(allowlist-autoadd.agi,)”) in new stack
7258 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:23] Macro(“Local/48227312501@special-fax-context-00000005;2”, “dialout-trunk-predial-hook,”) in new stack
7259 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“Local/48227312501@special-fax-context-00000005;2”, “”) in new stack
7260 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:24] GotoIf(“Local/48227312501@special-fax-context-00000005;2”, “0?skipcrm”) in new stack
7261 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:25] Set(“Local/48227312501@special-fax-context-00000005;2”, “__CRM_DIRECTION=OUTBOUND”) in new stack
7262 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:26] Set(“Local/48227312501@special-fax-context-00000005;2”, “__CRM_DESTINATION=048227312501”) in new stack
7263 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:27] Set(“Local/48227312501@special-fax-context-00000005;2”, “__CRM_SOURCE=102”) in new stack
7264 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:28] AGI(“Local/48227312501@special-fax-context-00000005;2”, “agi://127.0.0.1/sangomacrm.agi”) in new stack
7265 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] res_agi.c: <Local/48227312501@special-fax-context-00000005;2>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
7266 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:29] Set(“Local/48227312501@special-fax-context-00000005;2”, “CHANNEL(hangup_handler_push)=crm-hangup,s,1”) in new stack
7267 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:30] NoOp(“Local/48227312501@special-fax-context-00000005;2”, “CRM Finished”) in new stack
7268 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf(“Local/48227312501@special-fax-context-00000005;2”, “0?bypass,1”) in new stack
7269 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf(“Local/48227312501@special-fax-context-00000005;2”, “1?Set(CONNECTEDLINE(num,i)=048227312501)”) in new stack
7270 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:33] ExecIf(“Local/48227312501@special-fax-context-00000005;2”, “1?Set(CONNECTEDLINE(name,i)=CID:324612352)”) in new stack
7271 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:34] ExecIf(“Local/48227312501@special-fax-context-00000005;2”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)324612352)”) in new stack
7272 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:35] GotoIf(“Local/48227312501@special-fax-context-00000005;2”, “0?customtrunk”) in new stack
7273 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:36] ExecIf(“Local/48227312501@special-fax-context-00000005;2”, “0?Set(DIAL_TRUNK_OPTIONS=)”) in new stack
7274 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:37] Set(“Local/48227312501@special-fax-context-00000005;2”, “HASH(__SIPHEADERS,Alert-Info)=unset”) in new stack
7275 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-dialout-trunk:38] Dial(“Local/48227312501@special-fax-context-00000005;2”, “SIP/1-pstn4/048227312501,300,Tb(func-apply-sipheaders^s^1,(3))U(sub-send-obroute-email^048227312501^48227312501^3^1732011150^^324612352)”) in new stack
7276 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] netsock2.c: Using SIP RTP TOS bits 184
7277 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] netsock2.c: Using SIP RTP CoS mark 5
7278 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] app_stack.c: SIP/1-pstn4-000001b4 Internal Gosub(func-apply-sipheaders,s,1(3)) start
7279 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“SIP/1-pstn4-000001b4”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
7280 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“SIP/1-pstn4-000001b4”, “Applying SIP Headers to channel SIP/1-pstn4-000001b4”) in new stack
7281 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“SIP/1-pstn4-000001b4”, “localchan=1-pstn4-000001b4”) in new stack
7282 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“SIP/1-pstn4-000001b4”, “DialMCEXT=1”) in new stack
7283 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:5] Set(“SIP/1-pstn4-000001b4”, “CHANNEL(hangup_handler_push)=app-missedcall-hangup,1,1”) in new stack
7284 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“SIP/1-pstn4-000001b4”, “TECH=SIP”) in new stack
7285 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:7] Set(“SIP/1-pstn4-000001b4”, “SIPHEADERKEYS=Alert-Info”) in new stack
7286 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:8] While(“SIP/1-pstn4-000001b4”, “1”) in new stack
7287 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:9] Set(“SIP/1-pstn4-000001b4”, “sipheader=unset”) in new stack
7288 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“SIP/1-pstn4-000001b4”, “1?SIPRemoveHeader(Alert-Info:)”) in new stack
7289 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“SIP/1-pstn4-000001b4”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
7290 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf(“SIP/1-pstn4-000001b4”, “0?Set(sipheader=http://127.0.0.1;info=unset)”) in new stack
7291 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:13] ExecIf(“SIP/1-pstn4-000001b4”, “0?Set(sipheader=http://127.0.0.1unset)”) in new stack
7292 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:14] ExecIf(“SIP/1-pstn4-000001b4”, “0?SIPAddHeader(Alert-Info:unset)”) in new stack
7293 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:15] ExecIf(“SIP/1-pstn4-000001b4”, “0?Set(PJSIP_HEADER(add,Alert-Info)=unset)”) in new stack
7294 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:16] EndWhile(“SIP/1-pstn4-000001b4”, “”) in new stack
7295 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:8] While(“SIP/1-pstn4-000001b4”, “0”) in new stack
7296 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@func-apply-sipheaders:17] Return(“SIP/1-pstn4-000001b4”, “”) in new stack
7297 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] app_stack.c: Spawn extension (from-trunk, 48227312501, 1) exited non-zero on ‘SIP/1-pstn4-000001b4’
7298 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] app_stack.c: SIP/1-pstn4-000001b4 Internal Gosub(func-apply-sipheaders,s,1(3)) complete GOSUB_RETVAL=
7299 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] app_dial.c: Called SIP/1-pstn4/048227312501
7300 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] app_dial.c: SIP/1-pstn4-000001b4 is ringing
7301 [2024-11-19 11:12:30] VERBOSE[30891] dial.c: Local/48227312501@special-fax-context-00000005;1 is ringing
7302 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] app_dial.c: SIP/1-pstn4-000001b4 answered Local/48227312501@special-fax-context-00000005;2
7303 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] app_stack.c: SIP/1-pstn4-000001b4 Internal Gosub(sub-send-obroute-email,s,1(048227312501,48227312501,3,1732011150,324612352)) start
7304 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-send-obroute-email:1] GotoIf(“SIP/1-pstn4-000001b4”, “0?sendEmail”) in new stack
7305 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-send-obroute-email:2] NoOp(“SIP/1-pstn4-000001b4”, “email notifications disabled…exiting.”) in new stack
7306 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@sub-send-obroute-email:3] Return(“SIP/1-pstn4-000001b4”, “”) in new stack
7307 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] app_stack.c: Spawn extension (from-trunk, , 1) exited non-zero on ‘SIP/1-pstn4-000001b4’
7308 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] app_stack.c: SIP/1-pstn4-000001b4 Internal Gosub(sub-send-obroute-email,s,1(048227312501,48227312501,3,1732011150,324612352)) complete GOSUB_RETVAL=
7309 [2024-11-19 11:12:30] VERBOSE[30891] dial.c: Local/48227312501@special-fax-context-00000005;1 answered
7310 [2024-11-19 11:12:30] VERBOSE[30891][C-000000d0] pbx.c: Executing [s@send-fax:1] NoOp(“Local/48227312501@special-fax-context-00000005;1”, “Sending fax from 102 to 48227312501”) in new stack
7311 [2024-11-19 11:12:30] VERBOSE[30891][C-000000d0] pbx.c: Executing [s@send-fax:2] System(“Local/48227312501@special-fax-context-00000005;1”, "/var/lib/asterisk/bin/faxpro_helper.php --faxid=“1732011149909” --stationid=“324612352” --status=“In Progress” --channel=“Local/48227312501@special-fax-context-00000005;1” ") in new stack
7312 [2024-11-19 11:12:30] VERBOSE[30904][C-000000cf] bridge_channel.c: Channel SIP/1-pstn4-000001b4 joined ‘simple_bridge’ basic-bridge <1d78ed7f-36da-4aca-a88b-7d5a039c9b1a>
7313 [2024-11-19 11:12:30] VERBOSE[30892][C-000000cf] bridge_channel.c: Channel Local/48227312501@special-fax-context-00000005;2 joined ‘simple_bridge’ basic-bridge <1d78ed7f-36da-4aca-a88b-7d5a039c9b1a>
7314 [2024-11-19 11:12:30] WARNING[30904][C-000000cf] chan_sip.c: Don’t know how to indicate condition 36
7315 [2024-11-19 11:12:30] VERBOSE[30891][C-000000d0] pbx.c: Executing [s@send-fax:3] SendFAX(“Local/48227312501@special-fax-context-00000005;1”, “/var/spool/asterisk/tmp/fax-1732011150843.tif,zf”) in new stack
7316 [2024-11-19 11:12:30] VERBOSE[30891][C-000000d0] res_fax.c: Channel ‘Local/48227312501@special-fax-context-00000005;1’ sending FAX:
7317 [2024-11-19 11:12:30] VERBOSE[30891][C-000000d0] res_fax.c: /var/spool/asterisk/tmp/fax-1732011150843.tif
7318 [2024-11-19 11:12:33] VERBOSE[2522] chan_sip.c: Registered SIP ‘1-pstn3’ at 10.32.5.3:5060
7319 [2024-11-19 11:13:18] VERBOSE[2522] chan_sip.c: Registered SIP ‘1-pstn3’ at 10.32.5.3:5061
7320 [2024-11-19 11:13:31] VERBOSE[30891][C-000000d0] pbx.c: Auto fallthrough, channel ‘Local/48227312501@special-fax-context-00000005;1’ status is ‘UNKNOWN’
7321 [2024-11-19 11:13:31] VERBOSE[30891][C-000000d0] pbx.c: Executing [h@send-fax:1] System(“Local/48227312501@special-fax-context-00000005;1”, "/var/lib/asterisk/bin/faxpro_helper.php --faxid=“1732011149909” --stationid=“324612352” --status=“Timed out waiting for initial communication” --action=“sent” ") in new stack
7322 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] bridge_channel.c: Channel Local/48227312501@special-fax-context-00000005;2 left ‘simple_bridge’ basic-bridge <1d78ed7f-36da-4aca-a88b-7d5a039c9b1a>
7323 [2024-11-19 11:13:31] VERBOSE[30904][C-000000cf] bridge_channel.c: Channel SIP/1-pstn4-000001b4 left ‘simple_bridge’ basic-bridge <1d78ed7f-36da-4aca-a88b-7d5a039c9b1a>
7324 [2024-11-19 11:13:31] VERBOSE[30904][C-000000cf] app_stack.c: SIP/1-pstn4-000001b4 Internal Gosub(app-missedcall-hangup,1,1) start
7325 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] app_macro.c: Spawn extension (macro-dialout-trunk, s, 38) exited non-zero on ‘Local/48227312501@special-fax-context-00000005;2’ in macro ‘dialout-trunk’
7326 [2024-11-19 11:13:31] VERBOSE[30904][C-000000cf] pbx.c: Executing [1@app-missedcall-hangup:1] NoOp(“SIP/1-pstn4-000001b4”, “Dialed: 1”) in new stack
7327 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Spawn extension (from-internal, 48227312501, 11) exited non-zero on ‘Local/48227312501@special-fax-context-00000005;2’
7328 [2024-11-19 11:13:31] VERBOSE[30904][C-000000cf] pbx.c: Executing [1@app-missedcall-hangup:2] NoOp(“SIP/1-pstn4-000001b4”, “Caller: 102”) in new stack
7329 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [h@from-internal:1] Macro(“Local/48227312501@special-fax-context-00000005;2”, “hangupcall”) in new stack
7330 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-hangupcall:1] Set(“Local/48227312501@special-fax-context-00000005;2”, “__MCVMSTATUS=”) in new stack
7331 [2024-11-19 11:13:31] VERBOSE[30904][C-000000cf] pbx.c: Executing [1@app-missedcall-hangup:3] GotoIf(“SIP/1-pstn4-000001b4”, “0?exit”) in new stack
7332 [2024-11-19 11:13:31] VERBOSE[30904][C-000000cf] pbx.c: Executing [1@app-missedcall-hangup:4] Set(“SIP/1-pstn4-000001b4”, “EXTENNUM=1”) in new stack
7333 [2024-11-19 11:13:31] VERBOSE[30904][C-000000cf] pbx.c: Executing [1@app-missedcall-hangup:5] Set(“SIP/1-pstn4-000001b4”, “FEXTENNUM=1”) in new stack
7334 [2024-11-19 11:13:31] VERBOSE[30904][C-000000cf] pbx.c: Executing [1@app-missedcall-hangup:6] GotoIf(“SIP/1-pstn4-000001b4”, “0?exit”) in new stack
7335 [2024-11-19 11:13:31] VERBOSE[30904][C-000000cf] pbx.c: Executing [1@app-missedcall-hangup:7] AGI(“SIP/1-pstn4-000001b4”, “agi://127.0.0.1/missedcallnotify.php,1,1,0,SIP/1-pstn4-000001b4,”) in new stack
7336 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-hangupcall:2] Gosub(“Local/48227312501@special-fax-context-00000005;2”, “app-missedcall-hangup,s,1()”) in new stack
7337 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@app-missedcall-hangup:1] NoOp(“Local/48227312501@special-fax-context-00000005;2”, “Dialed: s”) in new stack
7338 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@app-missedcall-hangup:2] NoOp(“Local/48227312501@special-fax-context-00000005;2”, “Caller: 102”) in new stack
7339 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@app-missedcall-hangup:3] GotoIf(“Local/48227312501@special-fax-context-00000005;2”, “1?exit”) in new stack
7340 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx_builtins.c: Goto (app-missedcall-hangup,s,8)
7341 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@app-missedcall-hangup:8] Return(“Local/48227312501@special-fax-context-00000005;2”, “”) in new stack
7342 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-hangupcall:3] GotoIf(“Local/48227312501@special-fax-context-00000005;2”, “1?theend”) in new stack
7343 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx_builtins.c: Goto (macro-hangupcall,s,5)
7344 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-hangupcall:5] ExecIf(“Local/48227312501@special-fax-context-00000005;2”, “0?Set(CDR(recordingfile)=)”) in new stack
7345 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@macro-hangupcall:6] Hangup(“Local/48227312501@special-fax-context-00000005;2”, “”) in new stack
7346 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘Local/48227312501@special-fax-context-00000005;2’ in macro ‘hangupcall’
7347 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/48227312501@special-fax-context-00000005;2’
7348 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] app_stack.c: Local/48227312501@special-fax-context-00000005;2 Internal Gosub(crm-hangup,s,1) start
7349 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@crm-hangup:1] NoOp(“Local/48227312501@special-fax-context-00000005;2”, “Sending Hangup to CRM”) in new stack
7350 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@crm-hangup:2] NoOp(“Local/48227312501@special-fax-context-00000005;2”, “HANGUP CAUSE: 16”) in new stack
7351 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@crm-hangup:3] ExecIf(“Local/48227312501@special-fax-context-00000005;2”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
7352 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@crm-hangup:4] NoOp(“Local/48227312501@special-fax-context-00000005;2”, “MASTER CHANNEL: 1732011150.468 = 1732011150.468”) in new stack
7353 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@crm-hangup:5] GotoIf(“Local/48227312501@special-fax-context-00000005;2”, “0?return”) in new stack
7354 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@crm-hangup:6] Set(“Local/48227312501@special-fax-context-00000005;2”, “__CRM_HANGUP=1”) in new stack
7355 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@crm-hangup:7] AGI(“Local/48227312501@special-fax-context-00000005;2”, “agi://127.0.0.1/sangomacrm.agi”) in new stack
7356 [2024-11-19 11:13:31] VERBOSE[30904][C-000000cf] res_agi.c: <SIP/1-pstn4-000001b4>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
7357 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] res_agi.c: <Local/48227312501@special-fax-context-00000005;2>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
7358 [2024-11-19 11:13:31] VERBOSE[30904][C-000000cf] pbx.c: Executing [1@app-missedcall-hangup:8] Return(“SIP/1-pstn4-000001b4”, “”) in new stack
7359 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [s@crm-hangup:8] Return(“Local/48227312501@special-fax-context-00000005;2”, “”) in new stack
7360 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/48227312501@special-fax-context-00000005;2’
7361 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] app_stack.c: Local/48227312501@special-fax-context-00000005;2 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
7362 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] app_stack.c: Local/48227312501@special-fax-context-00000005;2 Internal Gosub(fax-hangup-handler,48227312501,1(1732011149909)) start
7363 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@fax-hangup-handler:1] NoOp(“Local/48227312501@special-fax-context-00000005;2”, “DIAL STATUS ANSWER”) in new stack
7364 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@fax-hangup-handler:2] ExecIf(“Local/48227312501@special-fax-context-00000005;2”, “0?system(/var/lib/asterisk/bin/faxpro_helper.php --faxid=“1732011149909” --action=“sent” --status=“ANSWER”)”) in new stack
7365 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@fax-hangup-handler:3] Return(“Local/48227312501@special-fax-context-00000005;2”, “”) in new stack
7366 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/48227312501@special-fax-context-00000005;2’
7367 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] app_stack.c: Local/48227312501@special-fax-context-00000005;2 Internal Gosub(fax-hangup-handler,48227312501,1(1732011149909)) complete GOSUB_RETVAL=
7368 [2024-11-19 11:13:31] VERBOSE[30904][C-000000cf] app_stack.c: Spawn extension (from-trunk, , 1) exited non-zero on ‘SIP/1-pstn4-000001b4’
7369 [2024-11-19 11:13:31] VERBOSE[30904][C-000000cf] app_stack.c: SIP/1-pstn4-000001b4 Internal Gosub(app-missedcall-hangup,1,1) complete GOSUB_RETVAL=

5

How to configure the fax module so that calls are made via a dedicated trunk?

6

Asterisk log says fax sent ^^

7

have you checked this page
https://sangomakb.atlassian.net/wiki/spaces/PG/pages/33882339/Fax+Pro-Admin+Guide#Inbound-Route-Fax-Settings

8

You need to review the whole log. The call must be answered for a fax to start that does not mean the fax was sent properly.

Here’s the fax in progress of being sent

7311 [2024-11-19 11:12:30] VERBOSE[30891][C-000000d0] pbx.c: Executing [s@send-fax:2] System(“Local/48227312501@special-fax-context-00000005;1”, "/var/lib/asterisk/bin/faxpro_helper.php --faxid=“1732011149909” --stationid=“324612352” --status=“In Progress” --channel=“Local/48227312501@special-fax-context-00000005;1” ") in new stack

Here’s the fax failing for a given reason:

7321 [2024-11-19 11:13:31] VERBOSE[30891][C-000000d0] pbx.c: Executing [h@send-fax:1] System(“Local/48227312501@special-fax-context-00000005;1”, "/var/lib/asterisk/bin/faxpro_helper.php --faxid=“1732011149909” --stationid=“324612352” --status=“Timed out waiting for initial communication” --action=“sent” ") in new stack

Here’s the system handling the hangup from the timeout

7362 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] app_stack.c: Local/48227312501@special-fax-context-00000005;2 Internal Gosub(fax-hangup-handler,48227312501,1(1732011149909)) start

Finally the section you highlighted which is just a section of the hangup handler. Yes it shows the call was answered because it needed to be for the fax to be sent. Yes the fax was sent, however, based on the logs it was sent and the sending failed.

7364 [2024-11-19 11:13:31] VERBOSE[30892][C-000000cf] pbx.c: Executing [48227312501@fax-hangup-handler:2] ExecIf(“Local/48227312501@special-fax-context-00000005;2”, “0?system(/var/lib/asterisk/bin/faxpro_helper.php --faxid=“1732011149909” --action=“sent” --status=“ANSWER”)”) in new stack

9

So what should i check to solve this issue? Fax should be sent through analog/digital gateway.

10

My suggestion is try to see via SNGREP or TCPDUMP dialog. You should be see more details about sent fax and INVITE with T38

If you don’t see any rejection or error at that time check your Analog GW log and settings side.

12

In tcpdump i can see that dommunication is starting, but I dont know what i should expect later
Could You tell how the correct traffic should looks like?

image
image882×27 4.33 KB

Also I can see that this request was sent through wrong trunk (Wrong analog GW). Where can I indicate the correct trunk in the fax module settings?

13

Have you tried to search here your question?

14

Yes, I entered FAX number is outbound route but is still dont work

image
image1036×772 116 KB

15

This doesn’t show us the patterns for the outbound route. We would need to see those.

16

This is outbound patterns of this outbound route:

image
image1762×442 60.8 KB