no action from Whisper and Listen buttons

tauon01

Hi Guys, i just wanted to say that i love the polished feel of FOP2 and how nicely it ties in with AMI.

I have been having issues using the Listen and Listen and whisper buttons. Transfer, transfer to voicemail and Dial all work from the FOP2 website. From what i understand clicking Listen should call the extension associated with the user i've logged in with, and once i pickup it should drop me into the call. However, when i click on listen nothing happens.

Also not sure if this is related, but the Action sub-menu does not come up either. Can someone help me figure this out? Is there anything in the logs i should be looking for? I am running this on CentOS 5.5 x86 with Asterisk 1.6.2.9 and FreePBX 2.8.0.2

nicolas

The best way to debug is start fop2_server in debug mode and look at the events. You might need more permissions for the manager user, or maybe there is some kind of issue that we will only find out by looking at the logs. You can stop fop2 as a service "service fop2 stop" and the start it in debug mode: "/usr/local/fop2/fop2_server -X 15". Then try one of your actions and inspect the logs for errors, warnings, etc..

tauon01

Thanks, it looks like it recognizes the event from the button, however no action follows. I couldnt find any events recorded in /var/log/messages but here is the output from the debug session (The first few lines occur when we connect the two calls):

10.5.1.252      => { 'btn': '3@GENERAL', 'cmd': 'settimer', 'data': '0@UP', 'slot': '1' }

10.5.1.252      => { 'btn': '3@GENERAL', 'cmd': 'state', 'data': 'UP', 'slot': '1' }

10.5.1.252      => { 'btn': '3@GENERAL', 'cmd': 'settext', 'data': '300 Nate', 'slot': '1' }

10.5.1.252      => { 'btn': '2@GENERAL', 'cmd': 'settimer', 'data': '0@UP', 'slot': '1' }

10.5.1.252      => { 'btn': '2@GENERAL', 'cmd': 'state', 'data': 'DIALING', 'slot': '1' }

10.5.1.252      => { 'btn': '2@GENERAL', 'cmd': 'settext', 'data': '400 ', 'slot': '1' }

10.5.1.252      => { 'btn': '2@GENERAL', 'cmd': 'settimer', 'data': '0@UP', 'slot': '1' }

10.5.1.252      => { 'btn': '2@GENERAL', 'cmd': 'state', 'data': 'UP', 'slot': '1' }

10.5.1.252      => { 'btn': '2@GENERAL', 'cmd': 'settext', 'data': '400 ', 'slot': '1' }

10.5.1.252      => { 'btn': '2@GENERAL', 'cmd': 'settext', 'data': '400', 'slot': '1' }

10.5.1.252      => { 'btn': '3@GENERAL', 'cmd': 'settext', 'data': '300', 'slot': '1' }

127.0.0.1       <- Event: RTCPSent
127.0.0.1       <- Privilege: reporting,all
127.0.0.1       <- To: 10.5.1.244:10601
127.0.0.1       <- OurSSRC: 635859081
127.0.0.1       <- SentNTP: 1281408892.2267717632
127.0.0.1       <- SentRTP: 26365232
127.0.0.1       <- SentPackets: 247
127.0.0.1       <- SentOctets: 39520
127.0.0.1       <- ReportBlock:
127.0.0.1       <- FractionLost: 0
127.0.0.1       <- CumulativeLoss: 0
127.0.0.1       <- IAJitter: 0.0004
127.0.0.1       <- TheirLastSR: 0
127.0.0.1       <- DLSR: 65535.6250 (sec)
127.0.0.1       <- Server: 0

127.0.0.1       <- Event: RTCPSent
127.0.0.1       <- Privilege: reporting,all
127.0.0.1       <- To: 10.5.1.250:18543
127.0.0.1       <- OurSSRC: 218854
127.0.0.1       <- SentNTP: 1281408892.2267725824
127.0.0.1       <- SentRTP: 39840
127.0.0.1       <- SentPackets: 250
127.0.0.1       <- SentOctets: 40000
127.0.0.1       <- ReportBlock:
127.0.0.1       <- FractionLost: 0
127.0.0.1       <- CumulativeLoss: 0
127.0.0.1       <- IAJitter: 0.0004
127.0.0.1       <- TheirLastSR: 0
127.0.0.1       <- DLSR: 65535.6250 (sec)
127.0.0.1       <- Server: 0

10.5.1.252      <= <msg data="1|ping||" />
10.5.1.252      => { 'btn': '0', 'cmd': 'pong', 'data': '0', 'slot': '' }

127.0.0.1       <- Event: RTCPSent
127.0.0.1       <- Privilege: reporting,all
127.0.0.1       <- To: 10.5.1.244:10601
127.0.0.1       <- OurSSRC: 635859081
127.0.0.1       <- SentNTP: 1281408897.2266210304
127.0.0.1       <- SentRTP: 26405232
127.0.0.1       <- SentPackets: 497
127.0.0.1       <- SentOctets: 79520
127.0.0.1       <- ReportBlock:
127.0.0.1       <- FractionLost: 0
127.0.0.1       <- CumulativeLoss: 0
127.0.0.1       <- IAJitter: 0.0005
127.0.0.1       <- TheirLastSR: 0
127.0.0.1       <- DLSR: 4.6250 (sec)
127.0.0.1       <- Server: 0

127.0.0.1       <- Event: RTCPSent
127.0.0.1       <- Privilege: reporting,all
127.0.0.1       <- To: 10.5.1.250:18543
127.0.0.1       <- OurSSRC: 218854
127.0.0.1       <- SentNTP: 1281408897.2266185728
127.0.0.1       <- SentRTP: 79840
127.0.0.1       <- SentPackets: 500
127.0.0.1       <- SentOctets: 80000
127.0.0.1       <- ReportBlock:
127.0.0.1       <- FractionLost: 0
127.0.0.1       <- CumulativeLoss: 0
127.0.0.1       <- IAJitter: 0.0001
127.0.0.1       <- TheirLastSR: 0
127.0.0.1       <- DLSR: 4.6250 (sec)
127.0.0.1       <- Server: 0

10.5.1.252      <= <msg data="1|towhisper|1|cf981cb0e1cf521504205ce0627a0517" />

127.0.0.1       <- Event: RTCPSent
127.0.0.1       <- Privilege: reporting,all
127.0.0.1       <- To: 10.5.1.244:10601
127.0.0.1       <- OurSSRC: 635859081
127.0.0.1       <- SentNTP: 1281408902.2264829952
127.0.0.1       <- SentRTP: 26445232
127.0.0.1       <- SentPackets: 747
127.0.0.1       <- SentOctets: 119520
127.0.0.1       <- ReportBlock:
127.0.0.1       <- FractionLost: 0
127.0.0.1       <- CumulativeLoss: 0
127.0.0.1       <- IAJitter: 0.0007
127.0.0.1       <- TheirLastSR: 0
127.0.0.1       <- DLSR: 9.6240 (sec)
127.0.0.1       <- Server: 0

127.0.0.1       <- Event: RTCPSent
127.0.0.1       <- Privilege: reporting,all
127.0.0.1       <- To: 10.5.1.250:18543
127.0.0.1       <- OurSSRC: 218854
127.0.0.1       <- SentNTP: 1281408902.2268901376
127.0.0.1       <- SentRTP: 119840
127.0.0.1       <- SentPackets: 750
127.0.0.1       <- SentOctets: 120000
127.0.0.1       <- ReportBlock:
127.0.0.1       <- FractionLost: 0
127.0.0.1       <- CumulativeLoss: 0
127.0.0.1       <- IAJitter: 0.0005
127.0.0.1       <- TheirLastSR: 0
127.0.0.1       <- DLSR: 9.6250 (sec)
127.0.0.1       <- Server: 0

127.0.0.1       <- Event: RTCPSent
127.0.0.1       <- Privilege: reporting,all
127.0.0.1       <- To: 10.5.1.244:10601
127.0.0.1       <- OurSSRC: 635859081
127.0.0.1       <- SentNTP: 1281408907.2267271168
127.0.0.1       <- SentRTP: 26485232
127.0.0.1       <- SentPackets: 997
127.0.0.1       <- SentOctets: 159520
127.0.0.1       <- ReportBlock:
127.0.0.1       <- FractionLost: 0
127.0.0.1       <- CumulativeLoss: 0
127.0.0.1       <- IAJitter: 0.0014
127.0.0.1       <- TheirLastSR: 0
127.0.0.1       <- DLSR: 14.6250 (sec)
127.0.0.1       <- Server: 0

127.0.0.1       <- Event: RTCPSent
127.0.0.1       <- Privilege: reporting,all
127.0.0.1       <- To: 10.5.1.250:18543
127.0.0.1       <- OurSSRC: 218854
127.0.0.1       <- SentNTP: 1281408907.2267295744
127.0.0.1       <- SentRTP: 159840
127.0.0.1       <- SentPackets: 1000
127.0.0.1       <- SentOctets: 160000
127.0.0.1       <- ReportBlock:
127.0.0.1       <- FractionLost: 0
127.0.0.1       <- CumulativeLoss: 0
127.0.0.1       <- IAJitter: 0.0002
127.0.0.1       <- TheirLastSR: 0
127.0.0.1       <- DLSR: 14.6250 (sec)
127.0.0.1       <- Server: 0

127.0.0.1       <- Event: RTCPSent
127.0.0.1       <- Privilege: reporting,all
127.0.0.1       <- To: 10.5.1.244:10601
127.0.0.1       <- OurSSRC: 635859081
127.0.0.1       <- SentNTP: 1281408912.2265784320
127.0.0.1       <- SentRTP: 26525232
127.0.0.1       <- SentPackets: 1247
127.0.0.1       <- SentOctets: 199520
127.0.0.1       <- ReportBlock:
127.0.0.1       <- FractionLost: 0
127.0.0.1       <- CumulativeLoss: 0
127.0.0.1       <- IAJitter: 0.0009
127.0.0.1       <- TheirLastSR: 0
127.0.0.1       <- DLSR: 19.6250 (sec)
127.0.0.1       <- Server: 0

127.0.0.1       <- Event: RTCPSent
127.0.0.1       <- Privilege: reporting,all
127.0.0.1       <- To: 10.5.1.250:18543
127.0.0.1       <- OurSSRC: 218854
127.0.0.1       <- SentNTP: 1281408912.2265751552
127.0.0.1       <- SentRTP: 199840
127.0.0.1       <- SentPackets: 1250
127.0.0.1       <- SentOctets: 200000
127.0.0.1       <- ReportBlock:
127.0.0.1       <- FractionLost: 0
127.0.0.1       <- CumulativeLoss: 0
127.0.0.1       <- IAJitter: 0.0002
127.0.0.1       <- TheirLastSR: 0
127.0.0.1       <- DLSR: 19.6250 (sec)
127.0.0.1       <- Server: 0

10.5.1.252      <= <msg data="1|tospy|2|cf981cb0e1cf521504205ce0627a0517" />

nicolas

Ok.. I need to see the events after the "tospy", you can increase verbosity by using -X 511, but the output will be huge. What fop2 version are you running? 2.11 ?

Best regards,

tauon01

I am using fop 2.11 with FreePBX 2.8 and Asterisk 1.6. I am also using the SCCP protocol to communicate to Cisco Phones. I have increased the verbosity to 511 and here is the output:

** MAIN AMI event received...
** MAIN There's an 'Event' in the event block
** MAIN Event detected block_count = 0
** MAIN There are 0 blocks for processing
** MAIN Answer block cleared

** DIGEST_EVENT: start

** PROCESA_BLOQUE RTCPSENT 0

** MAIN AMI event received...
** MAIN Processing command received from flash clients...

10.5.1.252      <= <msg data="3|tospy|2|a5ecfef163facc3e317598214a33c967" />

-- PROCESS_FLASH_COMMAND origen 3 accion tospy destino 2

-- PROCESS_FLASH_COMMAND password a5ecfef163facc3e317598214a33c967

VALIDAR USUARIO 300

Validation ok, have all permissions

It's blessed into class Extension

Not a reference at all

TO SPY SIP/300 no definido!

** MAIN End of block from localhost

This is probably unrelated but I also had to modify the SQL calls in /usr/local/fop2/autoconfig-buttons-freepbx.sh from -ENB to -E to get the buttons to show up after logging in. Would this also prevent the Action sub-menu from displaying?

nicolas

Thanks for the feedback, the mysql command line options changed with the latest version, the correct entry can be -EB now.

The debug output says SIP/300 is not defined in your buttons.cfg file, you mentioned that you are using SCCP, so I am not sure what is the proper channel that should be passed to chanspy... can you paste the output from autoconfig-users-freepbx.sh script for extension 300 ? Is that a SIP/300 channel or something else ?

tauon01

So I have reinstalled everything on a fresh machine using only SIP extensions and FOP2.11 and i still get the same error:

** MAIN AMI event received...
** MAIN Processing command received from flash clients...

10.5.1.196      <= <msg data="2|towhisper|1|22370d716d8ded01fb94b8bba0bca1a5" />

-- PROCESS_FLASH_COMMAND origen 2 accion towhisper destino 1

-- PROCESS_FLASH_COMMAND password 22370d716d8ded01fb94b8bba0bca1a5

VALIDAR USUARIO 200

Validation ok, have all permissions

Not a reference at all
Not a reference at all

TO WHISPER SIP/200 no definido!

** MAIN End of block from localhost

127.0.0.1       <- Event: RTCPReceived
127.0.0.1       <- Privilege: reporting,all
127.0.0.1       <- From: 10.5.1.235:50105
127.0.0.1       <- PT: 200(Sender Report)
127.0.0.1       <- ReceptionReports: 1
127.0.0.1       <- SenderSSRC: 0
127.0.0.1       <- FractionLost: 0
127.0.0.1       <- PacketsLost: 0
127.0.0.1       <- HighestSequence: 55282
127.0.0.1       <- SequenceNumberCycles: 0
127.0.0.1       <- IAJitter: 15
127.0.0.1       <- LastSR: 1662.29238517343191040
127.0.0.1       <- DLSR: 0.7390(sec)
127.0.0.1       <- RTT: 1(sec)
127.0.0.1       <- Server: 0

** MAIN AMI event received...
** MAIN There's an 'Event' in the event block
** MAIN Event detected block_count = 0
** MAIN There are 0 blocks for processing
** MAIN Answer block cleared

** DIGEST_EVENT: start

** PROCESA_BLOQUE RTCPRECEIVED 0

** MAIN End of block from localhost

These are SIP devices with fixed extensions:

[USER/100]
type=extension
extension=100
label=100
mailbox=100@default
context=from-internal
queuecontext=from-queue
extenvoicemail=*100@default
privacy=none

[USER/200]
type=extension
extension=200
label=200
mailbox=200@default
context=from-internal
queuecontext=from-queue
extenvoicemail=*200@default
privacy=none

[USER/300]
type=extension
extension=300
label=300
mailbox=300@default
context=from-internal
queuecontext=from-queue
extenvoicemail=*300@default
privacy=none

[PARK/default]
type=park
context=parkedcalls
queuecontext=from-queue
extenvoicemail=*

[ZAP/g0]
type=trunk
extension=
label=ZAP/g0
queuecontext=from-queue
extenvoicemail=*

If i dial 555 from fop2 then listen and whisper works, using the button however doesn't produce anything except: TO WHISPER SIP/200 no definido. The users are set to have all permissions and the privacy mode is set to none in FreePBX (through the FOP2 plugin).

Thanks!
John

nicolas

Yes, when using user&device mode you will have problems. The next release will work fine.

FOP2 Community Forum

no action from Whisper and Listen buttons