Problem entering the conference pin twice

I am currently running the latest version of FreePBX Distro.

I created a conference room in FreePBX with a user/admin pin and music on hold.

When I dial in from an external line or an internal extension, I have to enter the pin twice. The first time it doesn’t recognize the input.

As I understand it, we should only have to enter it once.

Any help would be much appreciated.

Yeah I get the same results.
I removed the pin because it made it appear to others that it was broken.

Here is what I’m seeing in the log files. I’ve removed my SIP IP address and the admin PIN.

2012-03-28 05:41:06] VERBOSE[14559] pbx.c: – Executing [[email protected]:9] Set(“SIP/XXX.XXX.XXX.XXX-00000000”, “PINCOUNT=0”) in new stack
[2012-03-28 05:41:06] VERBOSE[14559] pbx.c: – Executing [[email protected]:10] Read(“SIP/XXX.XXX.XXX.XXX-00000000”, “PIN,enter-conf-pin-number,”) in new stack
[2012-03-28 05:41:06] VERBOSE[14559] file.c: – <SIP/XXX.XXX.XXX.XXX-00000000> Playing ‘enter-conf-pin-number.ulaw’ (language ‘en’)
[2012-03-28 05:41:11] VERBOSE[14559] app_read.c: – User entered ‘’
[2012-03-28 05:41:11] VERBOSE[14559] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/XXX.XXX.XXX.XXX-00000000”, “0?USER”) in new stack
[2012-03-28 05:41:11] VERBOSE[14559] pbx.c: – Executing [[email protected]:12] GotoIf(“SIP/XXX.XXX.XXX.XXX-00000000”, “1?ADMIN”) in new stack
[2012-03-28 05:41:11] VERBOSE[14559] pbx.c: – Goto (ext-meetme,1010744,17)
[2012-03-28 05:41:11] VERBOSE[14559] pbx.c: – Executing [[email protected]:17] Set(“SIP/XXX.XXX.XXX.XXX-00000000”, “MEETME_OPTS=aAwoTcIMs”) in new stack
[2012-03-28 05:41:11] VERBOSE[14559] pbx.c: – Executing [[email protected]:18] Playback(“SIP/XXX.XXX.XXX.XXX-00000000”, “custom/conf-thanks-stand-by”) in new stack
[2012-03-28 05:41:11] VERBOSE[14559] file.c: – <SIP/XXX.XXX.XXX.XXX-00000000> Playing ‘custom/tvs-conf-thanks-stand-by.slin’ (language ‘en’)
[2012-03-28 05:41:14] VERBOSE[14559] pbx.c: – Executing [[email protected]:19] Goto(“SIP/XXX.XXX.XXX.XXX-00000000”, “STARTMEETME,1”) in new stack
[2012-03-28 05:41:14] VERBOSE[14559] pbx.c: – Goto (ext-meetme,STARTMEETME,1)
[2012-03-28 05:41:14] VERBOSE[14559] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/XXX.XXX.XXX.XXX-00000000”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2012-03-28 05:41:14] VERBOSE[14559] pbx.c: – Executing [[email protected]:2] Set(“SIP/XXX.XXX.XXX.XXX-00000000”, “GROUP(meetme)=1010744”) in new stack
[2012-03-28 05:41:14] VERBOSE[14559] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/XXX.XXX.XXX.XXX-00000000”, “0?MEETMEFULL,1”) in new stack
[2012-03-28 05:41:14] VERBOSE[14559] pbx.c: – Executing [[email protected]:4] MeetMe(“SIP/XXX.XXX.XXX.XXX-00000000”, “1010744,aAwoTcIMs,”) in new stack
[2012-03-28 05:41:14] VERBOSE[14559] config.c: == Parsing ‘/etc/asterisk/meetme.conf’: [2012-03-28 05:41:14] VERBOSE[14559] config.c: == Found
[2012-03-28 05:41:14] VERBOSE[14559] config.c: == Parsing ‘/etc/asterisk/meetme_additional.conf’: [2012-03-28 05:41:14] VERBOSE[14559] config.c: == Found
[2012-03-28 05:41:14] VERBOSE[14559] app_meetme.c: – Created MeetMe conference 1022 for conference ‘1010744’

[At this point, I would normally expect the conference to start. Instead, we get the following items in the log file.]

[2012-03-28 05:41:14] VERBOSE[14559] file.c: – <SIP/XXX.XXX.XXX.XXX-00000000> Playing ‘conf-getpin.ulaw’ (language ‘en’)

[I entered the USER PIN to see if that would work. No luck, resulted in the error below.]
[2012-03-28 05:41:19] VERBOSE[14559] file.c: – <SIP/XXX.XXX.XXX.XXX-00000000> Playing ‘conf-invalidpin.ulaw’ (language ‘en’)

[So, I re-entered the ADMIN PIN to see if that would work. That worked and I was prompted for my name.]

[2012-03-28 05:41:22] VERBOSE[14559] file.c: – <SIP/XXX.XXX.XXX.XXX-00000000> Playing ‘conf-getpin.ulaw’ (language ‘en’)
[2012-03-28 05:41:26] VERBOSE[14559] file.c: – <SIP/XXX.XXX.XXX.XXX-00000000> Playing ‘vm-rec-name.ulaw’ (language ‘en’)
[2012-03-28 05:41:30] VERBOSE[14559] file.c: – <SIP/XXX.XXX.XXX.XXX-00000000> Playing ‘beep.ulaw’ (language ‘en’)
[2012-03-28 05:41:30] VERBOSE[14559] app.c: – x=0, open writing: /var/spool/asterisk/meetme/meetme-username-1010744-1 format: sln, 0x8f0d210
[2012-03-28 05:41:31] VERBOSE[14559] app.c: – User hung up

We need a fix for this module ASAP please.

Thanks.

[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:1] Macro(“SIP/7570-00001cc6”, “user-callerid,”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:1] Set(“SIP/7570-00001cc6”, “AMPUSER=7570”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/7570-00001cc6”, “0?report”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/7570-00001cc6”, “1?Set(REALCALLERIDNUM=7570)”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:4] Set(“SIP/7570-00001cc6”, “AMPUSER=7570”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:5] Set(“SIP/7570-00001cc6”, “AMPUSERCIDNAME=Cory Wagner”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:6] GotoIf(“SIP/7570-00001cc6”, “0?report”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:7] Set(“SIP/7570-00001cc6”, “AMPUSERCID=7570”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:8] Set(“SIP/7570-00001cc6”, “CALLERID(all)=“Cory Wagner” <7570>”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/7570-00001cc6”, “0?limit”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:10] ExecIf(“SIP/7570-00001cc6”, “0?Set(GROUP(concurrency_limit)=7570)”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:11] GosubIf(“SIP/7570-00001cc6”, “7?sub-ccss,s,1(from-internal,8570)”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/7570-00001cc6”, “0?Return()”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:2] Set(“SIP/7570-00001cc6”, “CCSS_SETUP=TRUE”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:3] GosubIf(“SIP/7570-00001cc6”, “0?monitor_config,1(from-internal,8570):monitor_default,1(from-internal,8570)”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/7570-00001cc6”, “0?is_exten”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:2] StackPop(“SIP/7570-00001cc6”, “”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:3] Return(“SIP/7570-00001cc6”, “FALSE”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/7570-00001cc6”, “0?Set(CHANNEL(language)=)”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:13] GotoIf(“SIP/7570-00001cc6”, “0?continue”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:14] Set(“SIP/7570-00001cc6”, “__TTL=64”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:15] GotoIf(“SIP/7570-00001cc6”, “1?continue”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Goto (macro-user-callerid,s,26)
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:26] Set(“SIP/7570-00001cc6”, “CALLERID(number)=7570”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:27] Set(“SIP/7570-00001cc6”, “CALLERID(name)=Cory Wagner”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:28] Set(“SIP/7570-00001cc6”, “CHANNEL(language)=en”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:2] Set(“SIP/7570-00001cc6”, “MEETME_ROOMNUM=8570”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:3] Set(“SIP/7570-00001cc6”, “MAX_PARTICIPANTS=20”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:4] Set(“SIP/7570-00001cc6”, “MEETME_MUSIC=”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:5] Gosub(“SIP/7570-00001cc6”, “sub-record-check,s,1(conf,8570,never)”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/7570-00001cc6”, “1?check”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Goto (sub-record-check,s,3)
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:3] Set(“SIP/7570-00001cc6”, “MON_FMT=wav”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:4] GotoIf(“SIP/7570-00001cc6”, “1?next”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Goto (sub-record-check,s,7)
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:7] ExecIf(“SIP/7570-00001cc6”, “0?Return()”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/7570-00001cc6”, “0?conf,1”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:9] Set(“SIP/7570-00001cc6”, “__REC_STATUS=INITIALIZED”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:10] ExecIf(“SIP/7570-00001cc6”, “1?Set(__REC_POLICY_MODE=never)”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:11] Set(“SIP/7570-00001cc6”, “NOW=1332959017”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:12] Set(“SIP/7570-00001cc6”, “__DAY=28”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:13] Set(“SIP/7570-00001cc6”, “__MONTH=03”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:14] Set(“SIP/7570-00001cc6”, “__YEAR=2012”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:15] Set(“SIP/7570-00001cc6”, “__TIMESTR=20120328-132337”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:16] Set(“SIP/7570-00001cc6”, “__FROMEXTEN=7570”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:17] Set(“SIP/7570-00001cc6”, “__CALLFILENAME=conf-8570-7570-20120328-132337-1332959017.19389”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:18] Goto(“SIP/7570-00001cc6”, “conf,1”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Goto (sub-record-check,conf,1)
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:1] Gosub(“SIP/7570-00001cc6”, “recconf,1(conf,8570,8570)”) in new stack
[2012-03-28 13:23:37] NOTICE[13417] app_meetme.c: Error: conference (8570) not found
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:1] Set(“SIP/7570-00001cc6”, “__CALLFILENAME=conf-8570-8570-20120328-132337-1332959017.19389”) in new stack
[2012-03-28 13:23:37] NOTICE[13417] app_meetme.c: Error: conference (8570) not found
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:2] ExecIf(“SIP/7570-00001cc6”, “1?Set(DB(RECCONF/8570)=conf-8570-8570-20120328-132337-1332959017.19389)”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:3] Set(“SIP/7570-00001cc6”, “MEETME_RECORDINGFILE=/var/spool/asterisk/monitor/2012/03/28/conf-8570-8570-20120328-132337-1332959017.19389”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:4] Set(“SIP/7570-00001cc6”, “MEETME_RECORDINGFORMAT=wav”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:5] ExecIf(“SIP/7570-00001cc6”, “1?Return()”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:2] Return(“SIP/7570-00001cc6”, “”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:6] GotoIf(“SIP/7570-00001cc6”, “0?READPIN”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:7] Answer(“SIP/7570-00001cc6”, “”) in new stack
[2012-03-28 13:23:37] VERBOSE[13417] pbx.c: – Executing [[email protected]:8] Wait(“SIP/7570-00001cc6”, “1”) in new stack
[2012-03-28 13:23:38] VERBOSE[13417] pbx.c: – Executing [[email protected]:9] Set(“SIP/7570-00001cc6”, “PINCOUNT=0”) in new stack
[2012-03-28 13:23:38] VERBOSE[13417] pbx.c: – Executing [[email protected]:10] Read(“SIP/7570-00001cc6”, “PIN,enter-conf-pin-number,”) in new stack
[2012-03-28 13:23:38] VERBOSE[13417] file.c: – <SIP/7570-00001cc6> Playing ‘enter-conf-pin-number.ulaw’ (language ‘en’)
[2012-03-28 13:23:49] VERBOSE[13417] app_read.c: – User entered ‘3434’
[2012-03-28 13:23:49] VERBOSE[13417] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/7570-00001cc6”, “1?USER”) in new stack
[2012-03-28 13:23:49] VERBOSE[13417] pbx.c: – Goto (from-internal,8570,19)
[2012-03-28 13:23:49] VERBOSE[13417] pbx.c: – Executing [[email protected]:19] Set(“SIP/7570-00001cc6”, “MEETME_OPTS=wTcIM”) in new stack
[2012-03-28 13:23:49] VERBOSE[13417] pbx.c: – Executing [[email protected]:20] Goto(“SIP/7570-00001cc6”, “STARTMEETME,1”) in new stack
[2012-03-28 13:23:49] VERBOSE[13417] pbx.c: – Goto (from-internal,STARTMEETME,1)
[2012-03-28 13:23:49] VERBOSE[13417] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/7570-00001cc6”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2012-03-28 13:23:49] VERBOSE[13417] pbx.c: – Executing [[email protected]:2] Set(“SIP/7570-00001cc6”, “GROUP(meetme)=8570”) in new stack
[2012-03-28 13:23:49] VERBOSE[13417] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/7570-00001cc6”, “0?MEETMEFULL,1”) in new stack
[2012-03-28 13:23:49] VERBOSE[13417] pbx.c: – Executing [[email protected]:4] MeetMe(“SIP/7570-00001cc6”, “8570,wTcIM,”) in new stack
[2012-03-28 13:23:49] VERBOSE[13417] config.c: == Parsing ‘/etc/asterisk/meetme.conf’: [2012-03-28 13:23:49] VERBOSE[13417] config.c: == Found
[2012-03-28 13:23:49] VERBOSE[13417] config.c: == Parsing ‘/etc/asterisk/meetme_additional.conf’: [2012-03-28 13:23:49] VERBOSE[13417] config.c: == Found
[2012-03-28 13:23:49] VERBOSE[13417] app_meetme.c: – Created MeetMe conference 1022 for conference ‘8570’
[2012-03-28 13:23:49] VERBOSE[13417] file.c: – <SIP/7570-00001cc6> Playing ‘conf-getpin.ulaw’ (language ‘en’)
[2012-03-28 13:24:00] VERBOSE[13417] file.c: – <SIP/7570-00001cc6> Playing ‘vm-rec-name.ulaw’ (language ‘en’)
[2012-03-28 13:24:02] VERBOSE[13417] chan_dahdi.c: – Hungup ‘DAHDI/pseudo-1718423890’
[2012-03-28 13:24:02] VERBOSE[13417] pbx.c: == Spawn extension (from-internal, STARTMEETME, 4) exited non-zero on ‘SIP/7570-00001cc6’
[2012-03-28 13:24:02] VERBOSE[13417] pbx.c: – Executing [[email protected]:1] Hangup(“SIP/7570-00001cc6”, “”) in new stack
[2012-03-28 13:24:02] VERBOSE[13417] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/7570-00001cc6’

for developers…

any chance we can resolve this?

I am getting the same problem after a module update (and possibly a yum update) in the last week or so. I did a PCAP capture to ensure that rtpevents were flowing to the FreePBX. They are, in both the original PIN entry and after the re-prompting for the PIN. Need fix.

Is there a way to attach PCAPs on these posts?

Thanks
DeltaDB

If you want this looked at open a TRAC ticket. The deveopment team does not respond to forum posts. The tracker gives visibility for issues and features.

and I’m very busy. If someone else could and keep up with this thread, that would be great. thanks!

http://www.freepbx.org/trac/ticket/5752

For getting the ball rolling.