Vg Not Calling

[mediasol]

On the 24 Aug I loaded in about 2000 NSW numbers for immediate send on "Average" priority and it didn't start so I restarted the system and the calls started.

 

Then, just now, I loaded in about 700 WA numbers for immediate send on "Low" priority (so that they would go out later today) and the system stopped making any calls.

 

So, I re-started the system but it's not making any calls!!!

 

On the 25 Aug I had tried stopping and starting the outbound call loader a couple of times but it didn't work.

 

Then I tried it again just now and it's dialling again!

 

Now the system has about 800 calls stuck on the database with the 24 Aug and 25 aug date. And it not dailing.

 

What I'm doing worng?

 

What I also find is that if ever I set a VB to start at some time in future, it doesn't start unless I reboot.

 

I have inlcuded the log files for the 24 & 25 plus the 3 xml files.

0824_0752_vgDialListLoad.zip

0824_0721_vgEngine.zip

0824_0757_vgEngine.zip

0824_0953_vgEngine.zip

0824_1117_vgEngine.zip

0824_1154_vgEngine.zip

0824_1343_vgEngine.zip

0824_1534_vgEngine.zip

0824_1726_vgEngine.zip

0824_2111_vgEngine.zip

0824_ktTel.zip

0825_0701_vgEngine.zip

0825_0855_vgEngine.zip

0825_1053_vgEngine.zip

0825_1448_vgEngine.zip

0825_1253_vgEngine.zip

0825_1750_vgEngine.zip

ZZZmbscall_09_08_25_17_49.zip

[SupportTeam]

On the 24 Aug I loaded in about 2000 NSW numbers for immediate send on "Average" priority and it didn't start so I restarted the system and the calls started.

Can you please advise the time this happened so we can look in the right place in the log files.

 

Then, just now, I loaded in about 700 WA numbers for immediate send on "Low" priority (so that they would go out later today) and the system stopped making any calls.

Can you please advise the time this happened so we can look in the right place in the log files.

 

So, I re-started the system but it's not making any calls!!!

What time was the restart made?

 

Could you please .ZIP up and email the database (if yoa re using VistaDB then it's in the \data\ sundirectory) to support@voiceguide.com and include a link to this support forum thread in the email.

 

[mediasol]

2000 nsw list was started at approx 24 aug 7:54am

 

700 wa list was started at approx 24 aug 11:08

 

 

The restart was done many times over the 2 days.

 

We use mysql database, can you give me instruction on how it send to you.

 

[SupportTeam]

In MySql adminstrator select Backup option and create a new backup project. Add the vgdb database to the project and then press "Execute Backup Now" button. Then .ZIP up created file and post it here or email it to support@voiceguide.com along with the link to this support forum thread.

[mediasol]

Sorry the data was deleted, I can not get the data for you.

 

Is there any thing in the log file, that we are doing wrong?

[SupportTeam]

Looking at vgEngine traces, we can see at 07:54 the system started loading the file Outdial_09-08-24-07-54.xml :

 

075422.328 4 0 OutDial load from [C:\Program Files\VoiceGuide\data\Outdial_09-08-24-07-54.xml]

 

XML files take longer to load into the database. They do not use the streamlined loading that the Telephone Number Loader uses.

 

You can see in the 0834_0721_vgEngine log the phone numbers being loaded, and the timestamps in the log will let you see how long it takes to insert each call data into the tables of the databse that you are using. It takes between 50and 100ms for each record to be fully inserted into this database so 2000 records would take a bit over 2 minutes to insert. The dialing would not start untill all of the numbers were inserted.

 

We can see in log that less then 2 minutes after starting of loading the VoiceGuide service was stopped - which prevented completion of loading of all of the numbers.

 

When service was restarted the numbers that were loaded would have been dialed.

 

at 11:08 on 24th we can see in that VoiceGuide starts to load data into your database but soon after starting the database stops responding.

 

110853.703 21 0 OutDial load from [C:\Program Files\VoiceGuide\data\Outdial_09-08-24-11-08.xml]

110853.703 21 DialListLoadFileXML ReadAllText call

110853.734 21 0 Db_Insert_PrepareCmdParams AddParamToCmd calls

110853.734 21 0 Db_Insert_PrepareCmdParams cmdCQ.Parameters[...] set calls.

110853.734 21 0 Db_Insert_PrepareCmdParams

110853.734 21 0 dial Db_Insert_CallQueAndPortList CallQue insert call [18310862506583]

110853.734 21 0 dial cmd_CallQue_Insert.ExecuteReader [18310862506583] call

110853.734 21 0 dial cmd_CallQue_Insert sql [iNSERT INTO CallQue (PhoneNumber, PhoneNumberPrefix, ActivateTime, TimeStart_Mon, TimeStart_Tue, TimeStart_Wed, TimeStart_Thu, TimeStart_Fri, TimeStart_Sat, TimeStart_Sun, TimeStop_Mon, TimeStop_Tue, TimeStop_Wed, TimeStop_Thu, TimeStop_Fri, TimeStop_Sat, TimeStop_Sun, CampaignName, Priority, OnAnswerLive, OnAnswerMachine, OnAnswerFax, OnNotAnswered, OnRetriesExhausted, AnswerTimeout, RetriesLeft, RetriesDelay, RV, CallOptions, EscalationCalls) VALUES (@strNbrToDial, @strPhoneNumberPrefix, @dateActivateTime, @iTimeStart_Mon, @iTimeStart_Tue, @iTimeStart_Wed, @iTimeStart_Thu, @iTimeStart_Fri, @iTimeStart_Sat, @iTimeStart_Sun, @iTimeStop_Mon, @iTimeStop_Tue, @iTimeStop_Wed, @iTimeStop_Thu, @iTimeStop_Fri, @iTimeStop_Sat, @iTimeStop_Sun, @strCampaignName, @iPriority, @strOnAnswerLive, @strOnAnswerMachine, @strOnAnswerFax, @strOnNotAnswered, @strOnRetriesExhausted, @iAnswerTimeout, @iRetriesLeft, @iRetriesDelay, @strRV, @strOptions, @strEscalationCalls);SELECT DISTINCT @@identity FROM CallQue;]

...

110853.750 10 dial Db_Delete_Entry begin id=754 guid=00000000-0000-0000-0000-000000000000 linkField=ID, sql=[DELETE FROM CallQue WHERE ID=@iID; DELETE FROM PortToUse WHERE CallID=@iID;]

...

110853.750 21 0 dial cmd_CallQue_Insert.ExecuteReader [18310862506583] returned, some data FieldCount=1

110853.750 21 0 dial dr[iD] : Could not find specified column in results

110853.750 21 0 dial cmd_CallQue_Insert.ExecuteReader [18310862506583] sID=NOT SET, oID=2106, iID=2106

110853.750 10 dial Db_Delete_Entry end id=754 guid=00000000-0000-0000-0000-000000000000

 

not sure why it woudl stop responding.

A few minutes later we see that VG service was ended and we see in log file:

 

111222.125 21 ERROR v7.0.3415.28902 (2009-05-08 15:03:23.86) DialListLoadFileXML : Connection unexpectedly terminated.

 

after VG was started again restart we can see that it started again to load the Outdial_09-08-24-11-08.xml file, which did not complete loading beforehand

 

111527.453 4 0 OutDial load from [C:\Program Files\VoiceGuide\data\Outdial_09-08-24-11-08.xml]

 

although there were some errors raised by the MySQL driver:

 

111527.578 4 0 dial cmd_CallQue_Insert sql [iNSERT INTO CallQue (PhoneNumber, PhoneNumberPrefix, ActivateTime, TimeStart_Mon, TimeStart_Tue, TimeStart_Wed, TimeStart_Thu, TimeStart_Fri, TimeStart_Sat, TimeStart_Sun, TimeStop_Mon, TimeStop_Tue, TimeStop_Wed, TimeStop_Thu, TimeStop_Fri, TimeStop_Sat, TimeStop_Sun, CampaignName, Priority, OnAnswerLive, OnAnswerMachine, OnAnswerFax, OnNotAnswered, OnRetriesExhausted, AnswerTimeout, RetriesLeft, RetriesDelay, RV, CallOptions, EscalationCalls) VALUES (@strNbrToDial, @strPhoneNumberPrefix, @dateActivateTime, @iTimeStart_Mon, @iTimeStart_Tue, @iTimeStart_Wed, @iTimeStart_Thu, @iTimeStart_Fri, @iTimeStart_Sat, @iTimeStart_Sun, @iTimeStop_Mon, @iTimeStop_Tue, @iTimeStop_Wed, @iTimeStop_Thu, @iTimeStop_Fri, @iTimeStop_Sat, @iTimeStop_Sun, @strCampaignName, @iPriority, @strOnAnswerLive, @strOnAnswerMachine, @strOnAnswerFax, @strOnNotAnswered, @strOnRetriesExhausted, @iAnswerTimeout, @iRetriesLeft, @iRetriesDelay, @strRV, @strOptions, @strEscalationCalls);SELECT DISTINCT @@identity FROM CallQue;]

111528.000 4 0 dial cmd_CallQue_Insert.ExecuteReader [18310862506583] returned, some data FieldCount=1

111528.000 4 0 dial dr[iD] : Could not find specified column in results

111528.000 4 0 dial cmd_CallQue_Insert.ExecuteReader [18310862506583] sID=NOT SET, oID=2107, iID=2107

111528.000 4 0 dial Db_Insert_CallQueAndPortList CallQue insert returned TimeSpan=00:00:00.4218750

111528.000 4 dial Db_Insert_CallQueAndPortList iID_JustInserted=2107

111528.046 4 dial Db_Insert_PortToUse id=2107, guid=[ddefcf60-3ab6-4592-b75a-1d61add86265] sPortList=[1,2]

111528.062 10 0 ERROR v7.0.3415.28902 (2009-05-08 15:03:23.86) DoWorkFindCall stage1 : Value cannot be null.

Parameter name: key

 

at System.ThrowHelper.ThrowArgumentNullException(ExceptionArgument argument)

at System.Collections.Generic.Dictionary`2.FindEntry(TKey key)

at System.Collections.Generic.Dictionary`2.get_Item(TKey key)

at MySql.Data.MySqlClient.CharSetMap.GetChararcterSet(DBVersion version, String CharSetName)

at MySql.Data.MySqlClient.NativeDriver.GetFieldMetaData41()

at MySql.Data.MySqlClient.NativeDriver.GetFieldMetaData()

at MySql.Data.MySqlClient.NativeDriver.ReadColumnMetadata(Int32 count)

at MySql.Data.MySqlClient.MySqlDataReader.NextResult()

at MySql.Data.MySqlClient.MySqlCommand.ExecuteReader(CommandBehavior behavior)

at MySql.Data.MySqlClient.MySqlCommand.ExecuteDbDataReader(CommandBehavior behavior)

at System.Data.Common.DbCommand.ExecuteReader(CommandBehavior behavior)

at ktlib.vgDb.vgDb_ProviderInvariant.DbProcessQueue_DoQueuedWork_FindCall(Object sender, ProcessingQueueEventArgs`1 args)

 

but about 600 calls were loaded from the xml file in about 20 seconds, when the MySQL driver again started raising exceptions all of a sudden:

 

111549.328 21 0 dial cmd_CallQue_Insert sql [iNSERT INTO CallQue (PhoneNumber, PhoneNumberPrefix, ActivateTime, TimeStart_Mon, TimeStart_Tue, TimeStart_Wed, TimeStart_Thu, TimeStart_Fri, TimeStart_Sat, TimeStart_Sun, TimeStop_Mon, TimeStop_Tue, TimeStop_Wed, TimeStop_Thu, TimeStop_Fri, TimeStop_Sat, TimeStop_Sun, CampaignName, Priority, OnAnswerLive, OnAnswerMachine, OnAnswerFax, OnNotAnswered, OnRetriesExhausted, AnswerTimeout, RetriesLeft, RetriesDelay, RV, CallOptions, EscalationCalls) VALUES (@strNbrToDial, @strPhoneNumberPrefix, @dateActivateTime, @iTimeStart_Mon, @iTimeStart_Tue, @iTimeStart_Wed, @iTimeStart_Thu, @iTimeStart_Fri, @iTimeStart_Sat, @iTimeStart_Sun, @iTimeStop_Mon, @iTimeStop_Tue, @iTimeStop_Wed, @iTimeStop_Thu, @iTimeStop_Fri, @iTimeStop_Sat, @iTimeStop_Sun, @strCampaignName, @iPriority, @strOnAnswerLive, @strOnAnswerMachine, @strOnAnswerFax, @strOnNotAnswered, @strOnRetriesExhausted, @iAnswerTimeout, @iRetriesLeft, @iRetriesDelay, @strRV, @strOptions, @strEscalationCalls);SELECT DISTINCT @@identity FROM CallQue;]

111549.390 21 0 ERROR v7.0.3415.28902 (2009-05-08 15:03:23.86) Db_Insert_CallQueAndPortList_call : Non-negative number required.

Parameter name: count

 

at System.IO.BufferedStream.Read(Byte[] array, Int32 offset, Int32 count)

at MySql.Data.MySqlClient.MySqlStream.Read(Byte[] buffer, Int32 offset, Int32 count)

at MySql.Data.MySqlClient.MySqlStream.ReadByte()

at MySql.Data.MySqlClient.MySqlStream.ReadLong(Int32 numbytes)

at MySql.Data.MySqlClient.NativeDriver.ReadResult(UInt64& affectedRows, Int64& lastInsertId)

at MySql.Data.MySqlClient.MySqlDataReader.GetResultSet()

at MySql.Data.MySqlClient.MySqlDataReader.NextResult()

at MySql.Data.MySqlClient.MySqlCommand.ExecuteReader(CommandBehavior behavior)

at MySql.Data.MySqlClient.MySqlCommand.ExecuteNonQuery()

at MySql.Data.MySqlClient.MySqlCommand.HandleCommandBehaviors(CommandBehavior behavior)

at MySql.Data.MySqlClient.MySqlCommand.ExecuteReader(CommandBehavior behavior)

at MySql.Data.MySqlClient.MySqlCommand.ExecuteDbDataReader(CommandBehavior behavior)

at System.Data.Common.DbCommand.ExecuteReader(CommandBehavior behavior)

at ktlib.vgDb.vgDb_ProviderInvariant.Db_Insert_CallQueAndPortList(DbCommand& cmd_CallQue_Insert, DbCommand& cmd_PortList_Insert, String strPhoneNumber, String strPortSelection)

111549.390 21 0 dial Db_Insert_CallQueAndPortList CallQue insert returned TimeSpan=00:00:00.0625000

111549.390 21 dial Db_Insert_CallQueAndPortList iID_JustInserted=-1

111549.390 21 dial Db_Insert_PortToUse id=-1, guid=[e287fb19-d0bd-4334-9e4d-5e0b8ee8bd76] sPortList=[1,2]

111549.406 4 0 WARN dial cmd_CallQue_Insert.ExecuteReader [18310895711914] returned no data

111549.406 4 0 dial dr.ToString(): [MySql.Data.MySqlClient.MySqlDataReader]

 

and

 

111628.359 4 0 ERROR v7.0.3415.28902 (2009-05-08 15:03:23.86) Db_Insert_PortToUse : Fatal error encountered during command execution.

MySql.Data.MySqlClient.MySqlException: Timeout expired. The timeout period elapsed prior to completion of the operation or the server is not responding.

at MySql.Data.MySqlClient.MySqlDataReader.NextResult()

at MySql.Data.MySqlClient.MySqlCommand.ExecuteReader(CommandBehavior behavior)

at MySql.Data.MySqlClient.MySqlCommand.ExecuteReader(CommandBehavior behavior)

at MySql.Data.MySqlClient.MySqlCommand.ExecuteNonQuery()

at ktlib.vgDb.vgDb_ProviderInvariant.Db_Insert_PortToUse(DbCommand& cmd, String sPortList, Int32 iID_JustInserted, Guid guidJustInserted)

 

We can see other errors in the vgEngine trace files, eg:

 

212251.359 10 0 ERROR v7.0.3415.28902 (2009-05-08 15:03:23.86) DoWorkFindCall stage1 : Fatal error encountered during command execution.

MySql.Data.MySqlClient.MySqlException: Fatal error encountered attempting to read the resultset. ---> MySql.Data.MySqlClient.MySqlException: Reading from the stream has failed. ---> System.IO.IOException: Unable to read data from the transport connection: An existing connection was forcibly closed by the remote host. ---> System.Net.Sockets.SocketException: An existing connection was forcibly closed by the remote host

at System.Net.Sockets.Socket.Receive(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags)

at System.Net.Sockets.NetworkStream.Read(Byte[] buffer, Int32 offset, Int32 size)

--- End of inner exception stack trace ---

at System.Net.Sockets.NetworkStream.Read(Byte[] buffer, Int32 offset, Int32 size)

at System.IO.BufferedStream.ReadByte()

at MySql.Data.MySqlClient.MySqlStream.LoadPacket()

--- End of inner exception stack trace ---

at MySql.Data.MySqlClient.MySqlStream.LoadPacket()

at MySql.Data.MySqlClient.MySqlStream.OpenPacket()

at MySql.Data.MySqlClient.NativeDriver.ReadResult(UInt64& affectedRows, Int64& lastInsertId)

at MySql.Data.MySqlClient.MySqlDataReader.GetResultSet()

at MySql.Data.MySqlClient.MySqlDataReader.NextResult()

--- End of inner exception stack trace ---

at MySql.Data.MySqlClient.MySqlDataReader.NextResult()

at MySql.Data.MySqlClient.MySqlCommand.ExecuteReader(CommandBehavior behavior)

at MySql.Data.MySqlClient.MySqlCommand.ExecuteReader(CommandBehavior behavior)

at MySql.Data.MySqlClient.MySqlCommand.ExecuteDbDataReader(CommandBehavior behavior)

at System.Data.Common.DbCommand.ExecuteReader(CommandBehavior behavior)

at ktlib.vgDb.vgDb_ProviderInvariant.DbProcessQueue_DoQueuedWork_FindCall(Object sender, ProcessingQueueEventArgs`1 args)

 

So overall it looks like there are some issues with the Database setup.

 

Which version of MySQL and which version of MySQL ADO.NET drivers are you using?

 

Perhaps the quickest way to resolve this is to stop using the MySQL that you currently have and just use the default DB that is installed with VoiceGuide. Is there a particular reason why MySQL is used on this system instead of using the default DB installed by VoiceGuide?

 

Note that right now regardless of which DB is used, when calls are loaded using XML input files the making of new outbound calls will be paused until all of the file is completely loaded.

 

If you have similar problems in future have a look at the vgEngine log file to see if the calls are being loaded ok or if there are any database connection errors reported. The vgEngine logs have the information that would let you diagnose what is going on.