Issue with IVR Service

[jflood3]

Hello,

I am new 3CX customer but happy with the product so far. I have a 3CXPSPRO on Version 10 with SP3 Build 21197 running on a Windows Server 2008 R2 in a VM environment.

I have configured all of my extensions and provisioned my phones. Everything was working fine until last week when no one could access their voicemail. When I checked the System Services everything i working fine but he system extension status shows my special menu as non registered. I backed up the setting, uninstalled the 3CX server, rebooted, re installed using IIS with the backup and the same issue. I did the same process again but tried using the Abyss Server option but this did not solve it.

I reviewed this with my reseller but he did not know what to do.

Below is the log from the IVR. I see an error from the service "Error! Module '3CX.com.OSBinet'. Error ID 303."

What does this error mean?



23:04:38.824|3420|.\3CXIvrService.cpp(893):IvrService, main(), Starting service, revision 9.0.20931.0
23:04:39.511|2480|.\3CXIvrService.cpp(600):IvrService, MainServiceProc::Run(), Loading configuration
23:04:39.511|2480|.\ConfigLoader.cpp(100):IvrService, ConfigLoader::Load(), C:\Program Files (x86)\3CX PhoneSystem\Bin\..\Data\Ivr\Cfg\3CXIvr.cfg
23:04:39.511|2480|.\3CXIvrService.cpp(608):IvrService, MainServiceProc::Run(), Initializing VxmlCreator
23:04:39.511|2480|.\IVR_Api.cpp(687):IvrDll, Ivr_SetLog(), External log has been set for IVR dll
23:04:39.511|2480|.\3CXIvrService.cpp(664):IvrService, MainServiceProc::Run(), Setting IVR parameters
23:04:39.511|2480|.\3CXIvrService.cpp(688):IvrService, MainServiceProc::Run(), Setting callback functions
23:04:39.511|2480|.\IVR_Api.cpp(311):IvrDll, IVRApi::Init(), Initializing, revision 9.0.20944.0
23:04:39.511|2480|.\IVR_Api.cpp(313):IvrDll, IVRApi::Init(), Initializing sockets
23:04:39.511|2480|.\IVR_Api.cpp(322):IvrDll, IVRApi::Init(), Initializing WWW library
23:04:39.511|2480|.\IVR_Api.cpp(361):IvrDll, IVRApi::Init(), Loading configuration
23:04:39.511|2480|.\IVR_Api.cpp(172):IvrDll, IVRApi::LoadConfiguration(), Using config file 'C:\Program Files (x86)\3CX PhoneSystem\Bin\..\Data\Ivr\Cfg\3CXIvr.cfg'
23:04:39.511|2480|.\IVR_Api.cpp(206):IvrDll, IVRApi::LoadConfiguration(), Default vxml document: 'http://localhost:5000/ivr/ivr_start.aspx'
23:04:39.511|2480|.\IVR_Api.cpp(232):IvrDll, IVRApi::LoadConfiguration(), Prompts URL: 'http://localhost:5000/ivr/prompts'
23:04:39.511|2480|.\IVR_Api.cpp(258):IvrDll, IVRApi::LoadConfiguration(), Using beep URI 'file:///C%7c/Program%20Files%20%28x86%29/3CX%20PhoneSystem/Bin/%2e%2e/Data/Ivr/Media/beep%2ewav'
23:04:39.511|2480|.\IVR_Api.cpp(280):IvrDll, IVRApi::LoadConfiguration(), Using error message URI 'file:///C%7c/Program%20Files%20%28x86%29/3CX%20PhoneSystem/Bin/%2e%2e/Data/Ivr/Media/error%2ewav'
23:04:39.511|2480|.\IVR_Api.cpp(298):IvrDll, IVRApi::LoadConfiguration(), Using inet cache dir 'C:\Program Files (x86)\3CX PhoneSystem\Bin\..\Data\Ivr\Cache'
23:04:39.511|2480|.\IVR_Api.cpp(367):IvrDll, IVRApi::Init(), Initializing VXI platform
23:04:39.511|2480|(0):Subtag VXIplatformInit. entering (log init complete): 0x01D734A0
23:04:39.621|2340|(0):Error! Module '3CX.com.OSBinet'. Error ID 303.
23:04:39.621|2480|(0):Subtag SBjsiInit. entering: 0x01D74BB0, 4000, 16384000, 131072, 100000
23:04:39.621|2480|(0):Subtag SBjsiInit. exiting: returned 0
23:04:39.636|2480|(0):Subtag VXIplatformInit. exiting: rc = 0
23:04:39.636|2480|.\IVR_Api.cpp(376):IvrDll, IVRApi::Init(), State machine initializing
23:04:39.636|2480|.\3CXIvrService.cpp(703):IvrService, MainServiceProc::Run(), Creating SL Agent
23:04:39.636|2480|.\3CXIvrService.cpp(720):IvrService, MainServiceProc::Run(), Connecting to PBX (SL Agent)
23:04:39.636|2480|.\3CXIvrService.cpp(728):IvrService, MainServiceProc::Run(), Service started
23:04:39.902|2408|.\SLAgent.cpp(290):IvrService, SLAgent::Run(), Reconnected to 127.0.0.1:5482 SL server
23:04:39.996|2408|.\SLAgent.cpp(146):IvrService, UpdateLines(), Component Settings:=====[0133EB30]
CS=62914560
IVRDIALINGTONE=
MCFS=5242880
MUSICONHOLDFILE=C:\ProgramData\3CX\Data\Ivr\Prompts\onhold.wav
PBXSIPPORT=5060
PROMPTPATH=C:\ProgramData\3CX\Data\Ivr\Prompts\Sets
RECVA=0
RECVIP=-1
RECVP=0
RTPFIRST=40610
RTPLAST=40866
SENDA=0
SENDIP=-1
SENDP=0
SIP=40600
SIPINTERFACE=10.0.1.39
=================================

23:04:50.657|2408|.\PbxMedia\IVREngine.cpp(1597):IvrService, IVREngine::RegisterLines(), Registering of lines...127.0.0.1:5060127.0.0.1:5060500[][][EndCall,IVRForward,MakeCall,PlayFile,RecordFile]
23:04:50.657|2408|.\PbxMedia\IVREngine.cpp(1613):IvrService, IVREngine::RegisterLines(), Registering SpeciaMenu:500
23:04:50.657|2408|.\PbxMedia\IVREngine.cpp(1701):IvrService, IVREngine::RegisterLines(), Registering EndCall helper
23:04:50.657|2408|.\PbxMedia\IVREngine.cpp(1701):IvrService, IVREngine::RegisterLines(), Registering IVRForward helper
23:04:50.657|2408|.\PbxMedia\IVREngine.cpp(1701):IvrService, IVREngine::RegisterLines(), Registering MakeCall helper
23:04:50.658|2408|.\PbxMedia\IVREngine.cpp(1701):IvrService, IVREngine::RegisterLines(), Registering PlayFile helper
23:04:50.658|2408|.\PbxMedia\IVREngine.cpp(1701):IvrService, IVREngine::RegisterLines(), Registering RecordFile helper
23:04:50.658|2408|.\PbxMedia\IVREngine.cpp(1723):IvrService, IVREngine::RegisterLines(), Registering of lines done.

 

[SY]

Please restart 3CX PhoneSystem service and then check "Server Activity log" page in management console.
Do you see any [EC......] messages in that log?
Please wait some time after restart (around one minute) and then post startup log here (3CXPhoneSystem.log and 3CXPhoneSystem.trace.log)

Thanks

 

[jflood3]

Hi SY,

Yes there are a few.

12:28:47.330 Currently active calls [none]
12:28:16.955 Currently active calls [none]
12:27:55.302 [CM504001]: Ext.808: new contact is registered. Contact(s): [sip:[email protected]:5488/808]
12:27:55.189 [CM504001]: Ext.803: new contact is registered. Contact(s): [sip:[email protected]:5488/803]
12:27:54.279 [CM504004]: Registration succeeded for: 10001@Nextiva - 7325632220
12:27:54.254 [CM504004]: Registration succeeded for: 10000@Nextiva
12:27:54.231 [CM504004]: Registration succeeded for: 10003@Nextiva - 7323571300
12:27:54.202 [CM504004]: Registration succeeded for: 10002@Nextiva - 7323571289
12:27:54.042 [CM504001]: Ext.704: new contact is registered. Contact(s): [sip:[email protected]:40300;rinstance=037ec3703a60346a/704]
12:27:54.041 [CM504001]: Ext.703: new contact is registered. Contact(s): [sip:[email protected]:40300;rinstance=2c406f1abaa503a1/703]
12:27:54.040 [CM504001]: Ext.702: new contact is registered. Contact(s): [sip:[email protected]:40300;rinstance=64365d67a0e611bc/702]
12:27:54.032 [CM504001]: Ext.701: new contact is registered. Contact(s): [sip:[email protected]:40300;rinstance=2117297a12f5a42e/701]
12:27:54.016 [CM504001]: Ext.700: new contact is registered. Contact(s): [sip:[email protected]:40300;rinstance=73b7e034d82f6939/700]
12:27:53.961 [CM504003]: Sent registration request for 10001@Nextiva - 7325632220
12:27:53.940 [CM504003]: Sent registration request for 10000@Nextiva
12:27:53.916 [CM504003]: Sent registration request for 10003@Nextiva - 7323571300
12:27:53.891 [CM504003]: Sent registration request for 10002@Nextiva - 7323571289
12:27:53.870 IP(s) added:[10.0.1.39]
12:27:53.529 [CM504001]: Ext.SP9: new contact is registered. Contact(s): [sip:[email protected]:40000;rinstance=ae94e4f920050941/SP9]
12:27:53.528 [CM504001]: Ext.SP8: new contact is registered. Contact(s): [sip:[email protected]:40000;rinstance=3c2d189d5ca5ebe5/SP8]
12:27:53.527 [CM504001]: Ext.SP7: new contact is registered. Contact(s): [sip:[email protected]:40000;rinstance=f4d27f1f350e5ff3/SP7]
12:27:53.524 [CM504001]: Ext.SP6: new contact is registered. Contact(s): [sip:[email protected]:40000;rinstance=fcfb24767ec93d3b/SP6]
12:27:53.523 [CM504001]: Ext.SP5: new contact is registered. Contact(s): [sip:[email protected]:40000;rinstance=94ffaa533af12b44/SP5]
12:27:53.521 [CM504001]: Ext.SP4: new contact is registered. Contact(s): [sip:[email protected]:40000;rinstance=a8fcd10e7fc0bf25/SP4]
12:27:53.520 [CM504001]: Ext.SP3: new contact is registered. Contact(s): [sip:[email protected]:40000;rinstance=b89a875b05257d1b/SP3]
12:27:53.518 [CM504001]: Ext.SP2: new contact is registered. Contact(s): [sip:[email protected]:40000;rinstance=1ec3a58b2b2fe04d/SP2]
12:27:53.504 [CM504001]: Ext.SP1: new contact is registered. Contact(s): [sip:[email protected]:40000;rinstance=b67152e2d125180f/SP1]
12:27:53.486 [CM504001]: Ext.SP0: new contact is registered. Contact(s): [sip:[email protected]:40000;rinstance=72cdcc6594d5d398/SP0]
12:27:53.461 [CM504001]: Ext.*1: new contact is registered. Contact(s): [sip:*[email protected]:40000;rinstance=3931b10932441116/*1]
12:27:53.444 [CM504001]: Ext.*0: new contact is registered. Contact(s): [sip:*[email protected]:40000;rinstance=5e93e333ec743897/*0]
12:27:53.426 [CM504001]: Ext.*777: new contact is registered. Contact(s): [sip:*[email protected]:40000;rinstance=f997def568be5703/*777]
12:27:50.319 [CM504001]: Ext.DialCode: new contact is registered. Contact(s): [sip[email protected]:5488/DialCode]
12:27:49.604 [CM504001]: Ext.230: new contact is registered. Contact(s): [sip:[email protected]:61003;rinstance=91924281d7502b52/230]
12:27:49.604 [CM504002]: Ext.230: a contact is unregistered. Contact(s): [sip:[email protected]:61003;rinstance=91924281d7502b52/230]
12:27:48.551 [EC100007]: External application is connected: application:s29:0/QueueManager local:127.0.0.1:5482 remote:127.0.0.1:49800
12:27:48.433 [CM504004]: Registration succeeded for: 10000@Nextiva
12:27:48.401 [CM504004]: Registration succeeded for: 10002@Nextiva - 7323571289
12:27:47.833 [CM504004]: Registration succeeded for: 10001@Nextiva - 7325632220
12:27:47.806 [CM504004]: Registration succeeded for: 10003@Nextiva - 7323571300
12:27:47.617 [EC200002]: Media server is connected: application:s29:0/MediaServer local:127.0.0.1:5482 remote:127.0.0.1:49797
12:27:47.592 [CM504008]: Fax Service: registered as sip:[email protected]:5060 with contact sip:[email protected]:5100;user=phone
12:27:47.313 [CM504003]: Sent registration request for 10002@Nextiva - 7323571289
12:27:47.285 [CM504003]: Sent registration request for 10000@Nextiva
12:27:47.255 [CM504003]: Sent registration request for 10003@Nextiva - 7323571300
12:27:47.102 [CM504003]: Sent registration request for 10001@Nextiva - 7325632220
12:27:46.736 [EC200005]: Parking Orbit server is connected: application:s29:0/3CXParkOrbit local:127.0.0.1:5482 remote:127.0.0.1:49796
12:27:46.736 Currently active calls [none]
12:27:45.206 [EC200004]: IVR server is connected: application:s29:0/IVRServer local:127.0.0.1:5482 remote:127.0.0.1:49795
12:27:44.862 [EC200006]: Conference server is connected: application:s29:0/3CXConferenceRoom local:127.0.0.1:5482 remote:127.0.0.1:49791
12:27:44.019 [CM506005]: Public IP=xxx.xxx.xxx.xxx is used for WAN communications through local interface with IP=10.0.1.39
12:27:44.019 Transport [ V4 10.0.1.39:5060 UDP target domain=unspecified mFlowKey=776 ] is joined to SIP multicast group
12:27:44.019 Failed to obtain short path name for [C:\ProgramData\3CX\Bin\Cert]
12:27:44.019 [CM501006]: Default Local IP address: [10.0.1.39]
12:27:44.019 [CM501002]: Version: 10.0.21196.0
12:27:44.019 [CM501001]: Start 3CX PhoneSystem Call Manager
12:27:44.019 [CM501009]: License Info: Loaded Succeeded
12:27:43.847 [EC200001]: Configuration server is connected: application:s29:5485/DBProvider local:127.0.0.1:49788 remote:127.0.0.1:5485
12:27:43.847 [CM501007]: *** Started Calls Controller thread ***