Asterisk Voicemail Logs

sccp_not_working.txt

14638[2022-04-12 07:19:39] VERBOSE[15535] netsock2.c: Using SCCP RTP TOS bits 184
14639[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [*97@from-internal:1] Macro("SCCP/250-00000015", "user-callerid,") in new stack
14640[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:1] Set("SCCP/250-00000015", "TOUCH_MONITOR=1649747979.21") in new stack
14641[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:2] Set("SCCP/250-00000015", "CHANCONTEXT=") in new stack
14642[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:3] Set("SCCP/250-00000015", "CHANCONTEXT=") in new stack
14643[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:4] Set("SCCP/250-00000015", "CHANEXTENCONTEXT=250-00000015") in new stack
14644[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:5] Set("SCCP/250-00000015", "CHANEXTEN=250-00000015") in new stack
14645[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:6] Set("SCCP/250-00000015", "CALLERID(number)=") in new stack
14646[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:7] Set("SCCP/250-00000015", "AMPUSER=") in new stack
14647[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:8] Set("SCCP/250-00000015", "HOTDESCKCHAN=250-00000015") in new stack
14648[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:9] Set("SCCP/250-00000015", "HOTDESKEXTEN=250") in new stack
14649[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:10] Set("SCCP/250-00000015", "HOTDESKCALL=0") in new stack
14650[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("SCCP/250-00000015", "0?Set(HOTDESKCALL=1)") in new stack
14651[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("SCCP/250-00000015", "0?Set(CALLERID(name)=)") in new stack
14652[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("SCCP/250-00000015", "0?report") in new stack
14653[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("SCCP/250-00000015", "1?Set(REALCALLERIDNUM=)") in new stack
14654[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:15] Set("SCCP/250-00000015", "AMPUSER=") in new stack
14655[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("SCCP/250-00000015", "0?limit") in new stack
14656[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:17] Set("SCCP/250-00000015", "AMPUSERCIDNAME=") in new stack
14657[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("SCCP/250-00000015", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
14658[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("SCCP/250-00000015", "1?report") in new stack
14659[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx_builtins.c: Goto (macro-user-callerid,s,28)
14660[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:28] NoOp("SCCP/250-00000015", "Macro Depth is 1") in new stack
14661[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("SCCP/250-00000015", "1?report2:macroerror") in new stack
14662[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx_builtins.c: Goto (macro-user-callerid,s,30)
14663[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("SCCP/250-00000015", "0?continue") in new stack
14664[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("SCCP/250-00000015", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
14665[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:32] Set("SCCP/250-00000015", "__TTL=64") in new stack
14666[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("SCCP/250-00000015", "1?continue") in new stack
14667[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx_builtins.c: Goto (macro-user-callerid,s,49)
14668[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:49] Set("SCCP/250-00000015", "CALLERID(number)=") in new stack
14669[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:50] Set("SCCP/250-00000015", "CALLERID(name)=Test Phone") in new stack
14670[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("SCCP/250-00000015", "0?cnum") in new stack
14671[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:52] Set("SCCP/250-00000015", "CDR(cnam)=Test Phone") in new stack
14672[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:53] Set("SCCP/250-00000015", "CDR(cnum)=") in new stack
14673[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-user-callerid:54] Set("SCCP/250-00000015", "CHANNEL(language)=en") in new stack
14674[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [*97@from-internal:2] Set("SCCP/250-00000015", "CONNECTEDLINE(name-charset,i)=utf8") in new stack
14675[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [*97@from-internal:3] Set("SCCP/250-00000015", "CONNECTEDLINE(name,i)=My Voicemail") in new stack
14676[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [*97@from-internal:4] Set("SCCP/250-00000015", "CONNECTEDLINE(num,i)=") in new stack
14677[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [*97@from-internal:5] Answer("SCCP/250-00000015", "") in new stack
14678[2022-04-12 07:19:39] VERBOSE[32731][C-00000016] pbx.c: Executing [*97@from-internal:6] Wait("SCCP/250-00000015", "1") in new stack
14679[2022-04-12 07:19:40] VERBOSE[32731][C-00000016] pbx.c: Executing [*97@from-internal:7] Macro("SCCP/250-00000015", "get-vmcontext,") in new stack
14680[2022-04-12 07:19:40] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-get-vmcontext:1] Set("SCCP/250-00000015", "VMCONTEXT=") in new stack
14681[2022-04-12 07:19:40] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf("SCCP/250-00000015", "1?200:300") in new stack
14682[2022-04-12 07:19:40] VERBOSE[32731][C-00000016] pbx_builtins.c: Goto (macro-get-vmcontext,s,200)
14683[2022-04-12 07:19:40] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-get-vmcontext:200] Set("SCCP/250-00000015", "VMCONTEXT=default") in new stack
14684[2022-04-12 07:19:40] ERROR[32731][C-00000016] app_voicemail.c: VM_INFO requires an argument (<mailbox>[@<context>],attribute[,folder])
14685[2022-04-12 07:19:40] WARNING[32731][C-00000016] func_logic.c: Syntax IF(<expr>?[<true>][:<false>]) (expr must be non-null, and either <true> or <false> must be non-null)
14686[2022-04-12 07:19:40] WARNING[32731][C-00000016] func_logic.c: In this case, <expr>='', <true>='SUCCESS', and <false>='FAILED'
14687[2022-04-12 07:19:40] VERBOSE[32731][C-00000016] pbx.c: Executing [*97@from-internal:8] Set("SCCP/250-00000015", "VMBOXEXISTSSTATUS=") in new stack
14688[2022-04-12 07:19:40] VERBOSE[32731][C-00000016] pbx.c: Executing [*97@from-internal:9] GotoIf("SCCP/250-00000015", "0?mbexist") in new stack
14689[2022-04-12 07:19:40] VERBOSE[32731][C-00000016] pbx.c: Executing [*97@from-internal:10] VoiceMailMain("SCCP/250-00000015", "") in new stack
14690[2022-04-12 07:19:40] VERBOSE[32731][C-00000016] file.c: <SCCP/250-00000015> Playing 'vm-login.ulaw' (language 'en')
14691[2022-04-12 07:19:42] WARNING[32731][C-00000016] app_voicemail.c: Couldn't read username
14692[2022-04-12 07:19:42] VERBOSE[32731][C-00000016] pbx.c: Executing [h@from-internal:1] Macro("SCCP/250-00000015", "hangupcall") in new stack
14693[2022-04-12 07:19:42] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SCCP/250-00000015", "1?theend") in new stack
14694[2022-04-12 07:19:42] VERBOSE[32731][C-00000016] pbx_builtins.c: Goto (macro-hangupcall,s,3)
14695[2022-04-12 07:19:42] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SCCP/250-00000015", "0?Set(CDR(recordingfile)=)") in new stack
14696[2022-04-12 07:19:42] VERBOSE[32731][C-00000016] pbx.c: Executing [s@macro-hangupcall:4] Hangup("SCCP/250-00000015", "") in new stack
14697[2022-04-12 07:19:42] VERBOSE[32731][C-00000016] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SCCP/250-00000015' in macro 'hangupcall'
14698[2022-04-12 07:19:42] VERBOSE[32731][C-00000016] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SCCP/250-00000015'

sip_working.txt

14561[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:1] Macro("PJSIP/854-00000000", "user-callerid,") in new stack
14562[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/854-00000000", "TOUCH_MONITOR=1649747963.20") in new stack
14563[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/854-00000000", "CHANCONTEXT=") in new stack
14564[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/854-00000000", "CHANCONTEXT=") in new stack
14565[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/854-00000000", "CHANEXTENCONTEXT=854-00000000") in new stack
14566[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/854-00000000", "CHANEXTEN=854-00000000") in new stack
14567[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:6] Set("PJSIP/854-00000000", "CALLERID(number)=854") in new stack
14568[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/854-00000000", "AMPUSER=854") in new stack
14569[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:8] Set("PJSIP/854-00000000", "HOTDESCKCHAN=854-00000000") in new stack
14570[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/854-00000000", "HOTDESKEXTEN=854") in new stack
14571[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/854-00000000", "HOTDESKCALL=0") in new stack
14572[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("PJSIP/854-00000000", "0?Set(HOTDESKCALL=1)") in new stack
14573[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("PJSIP/854-00000000", "0?Set(CALLERID(name)=)") in new stack
14574[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("PJSIP/854-00000000", "0?report") in new stack
14575[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/854-00000000", "1?Set(REALCALLERIDNUM=854)") in new stack
14576[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:15] Set("PJSIP/854-00000000", "AMPUSER=854") in new stack
14577[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("PJSIP/854-00000000", "0?limit") in new stack
14578[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:17] Set("PJSIP/854-00000000", "AMPUSERCIDNAME=Test SIP") in new stack
14579[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("PJSIP/854-00000000", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
14580[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("PJSIP/854-00000000", "0?report") in new stack
14581[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:20] Set("PJSIP/854-00000000", "AMPUSERCID=854") in new stack
14582[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:21] Set("PJSIP/854-00000000", "__DIAL_OPTIONS=HhTtr") in new stack
14583[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:22] Set("PJSIP/854-00000000", "CALLERID(all)="Test SIP" <854>") in new stack
14584[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("PJSIP/854-00000000", "0?Set(CUSDIAL=)") in new stack
14585[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("PJSIP/854-00000000", "0?Set(CALLERID(all)="Test SIP" <854>)") in new stack
14586[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/854-00000000", "0?limit") in new stack
14587[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("PJSIP/854-00000000", "0?Set(GROUP(concurrency_limit)=854)") in new stack
14588[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:27] ExecIf("PJSIP/854-00000000", "0?Set(CHANNEL(language)=)") in new stack
14589[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:28] NoOp("PJSIP/854-00000000", "Macro Depth is 1") in new stack
14590[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("PJSIP/854-00000000", "1?report2:macroerror") in new stack
14591[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx_builtins.c: Goto (macro-user-callerid,s,30)
14592[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("PJSIP/854-00000000", "0?continue") in new stack
14593[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("PJSIP/854-00000000", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
14594[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:32] Set("PJSIP/854-00000000", "__TTL=64") in new stack
14595[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("PJSIP/854-00000000", "1?continue") in new stack
14596[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx_builtins.c: Goto (macro-user-callerid,s,49)
14597[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:49] Set("PJSIP/854-00000000", "CALLERID(number)=854") in new stack
14598[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/854-00000000", "CALLERID(name)=Test SIP") in new stack
14599[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("PJSIP/854-00000000", "0?cnum") in new stack
14600[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:52] Set("PJSIP/854-00000000", "CDR(cnam)=Test SIP") in new stack
14601[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/854-00000000", "CDR(cnum)=854") in new stack
14602[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/854-00000000", "CHANNEL(language)=en") in new stack
14603[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:2] Set("PJSIP/854-00000000", "CONNECTEDLINE(name-charset,i)=utf8") in new stack
14604[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:3] Set("PJSIP/854-00000000", "CONNECTEDLINE(name,i)=My Voicemail") in new stack
14605[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:4] Set("PJSIP/854-00000000", "CONNECTEDLINE(num,i)=854") in new stack
14606[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:5] Answer("PJSIP/854-00000000", "") in new stack
14607[2022-04-12 07:19:23] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:6] Wait("PJSIP/854-00000000", "1") in new stack
14608[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:7] Macro("PJSIP/854-00000000", "get-vmcontext,854") in new stack
14609[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-get-vmcontext:1] Set("PJSIP/854-00000000", "VMCONTEXT=default") in new stack
14610[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf("PJSIP/854-00000000", "0?200:300") in new stack
14611[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
14612[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp("PJSIP/854-00000000", "") in new stack
14613[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:8] Set("PJSIP/854-00000000", "VMBOXEXISTSSTATUS=SUCCESS") in new stack
14614[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:9] GotoIf("PJSIP/854-00000000", "1?mbexist") in new stack
14615[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx_builtins.c: Goto (from-internal,*97,109)
14616[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:109] GotoIf("PJSIP/854-00000000", "1?novmpw:vmpw") in new stack
14617[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx_builtins.c: Goto (from-internal,*97,110)
14618[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:110] NoOp("PJSIP/854-00000000", "Verifying channel PJSIP/854-00000000 is actually 854") in new stack
14619[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:111] Set("PJSIP/854-00000000", "DEVICES=854") in new stack
14620[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:112] ExecIf("PJSIP/854-00000000", "0?Set(DEVICES=854)") in new stack
14621[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:113] ExecIf("PJSIP/854-00000000", "0?Set(DEVICES=54)") in new stack
14622[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:114] While("PJSIP/854-00000000", "1") in new stack
14623[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:115] GotoIf("PJSIP/854-00000000", "1?vmpwskip") in new stack
14624[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx_builtins.c: Goto (from-internal,*97,120)
14625[2022-04-12 07:19:24] VERBOSE[32686][C-00000015] pbx.c: Executing [*97@from-internal:120] VoiceMailMain("PJSIP/854-00000000", "854@default,s") in new stack
14626[2022-04-12 07:19:25] VERBOSE[32686][C-00000015] file.c: <PJSIP/854-00000000> Playing 'vm-youhave.ulaw' (language 'en')
14627[2022-04-12 07:19:26] VERBOSE[32686][C-00000015] file.c: <PJSIP/854-00000000> Playing 'vm-no.ulaw' (language 'en')
14628[2022-04-12 07:19:27] VERBOSE[32686][C-00000015] file.c: <PJSIP/854-00000000> Playing 'vm-messages.ulaw' (language 'en')
14629[2022-04-12 07:19:28] VERBOSE[32686][C-00000015] file.c: <PJSIP/854-00000000> Playing 'vm-opts.ulaw' (language 'en')
14630[2022-04-12 07:19:34] VERBOSE[32686][C-00000015] pbx.c: Spawn extension (from-internal, *97, 120) exited non-zero on 'PJSIP/854-00000000'
14631[2022-04-12 07:19:34] VERBOSE[32686][C-00000015] pbx.c: Executing [h@from-internal:1] Macro("PJSIP/854-00000000", "hangupcall") in new stack
14632[2022-04-12 07:19:34] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/854-00000000", "1?theend") in new stack
14633[2022-04-12 07:19:34] VERBOSE[32686][C-00000015] pbx_builtins.c: Goto (macro-hangupcall,s,3)
14634[2022-04-12 07:19:34] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/854-00000000", "0?Set(CDR(recordingfile)=)") in new stack
14635[2022-04-12 07:19:34] VERBOSE[32686][C-00000015] pbx.c: Executing [s@macro-hangupcall:4] Hangup("PJSIP/854-00000000", "") in new stack
14636[2022-04-12 07:19:34] VERBOSE[32686][C-00000015] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'PJSIP/854-00000000' in macro 'hangupcall'
14637[2022-04-12 07:19:34] VERBOSE[32686][C-00000015] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/854-00000000'