no action from Whisper and Listen buttons

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

Comments

  • 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..
  • 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" />
    
  • 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,
  • 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?
  • 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 ?
  • 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
  • Yes, when using user&device mode you will have problems. The next release will work fine.
Sign In or Register to comment.