Error Voip Pop Up after Update

[Sebastian]

Hello,

after updating from Version 6.1.9 to 7.07 I don't get any pop ups from outgoing and ingoing calls any more.

I'm using Voip-Integration 1.17.3 and have a starface server V7.1 running

I looked up the log file and made a screenshot

I checked the user voip settings and made a conncetion test, all good!

Please help.

[Katia]

Hi Sebastian,

We will investigate this issue and let you know as soon as possible. Thanks!

[Sebastian]

Thanks for the heaps up

[tarasm]

This is fixed in VoIP Integration 1.18.0

[Sebastian]

I installed the VoIP Update 1.18.0.
The pop up from outgoing calls are working, but incoming calls only from time to time.

One example:
I made an incoming call from my mobile phone nothing happens (no pop up), but as soon as I make an outgoing call to that mobile number, from then on the incoming calls are recognized correctly.

I have no clue why it only works sporadically. Does anyone have a similar problem and can help me?

[Sebastian]

Can someone tell me where I have to insert the Starface CronJob
(* * * * * cd /var/www/html/espocrm; /usr/bin/php -f command.php voip Starface > /dev/null 2>&1 ).

Do i have to put it in root or in user folder?

At the moment I have it in user folder, but it works only after I have trigered the job by doing an outgoing call. After I made the outgoing call the job is alive for appr.10 min and incoming calls are recognized and displayed, but then the job is falling asleep again.

Can I somehow make a test whether this job is running or not?

[Vadym]

Hi Sebastian,

Open a terminal and run this command:

crontab -e -u WEBSERVER_USER

Add this line to crontab and save:
* * * * * cd /var/www/html/espocrm; /usr/bin/php -f command.php voip Starface > /dev/null 2>&1
https://docs.espocrm.com/administrat...ing-up-crontab

To print all available jobs run:

bin/command app-info --jobs

https://docs.espocrm.com/administrat...anually-in-cli

[Sebastian]

Hi Vadym,
thanks for your quick response.

I did as you said, but the line was already in the crontab. Anyway, I put it up once more, but the same result. No incoming call recognition.

Here are all available jobs which are runnning:

[Sebastian]

I uncommented the starface crontab line and the software behaves exactly the same. As it seems, the line has no effect whatsoever.

Any help is much appreciated

Here are the espo and apache logs:

[2021-11-11 10:26:01] ERROR: Starface: Cannot logout offline user [10], details: [0] . [] []
...skipping...
[2021-11-11 10:26:01] ERROR: Starface: Cannot logout offline user [10], details: [0] . [] []


root@espocrm .../data/logs# less espo-2021-11-10.log
[2021-11-10 07:13:19] ERROR: (403) EntryPoint Attachment: Not allowed type 'image/bmp'.; GET /; line: 91, file: /var/www/espocrm/application/Espo/EntryPoints/Attachment.php [] []
[2021-11-10 07:13:50] ERROR: (403) EntryPoint Attachment: Not allowed type 'image/bmp'.; GET /; line: 91, file: /var/www/espocrm/application/Espo/EntryPoints/Attachment.php [] []
[2021-11-10 07:14:02] ERROR: (403) EntryPoint Attachment: Not allowed type 'image/bmp'.; GET /; line: 91, file: /var/www/espocrm/application/Espo/EntryPoints/Attachment.php [] []
[2021-11-10 07:14:05] ERROR: (403) EntryPoint Attachment: Not allowed type 'image/bmp'.; GET /; line: 91, file: /var/www/espocrm/application/Espo/EntryPoints/Attachment.php [] []
[2021-11-10 07:14:39] ERROR: (403) EntryPoint Attachment: Not allowed type 'image/bmp'.; GET /; line: 91, file: /var/www/espocrm/application/Espo/EntryPoints/Attachment.php [] []
[2021-11-10 10:16:02] ERROR: Email sending error: Requested action aborted: local error in processing [] []
[2021-11-10 10:16:02] ERROR: Email sending:Email sending error.; 500 [] []
[2021-11-10 10:17:07] ERROR: Email sending error: Requested action aborted: local error in processing [] []
[2021-11-10 10:17:07] ERROR: Email sending:Email sending error.; 500 [] []
[2021-11-10 10:18:44] ERROR: Email sending error: Requested action aborted: local error in processing [] []
[2021-11-10 10:18:44] ERROR: Email sending:Email sending error.; 500 [] []
[2021-11-10 10:23:01] WARNING: Cron is not run because it's disabled with 'cronDisabled' param. [] []
[2021-11-10 10:24:01] WARNING: Cron is not run because it's disabled with 'cronDisabled' param. [] []
[2021-11-10 10:26:01] WARNING: Cron is not run because it's disabled with 'cronDisabled' param. [] []
[2021-11-10 10:27:01] WARNING: Cron is not run because it's disabled with 'cronDisabled' param. [] []
[2021-11-10 10:58:58] ERROR: Email sending error: Requested action aborted: local error in processing [] []
[2021-11-10 10:58:58] ERROR: Email sending:Email sending error.; 500 [] []
[2021-11-10 12:25:12] ERROR: JobManager: Failed job running, job '618bb8f1a2402ec0f'. Job CheckEmailAccounts 61497fe7871615f0e: [0] cannot connect to host; error = stream_socket_client(): unable to connect to ssl://imap.1und1.de:993 (Unknown error) (errno = 0 ); at /var/www/espocrm/application/Espo/Classes/Jobs/CheckEmailAccounts.php:78. [] []
[2021-11-10 12:25:12] ERROR: JobManager: Failed job running, job '618bb8f1a1cfc188a'. Job CheckEmailAccounts 610a36936fbd8a531: [0] cannot connect to host; error = stream_socket_client(): unable to connect to ssl://imap.1und1.de:993 (Unknown error) (errno = 0 ); at /var/www/espocrm/application/Espo/Classes/Jobs/CheckEmailAccounts.php:78. [] []
[2021-11-10 12:25:12] ERROR: JobManager: Failed job running, job '618bb8f1a156a2a6e'. Job CheckEmailAccounts 60f947b53ce16b5ee: [0] cannot connect to host; error = stream_socket_client(): unable to connect to ssl://imap.1und1.de:993 (Unknown error) (errno = 0 ); at /var/www/espocrm/application/Espo/Classes/Jobs/CheckEmailAccounts.php:78. [] []
[2021-11-10 12:26:11] ERROR: JobManager: Failed job running, job '618bb8f1a2402ec0f'. Job CheckEmailAccounts 61497fe7871615f0e: [0] cannot connect to host; error = stream_socket_client(): unable to connect to ssl://imap.1und1.de:993 (Unknown error) (errno = 0 ); at /var/www/espocrm/application/Espo/Classes/Jobs/CheckEmailAccounts.php:78. [] []
[2021-11-10 12:26:11] ERROR: JobManager: Failed job running, job '618bb8f1a156a2a6e'. Job CheckEmailAccounts 60f947b53ce16b5ee: [0] cannot connect to host; error = stream_socket_client(): unable to connect to ssl://imap.1und1.de:993 (Unknown error) (errno = 0 ); at /var/www/espocrm/application/Espo/Classes/Jobs/CheckEmailAccounts.php:78. [] []
[2021-11-10 12:26:11] ERROR: JobManager: Failed job running, job '618bb8f1a1cfc188a'. Job CheckEmailAccounts 610a36936fbd8a531: [0] cannot connect to host; error = stream_socket_client(): unable to connect to ssl://imap.1und1.de:993 (Unknown error) (errno = 0 ); at /var/www/espocrm/application/Espo/Classes/Jobs/CheckEmailAccounts.php:78. [] []
[2021-11-10 12:30:11] ERROR: JobManager: Failed job running, job '618bba1e1157e7d9d'. Job CheckEmailAccounts 60f947b53ce16b5ee: [0] cannot connect to host; error = stream_socket_client(): unable to connect to ssl://imap.1und1.de:993 (Unknown error) (errno = 0 ); at /var/www/espocrm/application/Esespo-2021-11-10.log


root@espocrm .../log/apache2# less error.log
[Fri Nov 12 00:00:31.532158 2021] [ssl:warn] [pid 329] AH01909: localhost:443:0 server certificate does NOT include an ID which matches the server name
[Fri Nov 12 00:00:31.532675 2021] [ssl:warn] [pid 329] AH01909: localhost:12322:0 server certificate does NOT include an ID which matches the server name
[Fri Nov 12 00:00:31.533015 2021] [mpm_prefork:notice] [pid 329] AH00163: Apache/2.4.38 (Debian) OpenSSL/1.1.1d configured -- resuming normal operations
[Fri Nov 12 00:00:31.533019 2021] [core:notice] [pid 329] AH00094: Command line: '/usr/sbin/apache2'
[Fri Nov 12 14:18:37.773059 2021] [mpm_prefork:notice] [pid 329] AH00169: caught SIGTERM, shutting down
[Fri Nov 12 14:19:28.794631 2021] [ssl:warn] [pid 263] AH01909: localhost:443:0 server certificate does NOT include an ID which matches the server name
[Fri Nov 12 14:19:28.795001 2021] [ssl:warn] [pid 263] AH01909: localhost:12322:0 server certificate does NOT include an ID which matches the server name
[Fri Nov 12 14:19:28.883582 2021] [ssl:warn] [pid 334] AH01909: localhost:443:0 server certificate does NOT include an ID which matches the server name
[Fri Nov 12 14:19:28.883844 2021] [ssl:warn] [pid 334] AH01909: localhost:12322:0 server certificate does NOT include an ID which matches the server name
[Fri Nov 12 14:19:28.886396 2021] [mpm_prefork:notice] [pid 334] AH00163: Apache/2.4.38 (Debian) OpenSSL/1.1.1d configured -- resuming normal operations
[Fri Nov 12 14:19:28.886407 2021] [core:notice] [pid 334] AH00094: Command line: '/usr/sbin/apache2'
error.log (END)

[Vadym]

Hi Sebastian,

Check 'cronDisabled' parameter in the data/config.php file:
https://docs.espocrm.com/administrat...fig-parameters

[Sebastian]

Hi Vadym,

thanks for sticking with me.

The parameter was set to "false". The entry in the log was from an update I ran, hence disabled cron jobs, as recommende. But I enabeld it afterwards.

I made some more call tests and set logging to debug.
This is what got my attention: INFO: JobManager: Skip job processing. Too frequent execution. [] []

I attached the complete log.

The incoming calls worked again for approx. 2 min after I made an outgoing call. After this time, the incoming calls disappear. Then the info on the log shows up.

[Vadym]

Hi Sebastian,

Check server error log file to investigate your issue in more detail:

https://docs.espocrm.com/administration/troubleshooting/#apache-error-log

[Sebastian]

Hi Vadym,

I made another espo log file in debug modus.

[Sebastian]

Problem solved with the latest Voip AddOn Update V1.20.3

Starface: Fixed timeout issue when web-socket enabled.

Thanks again for your help and support