Announcement

Collapse
No announcement yet.

#VoIP Server side error 500: Extension does not exist.

Collapse
X
 
  • Filter
  • Time
  • Show
Clear All
new posts

  • #VoIP Server side error 500: Extension does not exist.

    Hi EspoCRM,

    we have Espo 5.5.6 with AP 1.33.7 and VoIP Integration 1.9.1 on Linux EspoCRM 4.4.128-1-pve #1 SMP PVE 4.4.128-111 Debian Stretch, PHP Version 7.0.33, MariaDB version 10.1.37-MariaDB-0+deb9u1 --> up and running. All system requirements are ok, green. Installation made with: https://www.espocrm.com/features/ast...gration-setup/, cron is running etc...

    The connection between Asterisk 13.14.1~dfsg-2+deb9u4 is fine. Incoming calls for the user are resolved inside EspoCRM.

    But if i want to make an outgoing call i get the following error



    on Chrome console:

    Code:
    Failed to load resource: the server responded with a status of 500 (Internal Server Error)
    espo.min.js?r=1548945009:20 Server side error 500: Extension does not exist.
    (anonymous) @ espo.min.js?r=1548945009:20
    Firefox:



    When i click on the number Asterisk's AMI says:
    Code:
    == Manager 'admin' logged off from 192.xxx.x.xxx
      == Manager 'admin' logged on from 192.xxx.x.xxx

    Enabled DEBUG, but there is no message while trying to initiate the outgoing call.
    Further deactivated and activated VoIP in Settings, but it doesn't work.

    I am stuck ;-)
    Could you please give me a hint what to do.

    Thanks and best regards,

    ZUdev


  • #2
    Hello,
    please check server (apache, nginx etc) error log.

    Comment


    • #3
      Hi Tanya,

      i checked, but there is nothing. It's on debug level.

      Code:
      [Fri Feb 01 13:21:10.334490 2019] [mpm_prefork:notice] [pid 20805] AH00163: Apache/2.4.25 (Debian) OpenSSL/1.0.2q configured -- resuming normal operations
      [Fri Feb 01 13:21:10.334499 2019] [mpm_prefork:info] [pid 20805] AH00164: Server built: 2018-11-03T18:46:19
      [Fri Feb 01 13:21:10.334507 2019] [core:notice] [pid 20805] AH00094: Command line: '/usr/sbin/apache2'
      [Fri Feb 01 13:21:10.334511 2019] [core:debug] [pid 20805] log.c(1546): AH02639: Using SO_REUSEPORT: yes (1)
      [Fri Feb 01 13:21:10.334518 2019] [mpm_prefork:debug] [pid 20805] prefork.c(1032): AH00165: Accept mutex: none (default: sysvsem)
      [Fri Feb 01 13:21:10.334689 2019] [watchdog:debug] [pid 20827] mod_watchdog.c(563): AH02980: Watchdog: nothing configured?
      [Fri Feb 01 13:21:12.337732 2019] [watchdog:debug] [pid 20828] mod_watchdog.c(563): AH02980: Watchdog: nothing configured?
      [Fri Feb 01 13:21:14.340694 2019] [watchdog:debug] [pid 20837] mod_watchdog.c(563): AH02980: Watchdog: nothing configured?
      [Fri Feb 01 13:21:15.342648 2019] [watchdog:debug] [pid 20838] mod_watchdog.c(563): AH02980: Watchdog: nothing configured?
      [Fri Feb 01 13:21:15.342996 2019] [watchdog:debug] [pid 20839] mod_watchdog.c(563): AH02980: Watchdog: nothing configured?
      [Fri Feb 01 13:21:35.362727 2019] [watchdog:debug] [pid 20841] mod_watchdog.c(563): AH02980: Watchdog: nothing configured?
      [Fri Feb 01 13:21:56.734312 2019] [deflate:debug] [pid 20838] mod_deflate.c(853): [client 192.xxx.xxx.xxx:63188] AH01384: Zlib: Compressed 1903 to 759 : URL /index.php
      [Fri Feb 01 13:21:56.734374 2019] [deflate:debug] [pid 20838] mod_deflate.c(1620): [client 192.xxx.xxx.xxx:63188] AH01398: Zlib: Inflated 759 to 1903 : URL /index.php
      [Fri Feb 01 13:21:56.734528 2019] [deflate:debug] [pid 20838] mod_deflate.c(853): [client 192.xxx.xxx.xxx:63188] AH01384: Zlib: Compressed 2359 to 954 : URL /index.php
      [Fri Feb 01 13:22:42.639442 2019] [core:info] [pid 20824] [client 192.xxx.xxx.xxx:63383] AH00128: File does not exist: /var/www/espocrm/gridstack.min.map
      [Fri Feb 01 13:24:11.150058 2019] [core:info] [pid 20825] [client 192.xxx.xxx.xxx:63538] AH00128: File does not exist: /var/www/espocrm/favicon.ico, referer: http://mydomain.com/api/v1/VoipEvent/action/checkCall
      [Fri Feb 01 13:26:02.311923 2019] [deflate:debug] [pid 20825] mod_deflate.c(853): [client 192.xxx.xxx.xxx:63848] AH01384: Zlib: Compressed 1903 to 759 : URL /index.php
      [Fri Feb 01 13:26:02.311981 2019] [deflate:debug] [pid 20825] mod_deflate.c(1620): [client 192.xxx.xxx.xxx:63848] AH01398: Zlib: Inflated 759 to 1903 : URL /index.php
      [Fri Feb 01 13:26:02.312126 2019] [deflate:debug] [pid 20825] mod_deflate.c(853): [client 192.xxx.xxx.xxx:63848] AH01384: Zlib: Compressed 2359 to 954 : URL /index.php
      [Fri Feb 01 13:26:03.619974 2019] [watchdog:debug] [pid 20939] mod_watchdog.c(563): AH02980: Watchdog: nothing configured?
      [Fri Feb 01 13:26:04.621627 2019] [watchdog:debug] [pid 20940] mod_watchdog.c(563): AH02980: Watchdog: nothing configured?
      [Fri Feb 01 13:26:04.621987 2019] [watchdog:debug] [pid 20941] mod_watchdog.c(563): AH02980: Watchdog: nothing configured?
      [Fri Feb 01 13:26:05.623366 2019] [watchdog:debug] [pid 20942] mod_watchdog.c(563): AH02980: Watchdog: nothing configured?
      [Fri Feb 01 13:26:05.623746 2019] [watchdog:debug] [pid 20943] mod_watchdog.c(563): AH02980: Watchdog: nothing configured?
      [Fri Feb 01 13:26:05.624220 2019] [watchdog:debug] [pid 20944] mod_watchdog.c(563): AH02980: Watchdog: nothing configured?
      [Fri Feb 01 13:26:05.624674 2019] [watchdog:debug] [pid 20945] mod_watchdog.c(563): AH02980: Watchdog: nothing configured?
      [Fri Feb 01 13:26:43.400898 2019] [deflate:debug] [pid 20942] mod_deflate.c(853): [client 192.xxx.xxx.xxx:63892] AH01384: Zlib: Compressed 1903 to 759 : URL /index.php
      [Fri Feb 01 13:26:43.400951 2019] [deflate:debug] [pid 20942] mod_deflate.c(1620): [client 192.xxx.xxx.xxx:63892] AH01398: Zlib: Inflated 759 to 1903 : URL /index.php
      [Fri Feb 01 13:26:43.401081 2019] [deflate:debug] [pid 20942] mod_deflate.c(853): [client 192.xxx.xxx.xxx:63892] AH01384: Zlib: Compressed 2359 to 954 : URL /index.php
      [Fri Feb 01 13:28:28.131477 2019] [deflate:debug] [pid 20827] mod_deflate.c(853): [client 192.xxx.xxx.xxx:64201] AH01384: Zlib: Compressed 1903 to 759 : URL /index.php
      [Fri Feb 01 13:28:28.131523 2019] [deflate:debug] [pid 20827] mod_deflate.c(1620): [client 192.xxx.xxx.xxx:64201] AH01398: Zlib: Inflated 759 to 1903 : URL /index.php
      [Fri Feb 01 13:28:28.131650 2019] [deflate:debug] [pid 20827] mod_deflate.c(853): [client 192.xxx.xxx.xxx:64201] AH01384: Zlib: Compressed 2359 to 954 : URL /index.php
      [Fri Feb 01 13:28:29.765833 2019] [watchdog:debug] [pid 20989] mod_watchdog.c(563): AH02980: Watchdog: nothing configured?
      [Fri Feb 01 13:28:35.772694 2019] [watchdog:debug] [pid 20990] mod_watchdog.c(563): AH02980: Watchdog: nothing configured?
      [Fri Feb 01 13:28:54.482026 2019] [deflate:debug] [pid 20989] mod_deflate.c(853): [client 192.xxx.xxx.xxx:64287] AH01384: Zlib: Compressed 1903 to 759 : URL /index.php
      [Fri Feb 01 13:28:54.482110 2019] [deflate:debug] [pid 20989] mod_deflate.c(1620): [client 192.xxx.xxx.xxx:64287] AH01398: Zlib: Inflated 759 to 1903 : URL /index.php
      [Fri Feb 01 13:28:54.482273 2019] [deflate:debug] [pid 20989] mod_deflate.c(853): [client 192.xxx.xxx.xxx:64287] AH01384: Zlib: Compressed 2359 to 954 : URL /index.php
      I assumed that it could maybe have something to do with SSL, and tested it with HTTP / port 80 only, but it doesn't work either.

      Could it have something to do with file- / dir rights?

      It's a turnkey vm. pretty straightforward i think...turnkey-espocrm-15.0-stretch-amd64.
      Besides our SSL certificate (which i disabled right now) it's a default setup.

      Thanks.

      ZUdev





      Comment


      • #4
        One addition. Chrome console shows:

        Code:
        espo.min.js?r=1549027620:4 POST http://mydomain.com/[B]api/v1/VoipEvent/action/Dial[/B] 500 (Internal Server Error)
        send @ espo.min.js?r=1549027620:4
        ajax @ espo.min.js?r=1549027620:4
        actionDial @ VM10633:47
        events.click [data-action="dial"] @ VM10633:24
        dispatch @ espo.min.js?r=1549027620:3
        r.handle @ espo.min.js?r=1549027620:3
        espo.min.js?r=1549027620:20 Server side error 500: Extension does not exist.

        Checking the directory shows:

        Code:
        #ll /var/www/espocrm/api/v1/
        total 24
        drwxr-xr-x 3 www-data www-data 4096 Feb  1 14:35 ./
        drwxr-xr-x 3 www-data www-data 4096 Jun  8  2018 ../
        -rw-r--r-- 1 www-data www-data  375 Jun  8  2018 .htaccess
        -rw-r--r-- 1 www-data www-data 1449 Jun  8  2018 index.php
        drwxr-xr-x 2 www-data www-data 4096 Jun  8  2018 portal-access/
        -rw-r--r-- 1 www-data www-data  367 Jun  8  2018 web.config

        There is no 'VoipEvent'. Maybe something is going wrong with the API?!

        Thanks and regards,

        ZUdev




        Comment


        • #5
          Error "Extension does not exist." returns your Asterisk server, not EspoCRM. Please check the parameters in EspoCRM (Administration > VoIP Settings > Asterisk AMI):
          1. Version of your Asterisk server.
          2. Dialout Channel. Try "PJSIP/###" or "SIP/###".
          3. Dial Context. It should be the same as in your Asterisk server.
          Job Offers and Requests

          Comment


          • #6
            Yo, i recognized that too. That's right. The dial context was wrong.
            Arrrgh...now it is working.

            Thank you!

            Best regards.

            ZUdev



            Comment

            Working...
            X