Maximum Voicemail length of 15 seconds for external calls

brhunt · February 25, 2023, 9:36pm

Hello,

We cut over to a Raspberry PI version of Asterisk with FreePbx 15.0.29. Previously we were running an older Asterisk and Freepbix on Centos.

For calls coming in from our SIP provider (DIDs For Sale), we see voice messages being cut off at 15 seconds. This does not occur on internal (ext to ext) calls.

Our SIP provider is the same with our old config, and our trunk sip settings are also set up the same.

We do have ‘transmit_silence_during_record = yes’ set.

What else should I try to cure this issue?

Thanks.

Bryan Hunt

brhunt · February 25, 2023, 9:48pm

Also, max message length is 300. Tried changes to silence threshold and max message silence, but neither had any effect.

dicko · February 26, 2023, 12:00am

Logs are always needed.

mahbell · February 26, 2023, 5:33am

https://wiki.freepbx.org/plugins/servlet/mobile?contentId=30245283#content/view/30245283

brhunt · March 4, 2023, 8:08pm

Dicko and Mahbell,

Sorry, was out of town for a bit.

Here is log for a 15 second outside to inside call:

6287 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-sip-external:1] NoOp(“PJSIP/anonymous-0000002a”, “Received incoming SIP connection from unknown peer to 12223334444”) in new stack
6288 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-sip-external:2] Set(“PJSIP/anonymous-0000002a”, “DID=12223334444”) in new stack
6289 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-sip-external:3] Goto(“PJSIP/anonymous-0000002a”, “s,1”) in new stack
6290 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (from-sip-external,s,1)
6291 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@from-sip-external:1] GotoIf(“PJSIP/anonymous-0000002a”, “1?setlanguage:checkanon”) in new stack
6292 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (from-sip-external,s,2)
6293 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@from-sip-external:2] Set(“PJSIP/anonymous-0000002a”, “CHANNEL(language)=en”) in new stack
6294 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@from-sip-external:3] GotoIf(“PJSIP/anonymous-0000002a”, “0?noanonymous”) in new stack
6295 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@from-sip-external:4] Goto(“PJSIP/anonymous-0000002a”, “from-trunk,12223334444,1”) in new stack
6296 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (from-trunk,12223334444,1)
6297 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-trunk:1] Set(“PJSIP/anonymous-0000002a”, “__DIRECTION=INBOUND”) in new stack
6298 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-trunk:2] Gosub(“PJSIP/anonymous-0000002a”, “sub-record-check,s,1(in,12223334444,dontcare)”) in new stack
6299 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@sub-record-check:1] GotoIf(“PJSIP/anonymous-0000002a”, “0?initialized”) in new stack
6300 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@sub-record-check:2] Set(“PJSIP/anonymous-0000002a”, “__REC_STATUS=INITIALIZED”) in new stack
6301 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@sub-record-check:3] Set(“PJSIP/anonymous-0000002a”, “NOW=1677960139”) in new stack
6302 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@sub-record-check:4] Set(“PJSIP/anonymous-0000002a”, “__DAY=04”) in new stack
6303 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@sub-record-check:5] Set(“PJSIP/anonymous-0000002a”, “__MONTH=03”) in new stack
6304 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@sub-record-check:6] Set(“PJSIP/anonymous-0000002a”, “__YEAR=2023”) in new stack
6305 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@sub-record-check:7] Set(“PJSIP/anonymous-0000002a”, “__TIMESTR=20230304-150219”) in new stack
6306 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@sub-record-check:8] Set(“PJSIP/anonymous-0000002a”, “__FROMEXTEN=unknown”) in new stack
6307 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@sub-record-check:9] Set(“PJSIP/anonymous-0000002a”, “__MON_FMT=wav”) in new stack
6308 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@sub-record-check:10] NoOp(“PJSIP/anonymous-0000002a”, “Recordings initialized”) in new stack
6309 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@sub-record-check:11] ExecIf(“PJSIP/anonymous-0000002a”, “0?Set(ARG3=dontcare)”) in new stack
6310 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@sub-record-check:12] Set(“PJSIP/anonymous-0000002a”, “REC_POLICY_MODE_SAVE=”) in new stack
6311 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@sub-record-check:13] ExecIf(“PJSIP/anonymous-0000002a”, “0?Set(REC_STATUS=NO)”) in new stack
6312 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@sub-record-check:14] GotoIf(“PJSIP/anonymous-0000002a”, “2?checkaction”) in new stack
6313 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (sub-record-check,s,17)
6314 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@sub-record-check:17] GotoIf(“PJSIP/anonymous-0000002a”, “1?sub-record-check,in,1”) in new stack
6315 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (sub-record-check,in,1)
6316 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [in@sub-record-check:1] NoOp(“PJSIP/anonymous-0000002a”, “Inbound Recording Check to 12223334444”) in new stack
6317 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [in@sub-record-check:2] Set(“PJSIP/anonymous-0000002a”, “FROMEXTEN=unknown”) in new stack
6318 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [in@sub-record-check:3] ExecIf(“PJSIP/anonymous-0000002a”, “11?Set(FROMEXTEN=15556667777)”) in new stack
6319 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [in@sub-record-check:4] Gosub(“PJSIP/anonymous-0000002a”, “recordcheck,1(dontcare,in,12223334444)”) in new stack
6320 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/anonymous-0000002a”, “Starting recording check against dontcare”) in new stack
6321 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/anonymous-0000002a”, “dontcare”) in new stack
6322 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
6323 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“PJSIP/anonymous-0000002a”, “”) in new stack
6324 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [in@sub-record-check:5] Return(“PJSIP/anonymous-0000002a”, “”) in new stack
6325 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-trunk:3] Set(“PJSIP/anonymous-0000002a”, “CHANNEL(tonezone)=us”) in new stack
6326 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-trunk:4] Set(“PJSIP/anonymous-0000002a”, “__FROM_DID=12223334444”) in new stack
6327 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-trunk:5] Set(“PJSIP/anonymous-0000002a”, “CDR(did)=12223334444”) in new stack
6328 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-trunk:6] ExecIf(“PJSIP/anonymous-0000002a”, “1 ?Set(CALLERID(name)=15556667777)”) in new stack
6329 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-trunk:7] Set(“PJSIP/anonymous-0000002a”, “__MOHCLASS=”) in new stack
6330 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-trunk:8] Set(“PJSIP/anonymous-0000002a”, “__REVERSAL_REJECT=FALSE”) in new stack
6331 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-trunk:9] GotoIf(“PJSIP/anonymous-0000002a”, “1?post-reverse-charge”) in new stack
6332 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (from-trunk,12223334444,11)
6333 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-trunk:11] NoOp(“PJSIP/anonymous-0000002a”, “”) in new stack
6334 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-trunk:12] Set(“PJSIP/anonymous-0000002a”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
6335 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-trunk:13] Set(“PJSIP/anonymous-0000002a”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
6336 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-trunk:14] Set(“PJSIP/anonymous-0000002a”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
6337 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-trunk:15] Set(“PJSIP/anonymous-0000002a”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
6338 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-trunk:16] NoOp(“PJSIP/anonymous-0000002a”, “CallerID Entry Point”) in new stack
6339 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [12223334444@from-trunk:17] Goto(“PJSIP/anonymous-0000002a”, “ext-local,vmu101,1”) in new stack
6340 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (ext-local,vmu101,1)
6341 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [vmu101@ext-local:1] Macro(“PJSIP/anonymous-0000002a”, “vm,101,NOANSWER,”) in new stack
6342 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-vm:1] Macro(“PJSIP/anonymous-0000002a”, “user-callerid,SKIPTTL”) in new stack
6343 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:1] Set(“PJSIP/anonymous-0000002a”, “TOUCH_MONITOR=1677960139.51”) in new stack
6344 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:2] Set(“PJSIP/anonymous-0000002a”, “CHANCONTEXT=”) in new stack
6345 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:3] Set(“PJSIP/anonymous-0000002a”, “CHANCONTEXT=”) in new stack
6346 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:4] Set(“PJSIP/anonymous-0000002a”, “CHANEXTENCONTEXT=anonymous-0000002a”) in new stack
6347 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:5] Set(“PJSIP/anonymous-0000002a”, “CHANEXTEN=anonymous-0000002a”) in new stack
6348 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:6] Set(“PJSIP/anonymous-0000002a”, “CALLERID(number)=15556667777”) in new stack
6349 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:7] Set(“PJSIP/anonymous-0000002a”, “AMPUSER=15556667777”) in new stack
6350 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:8] Set(“PJSIP/anonymous-0000002a”, “HOTDESCKCHAN=anonymous-0000002a”) in new stack
6351 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:9] Set(“PJSIP/anonymous-0000002a”, “HOTDESKEXTEN=anonymous”) in new stack
6352 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:10] Set(“PJSIP/anonymous-0000002a”, “HOTDESKCALL=0”) in new stack
6353 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:11] ExecIf(“PJSIP/anonymous-0000002a”, “0?Set(HOTDESKCALL=1)”) in new stack
6354 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:12] ExecIf(“PJSIP/anonymous-0000002a”, “0?Set(CALLERID(name)=)”) in new stack
6355 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:13] GotoIf(“PJSIP/anonymous-0000002a”, “0?report”) in new stack
6356 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“PJSIP/anonymous-0000002a”, “1?Set(REALCALLERIDNUM=15556667777)”) in new stack
6357 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:15] Set(“PJSIP/anonymous-0000002a”, “AMPUSER=”) in new stack
6358 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:16] GotoIf(“PJSIP/anonymous-0000002a”, “0?limit”) in new stack
6359 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:17] Set(“PJSIP/anonymous-0000002a”, “AMPUSERCIDNAME=”) in new stack
6360 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:18] ExecIf(“PJSIP/anonymous-0000002a”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
6361 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:19] GotoIf(“PJSIP/anonymous-0000002a”, “1?report”) in new stack
6362 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (macro-user-callerid,s,27)
6363 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:27] NoOp(“PJSIP/anonymous-0000002a”, “Macro Depth is 2”) in new stack
6364 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:28] GotoIf(“PJSIP/anonymous-0000002a”, “1?report2:macroerror”) in new stack
6365 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (macro-user-callerid,s,29)
6366 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:29] GotoIf(“PJSIP/anonymous-0000002a”, “1?continue”) in new stack
6367 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (macro-user-callerid,s,47)
6368 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:47] Set(“PJSIP/anonymous-0000002a”, “CALLERID(number)=15556667777”) in new stack
6369 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:48] Set(“PJSIP/anonymous-0000002a”, “CALLERID(name)=15556667777”) in new stack
6370 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:49] GotoIf(“PJSIP/anonymous-0000002a”, “0?cnum”) in new stack
6371 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:50] Set(“PJSIP/anonymous-0000002a”, “CDR(cnam)=15556667777”) in new stack
6372 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:51] Set(“PJSIP/anonymous-0000002a”, “CDR(cnum)=15556667777”) in new stack
6373 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-user-callerid:52] Set(“PJSIP/anonymous-0000002a”, “CHANNEL(language)=en”) in new stack
6374 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-vm:2] Set(“PJSIP/anonymous-0000002a”, “VMGAIN=”) in new stack
6375 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-vm:3] Macro(“PJSIP/anonymous-0000002a”, “blkvm-check,”) in new stack
6376 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-blkvm-check:1] Set(“PJSIP/anonymous-0000002a”, “GOSUB_RETVAL=”) in new stack
6377 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-blkvm-check:2] ExecIf(“PJSIP/anonymous-0000002a”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
6378 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-blkvm-check:3] MacroExit(“PJSIP/anonymous-0000002a”, “”) in new stack
6379 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-vm:4] GotoIf(“PJSIP/anonymous-0000002a”, “1?vmx,1”) in new stack
6380 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (macro-vm,vmx,1)
6381 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [vmx@macro-vm:1] Set(“PJSIP/anonymous-0000002a”, “MEXTEN=101”) in new stack
6382 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [vmx@macro-vm:2] Set(“PJSIP/anonymous-0000002a”, “MMODE=NOANSWER”) in new stack
6383 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [vmx@macro-vm:3] Set(“PJSIP/anonymous-0000002a”, “RETVM=”) in new stack
6384 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [vmx@macro-vm:4] Set(“PJSIP/anonymous-0000002a”, “MODE=unavail”) in new stack
6385 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [vmx@macro-vm:5] Macro(“PJSIP/anonymous-0000002a”, “get-vmcontext,101”) in new stack
6386 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-get-vmcontext:1] Set(“PJSIP/anonymous-0000002a”, “VMCONTEXT=default”) in new stack
6387 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf(“PJSIP/anonymous-0000002a”, “0?200:300”) in new stack
6388 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
6389 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp(“PJSIP/anonymous-0000002a”, “”) in new stack
6390 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [vmx@macro-vm:6] Set(“PJSIP/anonymous-0000002a”, “MODE=unavail”) in new stack
6391 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [vmx@macro-vm:7] NoOp(“PJSIP/anonymous-0000002a”, “MODE IS: unavail”) in new stack
6392 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [vmx@macro-vm:8] GotoIf(“PJSIP/anonymous-0000002a”, “1?chknomsg”) in new stack
6393 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (macro-vm,vmx,10)
6394 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [vmx@macro-vm:10] GotoIf(“PJSIP/anonymous-0000002a”, “0?s-NOANSWER,1”) in new stack
6395 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [vmx@macro-vm:11] GotoIf(“PJSIP/anonymous-0000002a”, “1?notdirect”) in new stack
6396 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (macro-vm,vmx,13)
6397 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [vmx@macro-vm:13] NoOp(“PJSIP/anonymous-0000002a”, "Checking if ext 101 is enabled: ") in new stack
6398 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [vmx@macro-vm:14] GotoIf(“PJSIP/anonymous-0000002a”, “1?s-NOANSWER,1”) in new stack
6399 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (macro-vm,s-NOANSWER,1)
6400 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s-NOANSWER@macro-vm:1] Macro(“PJSIP/anonymous-0000002a”, “get-vmcontext,101”) in new stack
6401 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-get-vmcontext:1] Set(“PJSIP/anonymous-0000002a”, “VMCONTEXT=default”) in new stack
6402 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf(“PJSIP/anonymous-0000002a”, “0?200:300”) in new stack
6403 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
6404 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp(“PJSIP/anonymous-0000002a”, “”) in new stack
6405 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [s-NOANSWER@macro-vm:2] VoiceMail(“PJSIP/anonymous-0000002a”, “101@default,u”) in new stack
6406 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] file.c: <PJSIP/anonymous-0000002a> Playing ‘/var/spool/asterisk/voicemail/default/101/unavail.slin’ (language ‘en’)
6407 [2023-03-04 15:02:30] VERBOSE[21430][C-0000002b] file.c: <PJSIP/anonymous-0000002a> Playing ‘vm-intro.ulaw’ (language ‘en’)
6408 [2023-03-04 15:02:36] VERBOSE[21430][C-0000002b] file.c: <PJSIP/anonymous-0000002a> Playing ‘beep.ulaw’ (language ‘en’)
6409 [2023-03-04 15:02:36] VERBOSE[21430][C-0000002b] app_voicemail.c: Recording the message
6410 [2023-03-04 15:02:36] VERBOSE[21430][C-0000002b] app.c: x=0, open writing: /var/spool/asterisk/voicemail/default/101/tmp/YmPhrg format: wav, 0x74143568
6411 [2023-03-04 15:02:51] VERBOSE[21430][C-0000002b] app.c: User hung up
6412 [2023-03-04 15:02:51] VERBOSE[21430][C-0000002b] app_voicemail.c: Recording was 5 seconds long but needs to be at least 8 - abandoning
6413 [2023-03-04 15:02:51] VERBOSE[21430][C-0000002b] app_macro.c: Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on ‘PJSIP/anonymous-0000002a’ in macro ‘vm’
6414 [2023-03-04 15:02:51] VERBOSE[21430][C-0000002b] pbx.c: Spawn extension (ext-local, vmu101, 1) exited non-zero on ‘PJSIP/anonymous-0000002a’
6415 [2023-03-04 15:02:51] VERBOSE[21430][C-0000002b] pbx.c: Executing [h@ext-local:1] Macro(“PJSIP/anonymous-0000002a”, “hangupcall,”) in new stack
6416 [2023-03-04 15:02:51] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/anonymous-0000002a”, “1?theend”) in new stack
6417 [2023-03-04 15:02:51] VERBOSE[21430][C-0000002b] pbx_builtins.c: Goto (macro-hangupcall,s,3)
6418 [2023-03-04 15:02:51] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/anonymous-0000002a”, “0?Set(CDR(recordingfile)=)”) in new stack
6419 [2023-03-04 15:02:51] VERBOSE[21430][C-0000002b] pbx.c: Executing [s@macro-hangupcall:4] Hangup(“PJSIP/anonymous-0000002a”, “”) in new stack
6420 [2023-03-04 15:02:51] VERBOSE[21430][C-0000002b] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘PJSIP/anonymous-0000002a’ in macro ‘hangupcall’
6421 [2023-03-04 15:02:51] VERBOSE[21430][C-0000002b] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘PJSIP/anonymous-0000002a’

brhunt · March 4, 2023, 8:17pm

At 2023-03-04 15:02:51 it says that User hung up, but not true. Not sure why it thinks this.

Thanks.

Bryan Hunt

dicko · March 4, 2023, 9:30pm

Lets start with

6412 [2023-03-04 15:02:51] VERBOSE[21430][C-0000002b] app_voicemail.c: Recording was 5 seconds long but needs to be at least 8 - abandoning

brhunt · March 4, 2023, 9:44pm

K, on the outside call once the recording started I began counting aloud: 1, 2, 3, 4… up to the 15 second point where it hung up. That one looked like it was truncated, but on most calls I can play the full message back up up to 15 seconds when the call is cut off.

mahbell · March 4, 2023, 10:32pm

Do outside calls that go to a live person last longer than 15 seconds?

brhunt · March 4, 2023, 10:52pm

Mahbell, live calls cut off after 30 seconds. This is looking more like a SIP trunk issue? I have used these trunks for years, but I have just upgraded/updated my asterisk system. I have a ticket open with my sip trunk provider, but have not spoken with them yet.

mahbell · March 7, 2023, 2:35am

Just Google these forums, and you’ll see so many of these posts. Usual culprit is the network NAT.

brhunt · March 11, 2023, 4:48pm

mahbell,

NAT was the issue. I had changed the IP address after testing and did not reset the outside address.

I have seen NAT affect RTP traffic before, but did not know that it would affect SIP signalling.

Very nice catch there, thanks for the assist.

Bryan Hunt

system · March 18, 2023, 4:48pm

This topic was automatically closed 7 days after the last reply. New replies are no longer allowed.