7130 [2022-06-06 09:32:02] VERBOSE[2562] chan_sip.c: --- (10 headers 0 lines) --- 7131 [2022-06-06 09:32:02] VERBOSE[2562] chan_sip.c: Really destroying SIP dialog '2fbc297e0f7bdab931484d687e95b8a6@192.168.1.200:5160' Method: OPTIONS 7132 [2022-06-06 09:32:14] VERBOSE[2533] netsock2.c: Using SIP RTP Audio TOS bits 184 7133 [2022-06-06 09:32:14] VERBOSE[2533] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field. 7134 [2022-06-06 09:32:14] VERBOSE[2533] netsock2.c: Using SIP RTP Audio CoS mark 5 7135 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [00447586123123@from-internal:1] Macro("PJSIP/3-00000003", "user-callerid,LIMIT,EXTERNAL,") in new stack 7136 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/3-00000003", "TOUCH_MONITOR=1654504334.4") in new stack 7137 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/3-00000003", "CHANCONTEXT=") in new stack 7138 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/3-00000003", "CHANCONTEXT=") in new stack 7139 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/3-00000003", "CHANEXTENCONTEXT=3-00000003") in new stack 7140 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/3-00000003", "CHANEXTEN=3-00000003") in new stack 7141 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:6] Set("PJSIP/3-00000003", "CALLERID(number)=3") in new stack 7142 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/3-00000003", "AMPUSER=3") in new stack 7143 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:8] Set("PJSIP/3-00000003", "HOTDESCKCHAN=3-00000003") in new stack 7144 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/3-00000003", "HOTDESKEXTEN=3") in new stack 7145 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/3-00000003", "HOTDESKCALL=0") in new stack 7146 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("PJSIP/3-00000003", "0?Set(HOTDESKCALL=1)") in new stack 7147 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("PJSIP/3-00000003", "0?Set(CALLERID(name)=)") in new stack 7148 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("PJSIP/3-00000003", "0?report") in new stack 7149 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/3-00000003", "1?Set(REALCALLERIDNUM=3)") in new stack 7150 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:15] Set("PJSIP/3-00000003", "AMPUSER=3") in new stack 7151 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("PJSIP/3-00000003", "0?limit") in new stack 7152 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:17] Set("PJSIP/3-00000003", "AMPUSERCIDNAME=Asif") in new stack 7153 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("PJSIP/3-00000003", "0?Set(__CIDMASQUERADING=TRUE)") in new stack 7154 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("PJSIP/3-00000003", "0?report") in new stack 7155 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:20] Set("PJSIP/3-00000003", "AMPUSERCID=3") in new stack 7156 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:21] Set("PJSIP/3-00000003", "__DIAL_OPTIONS=HhTtr") in new stack 7157 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:22] Set("PJSIP/3-00000003", "CALLERID(all)="Asif" <3>") in new stack 7158 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("PJSIP/3-00000003", "0?Set(CUSDIAL=)") in new stack 7159 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("PJSIP/3-00000003", "0?Set(CALLERID(all)="Asif" <3>)") in new stack 7160 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/3-00000003", "0?limit") in new stack 7161 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("PJSIP/3-00000003", "1?Set(GROUP(concurrency_limit)=3)") in new stack 7162 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:27] ExecIf("PJSIP/3-00000003", "0?Set(CHANNEL(language)=)") in new stack 7163 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:28] NoOp("PJSIP/3-00000003", "Macro Depth is 1") in new stack 7164 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("PJSIP/3-00000003", "1?report2:macroerror") in new stack 7165 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx_builtins.c: Goto (macro-user-callerid,s,30) 7166 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("PJSIP/3-00000003", "1?continue") in new stack 7167 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx_builtins.c: Goto (macro-user-callerid,s,49) 7168 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:49] Set("PJSIP/3-00000003", "CALLERID(number)=3") in new stack 7169 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/3-00000003", "CALLERID(name)=Asif") in new stack 7170 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("PJSIP/3-00000003", "0?cnum") in new stack 7171 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:52] Set("PJSIP/3-00000003", "CDR(cnam)=Asif") in new stack 7172 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/3-00000003", "CDR(cnum)=3") in new stack 7173 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/3-00000003", "CHANNEL(language)=en") in new stack 7174 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [00447586123123@from-internal:2] Gosub("PJSIP/3-00000003", "sub-record-check,s,1(out,00447586123123,dontcare)") in new stack 7175 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/3-00000003", "0?initialized") in new stack 7176 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/3-00000003", "__REC_STATUS=INITIALIZED") in new stack 7177 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/3-00000003", "NOW=1654504334") in new stack 7178 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/3-00000003", "__DAY=06") in new stack 7179 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/3-00000003", "__MONTH=06") in new stack 7180 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/3-00000003", "__YEAR=2022") in new stack 7181 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/3-00000003", "__TIMESTR=20220606-093214") in new stack 7182 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/3-00000003", "__FROMEXTEN=3") in new stack 7183 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/3-00000003", "__MON_FMT=wav") in new stack 7184 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/3-00000003", "Recordings initialized") in new stack 7185 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/3-00000003", "0?Set(ARG3=dontcare)") in new stack 7186 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/3-00000003", "REC_POLICY_MODE_SAVE=") in new stack 7187 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/3-00000003", "0?Set(REC_STATUS=NO)") in new stack 7188 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/3-00000003", "3?checkaction") in new stack 7189 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx_builtins.c: Goto (sub-record-check,s,17) 7190 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/3-00000003", "1?sub-record-check,out,1") in new stack 7191 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx_builtins.c: Goto (sub-record-check,out,1) 7192 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [out@sub-record-check:1] NoOp("PJSIP/3-00000003", "Outbound Recording Check from 3 to 00447586123123") in new stack 7193 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [out@sub-record-check:2] Set("PJSIP/3-00000003", "RECMODE=dontcare") in new stack 7194 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [out@sub-record-check:3] ExecIf("PJSIP/3-00000003", "1?Goto(routewins)") in new stack 7195 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx_builtins.c: Goto (sub-record-check,out,7) 7196 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [out@sub-record-check:7] Gosub("PJSIP/3-00000003", "recordcheck,1(dontcare,out,00447586123123)") in new stack 7197 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/3-00000003", "Starting recording check against dontcare") in new stack 7198 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/3-00000003", "dontcare") in new stack 7199 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx_builtins.c: Goto (sub-record-check,recordcheck,3) 7200 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/3-00000003", "") in new stack 7201 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [out@sub-record-check:8] Return("PJSIP/3-00000003", "") in new stack 7202 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [00447586123123@from-internal:3] ExecIf("PJSIP/3-00000003", "0 ?Set(CDR(accountcode)=)") in new stack 7203 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [00447586123123@from-internal:4] Set("PJSIP/3-00000003", "_ROUTEID=1") in new stack 7204 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [00447586123123@from-internal:5] Set("PJSIP/3-00000003", "_ROUTENAME=SPA3102_Outbound") in new stack 7205 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [00447586123123@from-internal:6] Set("PJSIP/3-00000003", "MOHCLASS=default") in new stack 7206 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [00447586123123@from-internal:7] Set("PJSIP/3-00000003", "_CALLERIDNAMEINTERNAL=Asif") in new stack 7207 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [00447586123123@from-internal:8] Set("PJSIP/3-00000003", "_CALLERIDNUMINTERNAL=3") in new stack 7208 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [00447586123123@from-internal:9] Set("PJSIP/3-00000003", "_EMAILNOTIFICATION=FALSE") in new stack 7209 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [00447586123123@from-internal:10] Set("PJSIP/3-00000003", "_NODEST=") in new stack 7210 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [00447586123123@from-internal:11] Macro("PJSIP/3-00000003", "outisbusy,") in new stack 7211 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-outisbusy:1] Progress("PJSIP/3-00000003", "") in new stack 7212 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("PJSIP/3-00000003", "0?emergency,1") in new stack 7213 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("PJSIP/3-00000003", "0?intracompany,1") in new stack 7214 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-outisbusy:4] Playback("PJSIP/3-00000003", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack 7215 [2022-06-06 09:32:14] VERBOSE[7013][C-00000004] file.c: Playing 'all-circuits-busy-now.ulaw' (language 'en') 7216 [2022-06-06 09:32:16] VERBOSE[7013][C-00000004] file.c: Playing 'please-try-call-later.ulaw' (language 'en') 7217 [2022-06-06 09:32:18] VERBOSE[7013][C-00000004] pbx.c: Executing [h@from-internal:1] Macro("PJSIP/3-00000003", "hangupcall") in new stack 7218 [2022-06-06 09:32:18] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/3-00000003", "1?theend") in new stack 7219 [2022-06-06 09:32:18] VERBOSE[7013][C-00000004] pbx_builtins.c: Goto (macro-hangupcall,s,3) 7220 [2022-06-06 09:32:18] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/3-00000003", "0?Set(CDR(recordingfile)=)") in new stack 7221 [2022-06-06 09:32:18] VERBOSE[7013][C-00000004] pbx.c: Executing [s@macro-hangupcall:4] Hangup("PJSIP/3-00000003", "") in new stack 7222 [2022-06-06 09:32:18] VERBOSE[7013][C-00000004] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'PJSIP/3-00000003' in macro 'hangupcall' 7223 [2022-06-06 09:32:18] VERBOSE[7013][C-00000004] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/3-00000003'