Solved 3CX Windows App - Auto Dialling on Resume from Hibernation

Status
Not open for further replies.

TitleRequired

Bronze Partner
Advanced Certified
Joined
Mar 18, 2020
Messages
55
Reaction score
10
Posting this more for observation than anything else. I expect it is a symptom of something amiss on my laptop rather than the app.

On several occasions now, when resuming from hibernation the 3CX Windows App has auto dialled a number.

So far they have been internal contacts, and interestingly were not the last number i had dialled, but i had dialled the number recently.

For clarification, lets say at 3pm I dialled 233. Then dialled some external numbers and at 5pm put my computer into hibernation. At 7am the next morning I turn on my laptop, it goes to the login screen and it dials 233.

I use a USB headset, headset integration is disabled in the App settings.

App Version is 18.3.33.0
Windows 10 Enterprise 19043.1165
Headset Microsoft Life Chat LX-6000
 
Hi!

So, when you log in the next morning, once the desktop appears, you actually see the dialer there and then dialing a number?
Of you see in the hostory that it was dialed during some time while it was in hibernation?
 
Yes, and if put my headset on i can continue the conversation with the bewildered recipient!

No evidence of it dialling whilst hibernated.
 
This is very weird. OK, what happens in the following scenarios?
  • If you Lock (not hibernate), then Unlock, does the same happen?
  • When you hibernate, is the Desktop App visible? What happens if you press the X and it exists only in the Notification Area?
  • From your description, it seems you have a password-protected Windows. If you temporarily disable the password and it doesn't require one to resume from hibernation, does it do the same?
 
The desktop app and dialler windows would be open, visible on the taskbar but not the active window.

Will have to test and come back to you.
 
Didn't happen this morning. I kept the app open but the dialler panel was closed.

Will report again Monday.
 
  • Like
Reactions: NickD_3CX
Turned on my laptop this morning, logged in. Started opening some bits, and in my headset i got 'record your message'. Flipped over to the dialler and it had dialled 233 again. Interestingly this was not the last internal number i had dialled.

If i bring the dialler up, and click the call icon, it auto fills 233.

Are there any logs or anything i can pull to help troubleshoot this?
 
You can find the logs of the Desktop App in this file:
%appdata%\3CXDesktopApp\logs\main.log

After you resume from hibernation and you see that a call was made, don't do any other actions with the Desktop app and just copy this file onto your desktop.
Then open it and check what the last 10-20 lines say, maybe you could even post them here.
 
This is the log from when i shutdown on Friday, to just after i posted on Sunday. In the call log it shows i dialled 233 at 09:55:
Code:
[2021-09-17 18:00:53.211] [warn]  (provisioning-process.class)     provisioning failed, reconnects in 5000 ms
[2021-09-19 09:54:47.754] [info]  (update-interval-hook)           auto updater check for updates from https://server.3cx.co.uk:5001/electron/update/win32/18.3.33
[2021-09-19 09:54:53.441] [warn]  (provisioning-process.class)     {
  name: 'ElectronFetchError',
  message: 'request to https://server.3cx.co.uk:5001/ failed, reason: net::ERR_NETWORK_CHANGED | request to https://server.3cx.co.uk:5001/ failed, reason: net::ERR_NETWORK_CHANGED',
  type: 'system',
  code: 'ERR_NETWORK_CHANGED'
}
[2021-09-19 09:54:53.459] [warn]  (provisioning-process.class)     provisioning failed, reconnects in 5000 ms
[2021-09-19 09:54:59.130] [info]  (provisioning-process.class)     race winner https://server.3cx.co.uk:5001/provisioning/4m7s1hu13fnf3i/TcxProvFiles/3cxProv_9LE0IBQD5U236.xml
[2021-09-19 09:54:59.182] [info]  (remote-file-reader.func)        remoteFileReader https://server.3cx.co.uk:5001/provisioning/4m7s1hu13fnf3i/TcxProvFiles/3cxProv_9LE0IBQD5U236.xml
[2021-09-19 09:54:59.561] [info]  (provisioning-process.class)     update session was forced
[2021-09-19 09:54:59.579] [info]  (cleanup-session.func)           cleanupSession
[2021-09-19 09:54:59.629] [info]  (provisioning-process.class)     provisioningResult {
  resultType: 'renewed',
  domain: 'https://server.3cx.co.uk:5001',
  username: '236'
}
[2021-09-19 09:55:05.872] [info]  (status-ipc-hook)                ipcMain on status [ '3', 'info' ]
[2021-09-19 09:55:06.628] [info]  (status-ipc-hook)                badge overlay update [ '3', 'info' ]
[2021-09-19 09:55:06.704] [info]  (create-main-window)             tray icon update - type: info
[2021-09-19 10:20:08.431] [info]  (status-ipc-hook)                ipcMain on status [ '!', 'error' ]


I just went onto the server to see if the call log showed a more specific time, and it turns out i dialled 233 again this morning.
Code:
[2021-09-21 09:55:37.013] [info]  (update-interval-hook)           auto updater check for updates from https://server.3cx.co.uk:5001/electron/update/win32/18.3.33
[2021-09-21 09:55:37.466] [warn]  (provisioning-process.class)     {
  name: 'ElectronFetchError',
  message: 'request to https://server.3cx.co.uk:5001/ failed, reason: net::ERR_NETWORK_IO_SUSPENDED | request to https://server.3cx.co.uk:5001/ failed, reason: net::ERR_NETWORK_IO_SUSPENDED',
  type: 'system',
  code: 'ERR_NETWORK_IO_SUSPENDED'
}
[2021-09-21 09:55:37.474] [warn]  (provisioning-process.class)     provisioning failed, reconnects in 5000 ms
[2021-09-21 09:55:43.874] [warn]  (provisioning-process.class)     {
  name: 'ElectronFetchError',
  message: 'request to https://server.3cx.co.uk:5001/ failed, reason: net::ERR_NETWORK_CHANGED | request to https://server.3cx.co.uk:5001/ failed, reason: net::ERR_NETWORK_CHANGED',
  type: 'system',
  code: 'ERR_NETWORK_CHANGED'
}
[2021-09-21 09:55:43.882] [warn]  (provisioning-process.class)     provisioning failed, reconnects in 5000 ms
[2021-09-21 09:55:49.034] [info]  (provisioning-process.class)     race winner https://server.3cx.co.uk:5001/provisioning/4m7s1hu13fnf3i/TcxProvFiles/3cxProv_9LE0IBQD5U236.xml
[2021-09-21 09:55:49.133] [info]  (remote-file-reader.func)        remoteFileReader https://server.3cx.co.uk:5001/provisioning/4m7s1hu13fnf3i/TcxProvFiles/3cxProv_9LE0IBQD5U236.xml
[2021-09-21 09:55:49.311] [info]  (provisioning-process.class)     update session was forced
[2021-09-21 09:55:49.319] [info]  (cleanup-session.func)           cleanupSession
[2021-09-21 09:55:49.361] [info]  (provisioning-process.class)     provisioningResult {
  resultType: 'renewed',
  domain: 'https://server.3cx.co.uk:5001',
  username: '236'
}
[2021-09-21 09:55:55.531] [info]  (status-ipc-hook)                ipcMain on status [ '7', 'info' ]
[2021-09-21 09:55:55.537] [info]  (status-ipc-hook)                badge overlay update [ '7', 'info' ]
[2021-09-21 09:55:55.544] [info]  (create-main-window)             tray icon update - type: info

I had no idea that called was placed, and if i hadnt seen it on the server log i wouldnt have known.
 
If you exit the Desktop app before hibernation, does it still happen?
Also, have you tried temporarily provisioning with a different extension number, just as a test?
 
Not done either, although i am fairly sure if i don't leave the dialler window open it doesn't happen. Will confirm.

Will also try a different extension.
 
  • Like
Reactions: NickD_3CX
App was closed at hibernation last night.

Opened App this morning, and although i didnt hear anything in my headset my extension did call 233 again.

Code:
[2021-09-21 18:28:52.282] [warn]  (provisioning-process.class)     provisioning failed, reconnects in 5000 ms
[2021-09-22 09:55:50.847] [info]  (update-interval-hook)           auto updater check for updates from https://server.3cx.co.uk:5001/electron/update/win32/18.3.33
[2021-09-22 09:55:57.282] [warn]  (provisioning-process.class)     {
  name: 'ElectronFetchError',
  message: 'request to https://server.3cx.co.uk:5001/ failed, reason: net::ERR_NETWORK_CHANGED | request to https://server.3cx.co.uk:5001/ failed, reason: net::ERR_NETWORK_CHANGED',
  type: 'system',
  code: 'ERR_NETWORK_CHANGED'
}
[2021-09-22 09:55:57.483] [warn]  (provisioning-process.class)     provisioning failed, reconnects in 5000 ms
[2021-09-22 09:56:03.411] [info]  (provisioning-process.class)     race winner https://server.3cx.co.uk:5001/provisioning/4m7s1hu13fnf3i/TcxProvFiles/3cxProv_9LE0IBQD5U236.xml
[2021-09-22 09:56:03.452] [info]  (remote-file-reader.func)        remoteFileReader https://server.3cx.co.uk:5001/provisioning/4m7s1hu13fnf3i/TcxProvFiles/3cxProv_9LE0IBQD5U236.xml
[2021-09-22 09:56:04.126] [info]  (provisioning-process.class)     update session was forced
[2021-09-22 09:56:04.162] [info]  (cleanup-session.func)           cleanupSession
[2021-09-22 09:56:04.269] [info]  (provisioning-process.class)     provisioningResult {
  resultType: 'renewed',
  domain: 'https://server.3cx.co.uk:5001',
  username: '236'
}
[2021-09-22 09:56:53.934] [info]  (status-ipc-hook)                ipcMain on status [ '3', 'info' ]
[2021-09-22 09:56:53.939] [info]  (status-ipc-hook)                badge overlay update [ '3', 'info' ]
[2021-09-22 09:56:53.947] [info]  (create-main-window)             tray icon update - type: info


Call placed at 09:56 for a duration of 9 seconds.
 
OK, I am starting to see a pattern here. From what I understand, each time you resume from hibernation, the same extension is being dialed right (ext 233)?

Did you also try the other 2 things we talked about:
  • If you exit the Desktop app before hibernation, does it still happen?
  • If you temporarily provision with a different extension number (create a new one for a test), and you repeat the same thing with hibernation, does the same happen?
 
Just did a few test hibernations, with the app open, unable to reproduce. Very frustrating for Mr 233.
 
OK, I am starting to see a pattern here. From what I understand, each time you resume from hibernation, the same extension is being dialed right (ext 233)?

Did you also try the other 2 things we talked about:
  • If you exit the Desktop app before hibernation, does it still happen?
  • If you temporarily provision with a different extension number (create a new one for a test), and you repeat the same thing with hibernation, does the same happen?
I closed the app, or i thought i did it may have been running in the system tray. Will try to reprovision with a new extension before i close down this evening.
 
  • Like
Reactions: NickD_3CX
I have been using a different (brand new) extension since my last message. No issues, until this morning.

Resumed from hibernation, my laptop showed 'no network' for a few moments, the 3cx app also showed network disconnected.

As soon as the wifi came online, the phone dialled 235, which was the last internal extension i dialled yesterday.

Excerpt from main.log:

Code:
[2021-09-28 07:33:06.960] [info]  (update-interval-hook)           auto updater check for updates from https://server.3cx.co.uk:5001/electron/update/win32/18.3.33
[2021-09-28 07:33:29.850] [warn]  (provisioning-process.class)     {
  name: 'ElectronFetchError',
  message: 'request to https://server.3cx.co.uk:5001/ failed, reason: net::ERR_NETWORK_IO_SUSPENDED | request to https://172.31.7.163:5001/ failed, reason: net::ERR_NETWORK_CHANGED',
  type: 'system',
  code: 'ERR_NETWORK_IO_SUSPENDED'
}
[2021-09-28 07:33:30.621] [warn]  (provisioning-process.class)     provisioning failed, reconnects in 5000 ms
[2021-09-28 07:33:37.624] [info]  (provisioning-process.class)     race winner https://server.3cx.co.uk:5001/provisioning/4m7s1hu13fnf3i/TcxProvFiles/3cxProv_O0FHIE87SC237.xml
[2021-09-28 07:33:37.687] [info]  (remote-file-reader.func)        remoteFileReader https://server.3cx.co.uk:5001/provisioning/4m7s1hu13fnf3i/TcxProvFiles/3cxProv_O0FHIE87SC237.xml
[2021-09-28 07:33:39.517] [info]  (provisioning-process.class)     update session was forced
[2021-09-28 07:33:39.583] [info]  (cleanup-session.func)           cleanupSession
[2021-09-28 07:33:41.639] [warn]  (provisioning-process.class)     FetchError: request to https://server.3cx.co.uk:5001/webclient/api/Login failed, reason: net::ERR_NAME_NOT_RESOLVED
    at ClientRequest.eval (webpack://3CXDesktopApp/./electron/main.ts_+_130_modules?:3518:14)
    at ClientRequest.emit (events.js:315:20)
    at ClientRequest._die (electron/js2c/browser_init.js:105:8391)
    at SimpleURLLoaderWrapper.<anonymous> (electron/js2c/browser_init.js:105:7128)
    at SimpleURLLoaderWrapper.emit (events.js:315:20)
[2021-09-28 07:33:41.671] [warn]  (provisioning-process.class)     provisioning failed, reconnects in 5000 ms
[2021-09-28 07:33:46.745] [warn]  (provisioning-process.class)     {
  name: 'ElectronFetchError',
  message: 'request to https://server.3cx.co.uk:5001/ failed, reason: net::ERR_NAME_NOT_RESOLVED | request to https://172.31.7.163:5001/ failed, reason: net::ERR_ADDRESS_UNREACHABLE',
  type: 'system',
  code: 'ENOTFOUND'
}
[2021-09-28 07:33:46.768] [warn]  (provisioning-process.class)     provisioning failed, reconnects in 5000 ms
[2021-09-28 07:34:12.295] [info]  (provisioning-process.class)     race winner https://server.3cx.co.uk:5001/provisioning/4m7s1hu13fnf3i/TcxProvFiles/3cxProv_O0FHIE87SC237.xml
[2021-09-28 07:34:12.303] [info]  (remote-file-reader.func)        remoteFileReader https://server.3cx.co.uk:5001/provisioning/4m7s1hu13fnf3i/TcxProvFiles/3cxProv_O0FHIE87SC237.xml
[2021-09-28 07:34:12.525] [info]  (provisioning-process.class)     update session was forced
[2021-09-28 07:34:12.602] [info]  (cleanup-session.func)           cleanupSession
[2021-09-28 07:34:12.694] [info]  (provisioning-process.class)     provisioningResult {
  resultType: 'renewed',
  domain: 'https://server.3cx.co.uk:5001',
  username: '237'
}
[2021-09-28 07:34:15.961] [info]  (status-ipc-hook)                ipcMain on status [ '5', 'info' ]
[2021-09-28 07:34:16.045] [info]  (status-ipc-hook)                badge overlay update [ '5', 'info' ]
[2021-09-28 07:34:16.127] [info]  (create-main-window)             tray icon update - type: info

I saw the dialler start the call, and hit hang up. There was no number filled, it showed 'Enter Name or Number' before it turned to the call in progress display.

Any other logs i can provide?
 
I have to say this is extremely strange. I have made numerous attempts to try and somehow replicate but this with no success.

Is there perhaps anything installed on this PC that may be somehow causing this or interfering with the 3CX Desktop app? Any software you can think of or particular configuration with this PC that may be the culprit?

This might sound like nonsense but I think eliminating the possibility of this occurring due to accidental user input might be a good idea so that we can at least narrow it down. I mean, a simple act such as double tapping enter while the dialer is in focus will have the app redial so you never know.

Could you perhaps start up Windows Step recorder right before you hibernate the PC so that, next time it happens, you can trace all input and confirm it is unrelated to this behavior?
 
I have had processmon and wireshark running on resume from hibernation, but so far i havent captured an occurrence. Happy to accept it is my error, but i am pretty sure i am not pressing anything to initiate dialling the call.

I will try and setup steps recorder as well, hopefully i can catch it.
 
  • Like
Reactions: ChrisC_3CX
Not willing to steal your topic, but we (i) had the same: colleague called me automatically first thing in the morning as he turned his PC on.

Unable to reproduce now, as his laptop has been reinstalled (and that solved the case), but just wanted to let you know there are more people out there :)
 
AHa i knew it wasnt just me :p
 
Status
Not open for further replies.

Forum statistics

Threads
111,974
Messages
590,081
Members
164,899
Latest member
mazet