Conference Room Getting Stuck

FreePBX Endpoints
1

I have had a client inform me that the Conference room gets stuck every time they use it (which is not very often). I have noticed that I can kill the channel, but this is not a proper solution as im sure you all agree.

I looked at the channels, and noticed this time, that I could not clear it out at all. I found that the outbound trunk was stuck on that conference channel, and I had to kill this to clear it. I found somewhere before that adding the below lines to sip_general_custom.conf would help with timeouts, but that doesn’t seem to be doing the trick.

So I am reaching out to all of you to see if anyone has any other input on this? This is the latest version of everything (FreePBX, asterisk, modules, etc).

rtptimeout=120
rtpholdtimeout=600

2

There are at least four versions of Asterisk that are all “the latest” including the unsupported “latest” version of Asterisk.

We’re going to need a lot more on this. If the client knows when the conference started and ended, I’d recommend going back through the /var/log/asterisk/full log and seeing what is happening there.

This is a very uncommon problem (you’re the first I’ve ever heard of having it) so this might be a networking/configuration edge-case thing that is going to need some special hand-holding. Any additional information (including phones in use in the call, how it’s configured, how it operates in the larger PSTN, what country you’re in, etc.) would probably be a lot of help.

Honestly, at this point, I can’t think of anything else that you can even try. We’re going to need more clues to help you.

3

I am on Asterisk 16.6.2, using the standard modules and nothing on edge at all.

I tried looking into it, but he only had the start time, and said the conference went on for hours and he wasnt sure what time he got off of it. I just tried calling several times myself and it never stuck on me. I can possibly post logs if need be, but I dont know how long it was going on and dont want to include logs for all other normal calls in between it either.

I can tell you we are using Mitel 6869i for every single person, and the extensions are all chan_sip with the PJSIP driver disabled completely. We just have a BLF transfer set up to 2 conference rooms they can use at any given time, which are 2001 and 2002.

I will try to have them write down the start and stop times of conferences so next time this happens, I can looks at the logs more precisely.

4

So, it just happened again, and I found the start and stop of the conference in the logs. At the end, this is what I see. The conference number is 2001 that is having the issue.

START
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [2001@from-internal:29] Gosub(“SIP/119-00008c40”, “sub-conference-options,s,1(2001,USER)”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:1] NoOp(“SIP/119-00008c40”, “Setting options for Conference 2001”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:2] ExecIf(“SIP/119-00008c40”, “1?Set(CONFBRIDGE(bridge,language)=en):Set(CONFBRIDGE(bridge,language)=)”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:3] Goto(“SIP/119-00008c40”, “USER”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx_builtins.c: Goto (sub-conference-options,s,4)
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:4] ExecIf(“SIP/119-00008c40”, “0?Set(CONFBRIDGE(user,wait_marked)=yes)”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:5] ExecIf(“SIP/119-00008c40”, “0?Set(CONFBRIDGE(user,end_marked)=yes)”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:6] ExecIf(“SIP/119-00008c40”, “0?Set(MENU_PROFILE=user_menu)”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:7] ExecIf(“SIP/119-00008c40”, “0?Set(CONFBRIDGE(user,startmuted)=yes)”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:8] Goto(“SIP/119-00008c40”, “RETURN”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx_builtins.c: Goto (sub-conference-options,s,13)
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:13] NoOp(“SIP/119-00008c40”, “Setting Additional Options:”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:14] ExecIf(“SIP/119-00008c40”, “0?Set(CONFBRIDGE(user,quiet)=yes)”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:15] ExecIf(“SIP/119-00008c40”, “0?Set(CONFBRIDGE(user,announce_user_count)=yes)”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:16] ExecIf(“SIP/119-00008c40”, “0?Set(CONFBRIDGE(user,announce_join_leave)=yes)”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:17] ExecIf(“SIP/119-00008c40”, “0?Set(CONFBRIDGE(user,dsp_drop_silence)=yes)”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:18] ExecIf(“SIP/119-00008c40”, “0?Set(CONFBRIDGE(user,talk_detection_events)=yes)”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:19] ExecIf(“SIP/119-00008c40”, “0?Set(CONFBRIDGE(user,music_on_hold_when_empty)=yes)”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:20] ExecIf(“SIP/119-00008c40”, “0?Set(CONFBRIDGE(user,timeout)=)”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [s@sub-conference-options:21] Return(“SIP/119-00008c40”, “”) in new stack
[2020-03-25 12:03:38] VERBOSE[13415][C-00001ba6] pbx.c: Executing [2001@from-internal:30] Gosub(“SIP/119-00008c40”, “sub-record-check,s,1(conf,2001,never)”) in new stack

END
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] pbx.c: Executing [conf@sub-record-check:1] NoOp(“SIP/115-00008d0d”, “Conference Recording Check 115 to 2001”) in new stack
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] pbx.c: Executing [conf@sub-record-check:2] Gosub(“SIP/115-00008d0d”, “recconf,1(2001,2001,never)”) in new stack
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] pbx.c: Executing [recconf@sub-record-check:1] NoOp(“SIP/115-00008d0d”, “Setting up recording: 2001, 2001, never”) in new stack
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] pbx.c: Executing [recconf@sub-record-check:2] Set(“SIP/115-00008d0d”, “__CALLFILENAME=2001-2001-never-20200325-120035-1585155634.36174”) in new stack
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] pbx.c: Executing [recconf@sub-record-check:3] ExecIf(“SIP/115-00008d0d”, “0?Set(DB(RECCONF/2001)=2001-2001-never-20200325-120035-1585155634.36174)”) in new stack
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] pbx.c: Executing [recconf@sub-record-check:4] Set(“SIP/115-00008d0d”, “CONFBRIDGE(bridge,record_file)=2020/03/25/2001-2001-never-20200325-120035-1585155634.36174.wav”) in new stack
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] pbx.c: Executing [recconf@sub-record-check:5] ExecIf(“SIP/115-00008d0d”, “1?Return()”) in new stack
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] pbx.c: Executing [conf@sub-record-check:3] Return(“SIP/115-00008d0d”, “”) in new stack
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] pbx.c: Executing [2001@from-internal:31] ExecIf(“SIP/115-00008d0d”, “0?Playback()”) in new stack
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] pbx.c: Executing [2001@from-internal:32] Goto(“SIP/115-00008d0d”, “STARTMEETME,1”) in new stack
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] pbx_builtins.c: Goto (from-internal,STARTMEETME,1)
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] pbx.c: Executing [STARTMEETME@from-internal:1] ExecIf(“SIP/115-00008d0d”, “0?Set(CONFBRIDGE(user,music_on_hold_class)=)”) in new stack
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] pbx.c: Executing [STARTMEETME@from-internal:2] ExecIf(“SIP/115-00008d0d”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] pbx.c: Executing [STARTMEETME@from-internal:3] Set(“SIP/115-00008d0d”, “GROUP(meetme)=2001”) in new stack
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] pbx.c: Executing [STARTMEETME@from-internal:4] GotoIf(“SIP/115-00008d0d”, “0?MEETMEFULL,1”) in new stack
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] pbx.c: Executing [STARTMEETME@from-internal:5] ConfBridge(“SIP/115-00008d0d”, “2001,”) in new stack
[2020-03-25 13:31:21] VERBOSE[21158][C-00001bcf] bridge_channel.c: Channel SIP/115-00008d0d joined ‘softmix’ base-bridge
[2020-03-25 13:31:21] VERBOSE[13044] file.c: <CBAnn/2001-00000028;1> Playing ‘confbridge-join.slin16’ (language ‘en’)
[2020-03-25 13:31:21] WARNING[21158][C-00001bcf] chan_iax2.c: Resyncing the jb. last_delay 0, this delay -138170199, threshold 1000, new offset 138170199
[2020-03-25 13:31:22] VERBOSE[21158][C-00001bcf] bridge_channel.c: Channel SIP/115-00008d0d left ‘softmix’ base-bridge
[2020-03-25 13:31:22] VERBOSE[21158][C-00001bcf] pbx.c: Executing [h@from-internal:1] Macro(“SIP/115-00008d0d”, “hangupcall”) in new stack
[2020-03-25 13:31:22] VERBOSE[21158][C-00001bcf] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/115-00008d0d”, “1?theend”) in new stack
[2020-03-25 13:31:22] VERBOSE[21158][C-00001bcf] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2020-03-25 13:31:22] VERBOSE[21158][C-00001bcf] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/115-00008d0d”, “0?Set(CDR(recordingfile)=)”) in new stack
[2020-03-25 13:31:22] VERBOSE[21158][C-00001bcf] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“SIP/115-00008d0d”, " montior file= ") in new stack
[2020-03-25 13:31:22] VERBOSE[21158][C-00001bcf] pbx.c: Executing [s@macro-hangupcall:5] GotoIf(“SIP/115-00008d0d”, “1?skipagi”) in new stack
[2020-03-25 13:31:22] VERBOSE[21158][C-00001bcf] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2020-03-25 13:31:22] VERBOSE[21158][C-00001bcf] pbx.c: Executing [s@macro-hangupcall:7] Hangup(“SIP/115-00008d0d”, “”) in new stack
[2020-03-25 13:31:22] VERBOSE[21158][C-00001bcf] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘SIP/115-00008d0d’ in macro ‘hangupcall’
[2020-03-25 13:31:22] VERBOSE[21158][C-00001bcf] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/115-00008d0d’
[2020-03-25 13:31:22] VERBOSE[2849] chan_sip.c: Extension Changed 115[ext-local] new state Idle for Notify User 116
[2020-03-25 13:31:22] VERBOSE[2849] chan_sip.c: Extension Changed 115[ext-local] new state Idle for Notify User 111
[2020-03-25 13:31:22] VERBOSE[2849] chan_sip.c: Extension Changed 115[ext-local] new state Idle for Notify User 112
[2020-03-25 13:31:22] VERBOSE[2849] chan_sip.c: Extension Changed 115[ext-local] new state Idle for Notify User 107
[2020-03-25 13:31:22] VERBOSE[2849] chan_sip.c: Extension Changed 115[ext-local] new state Idle for Notify User 114
[2020-03-25 13:31:22] VERBOSE[2849] chan_sip.c: Extension Changed 115[ext-local] new state Idle for Notify User 103
[2020-03-25 13:31:22] VERBOSE[2849] chan_sip.c: Extension Changed 115[ext-local] new state Idle for Notify User 108
[2020-03-25 13:31:22] VERBOSE[2849] chan_sip.c: Extension Changed 115[ext-local] new state Idle for Notify User 101
[2020-03-25 13:31:22] VERBOSE[2849] chan_sip.c: Extension Changed 115[ext-local] new state Idle for Notify User 117
[2020-03-25 13:31:22] VERBOSE[2849] chan_sip.c: Extension Changed 115[ext-local] new state Idle for Notify User 113
[2020-03-25 13:31:22] VERBOSE[13044] file.c: <CBAnn/2001-00000028;1> Playing ‘confbridge-leave.slin16’ (language ‘en’)

5

That looks worrisome to me. A five day jitter buffer seems a little extreme.

6

Can you tell me what may cause this?

7

I don’t do IAX2 connections anymore, so my experience is dated (and incomplete), but there’s something horked in your configuration if IAX2 is thinking your packets are getting delayed by almost a week…

There is the SET IAX2 DEBUG route, but that’s going to hammer your /var/…/full log with traffic.

If that’s the problem, it would make sense to the conference to hang on until the last packet clears the jitter buffer in a week.

Other than that, I’m pretty useless on this one.

8

OK, I will look into how to change the conferences to non IAX2! Thank you for looking through that for me!

9

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