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 [[email protected]: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 [[email protected]:2] Set(“PJSIP/anonymous-0000002a”, “DID=12223334444”) in new stack
6289 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:3] GotoIf(“PJSIP/anonymous-0000002a”, “0?noanonymous”) in new stack
6295 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]:1] Set(“PJSIP/anonymous-0000002a”, “__DIRECTION=INBOUND”) in new stack
6298 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]:1] GotoIf(“PJSIP/anonymous-0000002a”, “0?initialized”) in new stack
6300 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]:3] Set(“PJSIP/anonymous-0000002a”, “NOW=1677960139”) in new stack
6302 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]:4] Set(“PJSIP/anonymous-0000002a”, “__DAY=04”) in new stack
6303 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]:5] Set(“PJSIP/anonymous-0000002a”, “__MONTH=03”) in new stack
6304 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]:6] Set(“PJSIP/anonymous-0000002a”, “__YEAR=2023”) in new stack
6305 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]:8] Set(“PJSIP/anonymous-0000002a”, “__FROMEXTEN=unknown”) in new stack
6307 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]:10] NoOp(“PJSIP/anonymous-0000002a”, “Recordings initialized”) in new stack
6309 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set(“PJSIP/anonymous-0000002a”, “FROMEXTEN=unknown”) in new stack
6318 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:3] Return(“PJSIP/anonymous-0000002a”, “”) in new stack
6324 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]:5] Return(“PJSIP/anonymous-0000002a”, “”) in new stack
6325 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:7] Set(“PJSIP/anonymous-0000002a”, “__MOHCLASS=”) in new stack
6330 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:11] NoOp(“PJSIP/anonymous-0000002a”, “”) in new stack
6334 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set(“PJSIP/anonymous-0000002a”, “CHANCONTEXT=”) in new stack
6345 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]:3] Set(“PJSIP/anonymous-0000002a”, “CHANCONTEXT=”) in new stack
6346 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:7] Set(“PJSIP/anonymous-0000002a”, “AMPUSER=15556667777”) in new stack
6350 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]:9] Set(“PJSIP/anonymous-0000002a”, “HOTDESKEXTEN=anonymous”) in new stack
6352 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]:10] Set(“PJSIP/anonymous-0000002a”, “HOTDESKCALL=0”) in new stack
6353 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:13] GotoIf(“PJSIP/anonymous-0000002a”, “0?report”) in new stack
6356 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]:15] Set(“PJSIP/anonymous-0000002a”, “AMPUSER=”) in new stack
6358 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]:16] GotoIf(“PJSIP/anonymous-0000002a”, “0?limit”) in new stack
6359 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]:17] Set(“PJSIP/anonymous-0000002a”, “AMPUSERCIDNAME=”) in new stack
6360 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:49] GotoIf(“PJSIP/anonymous-0000002a”, “0?cnum”) in new stack
6371 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set(“PJSIP/anonymous-0000002a”, “VMGAIN=”) in new stack
6375 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]:3] Macro(“PJSIP/anonymous-0000002a”, “blkvm-check,”) in new stack
6376 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]:1] Set(“PJSIP/anonymous-0000002a”, “GOSUB_RETVAL=”) in new stack
6377 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]:3] MacroExit(“PJSIP/anonymous-0000002a”, “”) in new stack
6379 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]:1] Set(“PJSIP/anonymous-0000002a”, “MEXTEN=101”) in new stack
6382 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]:2] Set(“PJSIP/anonymous-0000002a”, “MMODE=NOANSWER”) in new stack
6383 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]:3] Set(“PJSIP/anonymous-0000002a”, “RETVM=”) in new stack
6384 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]:4] Set(“PJSIP/anonymous-0000002a”, “MODE=unavail”) in new stack
6385 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]:1] Set(“PJSIP/anonymous-0000002a”, “VMCONTEXT=default”) in new stack
6387 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]:300] NoOp(“PJSIP/anonymous-0000002a”, “”) in new stack
6390 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]:6] Set(“PJSIP/anonymous-0000002a”, “MODE=unavail”) in new stack
6391 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:1] Set(“PJSIP/anonymous-0000002a”, “VMCONTEXT=default”) in new stack
6402 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]:300] NoOp(“PJSIP/anonymous-0000002a”, “”) in new stack
6405 [2023-03-04 15:02:19] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]:2] VoiceMail(“PJSIP/anonymous-0000002a”, “[email protected],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 [[email protected]:1] Macro(“PJSIP/anonymous-0000002a”, “hangupcall,”) in new stack
6416 [2023-03-04 15:02:51] VERBOSE[21430][C-0000002b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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.