I put this on the back burner both due to time and frustration but yesterday and today I got back at it. I have tested it in every way possible, and the bottom line is I can NOT get a 9.1 control unit AND a 9.1 voicemail pro server to work together. If either the control unit or the voicemail pro is reverted to 9.0.8 or earlier(tried 9.0.8, 9.0.5, 8.1, 8.0 and no issues) it works perfectly fine. I have tried running voicemail pro on Windows XP, Windows Vista, Windows 7, and Windows Server 2003. I have tried running manager from a separate computer and on the voicemail pro computer (and setting the file writer and manager pc ip addresses) and nothing. I have tried completely removing voicemail pro and then fresh installing and Ive tried getting it working on 9.0.8 and upgrading and nothing. I have run out of ideas to try at this point.
I tried running the system monitor and it was next to useless. It shows the system hearbeat status and thats about it under normal circumstances with only the voicemail filters enabled. However, if you stop the voicemail pro service on the voicemail pro server, it does shoot a message:
8211622mS PRN: VMAIL - Down Server c0a82a08 Backlog=0
So clearly they are communicating on some level to know that the server is going down.
I was able to get a syslog server up and running and get some logs from voicemail pro. It looks like its getting a "bad request" and closing the connection. I copied a portion below, seems to just loop.
12-10-2015 13:49:19 System1.Debug 192.168.42.8 +13:50 2015 000 1 .ipovmpro | 0 VMP|TFTPTask[TFTPTask [SOCKSTask] port 0]: remip={192.168.42.1} destroying connection|
12-10-2015 13:49:19 System1.Debug 192.168.42.8 +13:50 2015 000 1 .ipovmpro | 0 VMP|TFTPDevIOTask[192.168.42.1]: destroying PBX={00E00708825D (MASTER:192.168.42.1 - SLAVE:0.0.0.0 [INACTIVE]) [9.1.3.0 build 120]} (valid) (current_pbx={041CFF50})|
12-10-2015 13:49:19 Daemon.Info 192.168.42.8 +13:50 2015 000 1 .ipovmpro | 0 VMP|TFTPWebSocket[TFTPTask [SOCKSTask] port 0]: Cannot Upgrade to WebSocket. Response from Server: [HTTP/1.1 400 Bad Request Connection: close Date: Thu, 10 Dec 2015 18:50:37 GMT Expires: Thu, 10 Dec 2015 18:51:37 GMT Cache-Control: private,max-age=60 Server: IPOffice/ Content-Type: text/plain Content-Length: 13 ]
12-10-2015 13:49:18 Daemon.Info 192.168.42.8 +13:50 2015 000 1 .ipovmpro | 0 VMP|TFTPWebSocket[TFTPTask [SOCKSTask] port 0]: remip={0.0.0.0} main running|
12-10-2015 13:49:18 System1.Info 192.168.42.8 +13:50 2015 000 1 .ipovmpro | 0 VMP|TFTPDevIOTask[192.168.42.1]: starting PBX={00E00708825D (MASTER:192.168.42.1 - SLAVE:0.0.0.0 [INACTIVE]) [9.1.3.0 build 120]} {valid} (current_pbx={046DD368})|
12-10-2015 13:49:18 System1.Debug 192.168.42.8 +13:50 2015 000 1 .ipovmpro | 0 VMP|TFTPTask[TFTPTask [SOCKSTask] port 0]: remip={0.0.0.0} starting connection|
12-10-2015 13:48:19 System1.Debug 192.168.42.8 +13:49 2015 000 1 .ipovmpro | 0 VMP|TFTPTask[TFTPTask [SOCKSTask] port 0]: remip={192.168.42.1} destroying connection|
12-10-2015 13:48:19 System1.Debug 192.168.42.8 +13:49 2015 000 1 .ipovmpro | 0 VMP|TFTPDevIOTask[192.168.42.1]: destroying PBX={00E00708825D (MASTER:192.168.42.1 - SLAVE:0.0.0.0 [INACTIVE]) [9.1.3.0 build 120]} (valid) (current_pbx={041CFF50})|
12-10-2015 13:48:19 Daemon.Info 192.168.42.8 +13:49 2015 000 1 .ipovmpro | 0 VMP|TFTPWebSocket[TFTPTask [SOCKSTask] port 0]: Cannot Upgrade to WebSocket. Response from Server: [HTTP/1.1 400 Bad Request Connection: close Date: Thu, 10 Dec 2015 18:49:37 GMT Expires: Thu, 10 Dec 2015 18:50:37 GMT Cache-Control: private,max-age=60 Server: IPOffice/ Content-Type: text/plain Content-Length: 13 ]
12-10-2015 13:48:18 Daemon.Info 192.168.42.8 +13:49 2015 000 1 .ipovmpro | 0 VMP|TFTPWebSocket[TFTPTask [SOCKSTask] port 0]: remip={0.0.0.0} main running|
12-10-2015 13:48:18 System1.Info 192.168.42.8 +13:49 2015 000 1 .ipovmpro | 0 VMP|TFTPDevIOTask[192.168.42.1]: starting PBX={00E00708825D (MASTER:192.168.42.1 - SLAVE:0.0.0.0 [INACTIVE]) [9.1.3.0 build 120]} {valid} (current_pbx={046DD368})|
12-10-2015 13:48:18 System1.Debug 192.168.42.8 +13:49 2015 000 1 .ipovmpro | 0 VMP|TFTPTask[TFTPTask [SOCKSTask] port 0]: remip={0.0.0.0} starting connection|
12-10-2015 13:47:19 System1.Debug 192.168.42.8 +13:48 2015 000 1 .ipovmpro | 0 VMP|TFTPTask[TFTPTask [SOCKSTask] port 0]: remip={192.168.42.1} destroying connection|
12-10-2015 13:47:19 System1.Debug 192.168.42.8 +13:48 2015 000 1 .ipovmpro | 0 VMP|TFTPDevIOTask[192.168.42.1]: destroying PBX={00E00708825D (MASTER:192.168.42.1 - SLAVE:0.0.0.0 [INACTIVE]) [9.1.3.0 build 120]} (valid) (current_pbx={041CFF50})|
12-10-2015 13:47:19 Daemon.Info 192.168.42.8 +13:48 2015 000 1 .ipovmpro | 0 VMP|TFTPWebSocket[TFTPTask [SOCKSTask] port 0]: Cannot Upgrade to WebSocket. Response from Server: [HTTP/1.1 400 Bad Request Connection: close Date: Thu, 10 Dec 2015 18:48:37 GMT Expires: Thu, 10 Dec 2015 18:49:37 GMT Cache-Control: private,max-age=60 Server: IPOffice/ Content-Type: text/plain Content-Length: 13 ]
12-10-2015 13:47:18 Daemon.Info 192.168.42.8 +13:48 2015 000 1 .ipovmpro | 0 VMP|TFTPWebSocket[TFTPTask [SOCKSTask] port 0]: remip={0.0.0.0} main running|
12-10-2015 13:47:18 System1.Info 192.168.42.8 +13:48 2015 000 1 .ipovmpro | 0 VMP|TFTPDevIOTask[192.168.42.1]: starting PBX={00E00708825D (MASTER:192.168.42.1 - SLAVE:0.0.0.0 [INACTIVE]) [9.1.3.0 build 120]} {valid} (current_pbx={046DD368})|
12-10-2015 13:47:18 System1.Debug 192.168.42.8 +13:48 2015 000 1 .ipovmpro | 0 VMP|TFTPTask[TFTPTask [SOCKSTask] port 0]: remip={0.0.0.0} starting connection|
12-10-2015 13:46:19 System1.Debug 192.168.42.8 +13:47 2015 000 1 .ipovmpro | 0 VMP|TFTPTask[TFTPTask [SOCKSTask] port 0]: remip={192.168.42.1} destroying connection|
12-10-2015 13:46:19 System1.Debug 192.168.42.8 +13:47 2015 000 1 .ipovmpro | 0 VMP|TFTPDevIOTask[192.168.42.1]: destroying PBX={00E00708825D (MASTER:192.168.42.1 - SLAVE:0.0.0.0 [INACTIVE]) [9.1.3.0 build 120]} (valid) (current_pbx={041CFF50})|
12-10-2015 13:46:19 Daemon.Info 192.168.42.8 +13:47 2015 000 1 .ipovmpro | 0 VMP|TFTPWebSocket[TFTPTask [SOCKSTask] port 0]: Cannot Upgrade to WebSocket. Response from Server: [HTTP/1.1 400 Bad Request Connection: close Date: Thu, 10 Dec 2015 18:47:36 GMT Expires: Thu, 10 Dec 2015 18:48:36 GMT Cache-Control: private,max-age=60 Server: IPOffice/ Content-Type: text/plain Content-Length: 13 ]
12-10-2015 13:46:18 Daemon.Info 192.168.42.8 +13:47 2015 000 1 .ipovmpro | 0 VMP|TFTPWebSocket[TFTPTask [SOCKSTask] port 0]: remip={0.0.0.0} main running|
12-10-2015 13:46:18 System1.Info 192.168.42.8 +13:47 2015 000 1 .ipovmpro | 0 VMP|TFTPDevIOTask[192.168.42.1]: starting PBX={00E00708825D (MASTER:192.168.42.1 - SLAVE:0.0.0.0 [INACTIVE]) [9.1.3.0 build 120]} {valid} (current_pbx={046DD368})|
12-10-2015 13:46:18 System1.Debug 192.168.42.8 +13:47 2015 000 1 .ipovmpro | 0 VMP|TFTPTask[TFTPTask [SOCKSTask] port 0]: remip={0.0.0.0} starting connection|
12-10-2015 13:45:19 System1.Debug 192.168.42.8 +13:46 2015 000 1 .ipovmpro | 0 VMP|TFTPTask[TFTPTask [SOCKSTask] port 0]: remip={192.168.42.1} destroying connection|
12-10-2015 13:45:19 System1.Debug 192.168.42.8 +13:46 2015 000 1 .ipovmpro | 0 VMP|TFTPDevIOTask[192.168.42.1]: destroying PBX={00E00708825D (MASTER:192.168.42.1 - SLAVE:0.0.0.0 [INACTIVE]) [9.1.3.0 build 120]} (valid) (current_pbx={041CFF50})|
12-10-2015 13:45:19 Daemon.Info 192.168.42.8 +13:46 2015 000 1 .ipovmpro | 0 VMP|TFTPWebSocket[TFTPTask [SOCKSTask] port 0]: Cannot Upgrade to WebSocket. Response from Server: [HTTP/1.1 400 Bad Request Connection: close Date: Thu, 10 Dec 2015 18:46:37 GMT Expires: Thu, 10 Dec 2015 18:47:37 GMT Cache-Control: private,max-age=60 Server: IPOffice/ Content-Type: text/plain Content-Length: 13 ]
12-10-2015 13:45:18 Daemon.Info 192.168.42.8 +13:46 2015 000 1 .ipovmpro | 0 VMP|TFTPWebSocket[TFTPTask [SOCKSTask] port 0]: remip={0.0.0.0} main running|
12-10-2015 13:45:18 System1.Info 192.168.42.8 +13:46 2015 000 1 .ipovmpro | 0 VMP|TFTPDevIOTask[192.168.42.1]: starting PBX={00E00708825D (MASTER:192.168.42.1 - SLAVE:0.0.0.0 [INACTIVE]) [9.1.3.0 build 120]} {valid} (current_pbx={046DD368})|
12-10-2015 13:45:18 System1.Debug 192.168.42.8 +13:46 2015 000 1 .ipovmpro | 0 VMP|TFTPTask[TFTPTask [SOCKSTask] port 0]: remip={0.0.0.0} starting connection|
12-10-2015 13:44:19 System1.Debug 192.168.42.8 +13:45 2015 000 1 .ipovmpro | 0 VMP|TFTPTask[TFTPTask [SOCKSTask] port 0]: remip={192.168.42.1} destroying connection|
12-10-2015 13:44:19 System1.Debug 192.168.42.8 +13:45 2015 000 1 .ipovmpro | 0 VMP|TFTPDevIOTask[192.168.42.1]: destroying PBX={00E00708825D (MASTER:192.168.42.1 - SLAVE:0.0.0.0 [INACTIVE]) [9.1.3.0 build 120]} (valid) (current_pbx={041CFF50})|
12-10-2015 13:44:19 Daemon.Info 192.168.42.8 +13:45 2015 000 1 .ipovmpro | 0 VMP|TFTPWebSocket[TFTPTask [SOCKSTask] port 0]: Cannot Upgrade to WebSocket. Response from Server: [HTTP/1.1 400 Bad Request Connection: close Date: Thu, 10 Dec 2015 18:45:37 GMT Expires: Thu, 10 Dec 2015 18:46:37 GMT Cache-Control: private,max-age=60 Server: IPOffice/ Content-Type: text/plain Content-Length: 13 ]
12-10-2015 13:44:18 Daemon.Info 192.168.42.8 +13:45 2015 000 1 .ipovmpro | 0 VMP|TFTPWebSocket[TFTPTask [SOCKSTask] port 0]: remip={0.0.0.0} main running|
12-10-2015 13:44:18 System1.Info 192.168.42.8 +13:45 2015 000 1 .ipovmpro | 0 VMP|TFTPDevIOTask[192.168.42.1]: starting PBX={00E00708825D (MASTER:192.168.42.1 - SLAVE:0.0.0.0 [INACTIVE]) [9.1.3.0 build 120]} {valid} (current_pbx={046DD368})|
12-10-2015 13:44:18 System1.Debug 192.168.42.8 +13:45 2015 000 1 .ipovmpro | 0 VMP|TFTPTask[TFTPTask [SOCKSTask] port 0]: remip={0.0.0.0} starting connection|