Please note that VisualCron support is not actively monitoring this community forum. Please use our contact page for contacting the VisualCron support directly.


MRomer
2020-12-16T01:22:08Z
Today our VisualCron server became unresponsive. I could not connect the VC client to it. I ended up having to restart the service. As you can see from this snippet of the log_server from today, many of the jobs just seemed to be stuck in the "running" status. The jobs listed that are stuck interact with our MS SQL Server. Do you have any advise on where we can start looking for a solution?

12/15/2020 3:50:30 PM	Info	Task started(PowerShell): Convert flat file to XML (387862896)
12/15/2020 3:50:30 PM	Info	Job started: Finalize Queued Transactions
12/15/2020 3:50:30 PM	Info	Task started(SQL): SQL (387862897)
12/15/2020 3:50:30 PM	Debug	CleanLocalDB started (False)
12/15/2020 3:50:30 PM	Info	Cleaning up local database according to cleanup rules
12/15/2020 3:50:30 PM	Debug	CleanLocalDB - retrieve stats - start
12/15/2020 3:50:30 PM	Info	Task completed (SQL) (Success)->'SQL' (387862897)
12/15/2020 3:50:30 PM	Info	Job completed (Success)->'Finalize Queued Transactions'
12/15/2020 3:50:31 PM	Info	Task completed (PowerShell) (Success)->'Convert flat file to XML' (387862896)
12/15/2020 3:50:31 PM	Info	Task started(SQL): Load XML data into database (387862898)
12/15/2020 3:50:31 PM	Debug	CleanLocalDB - retrieve stats - end
12/15/2020 3:50:31 PM	Debug	CleanLocalDB - log size (before): 3633315840
12/15/2020 3:50:31 PM	Debug	CleanLocalDB - start (forced)
12/15/2020 3:50:31 PM	Debug	LogDBExecNonQueryWithDateParam->12/13/2020 10:16:50 AM
12/15/2020 3:50:31 PM	Info	Job started: Finalize Queued Transactions
12/15/2020 3:50:31 PM	Info	Task completed (SQL) (Success)->'Load XML data into database' (387862898)
12/15/2020 3:50:31 PM	Debug	LogDBExecNonQueryWithDateParam->Result: 4677
12/15/2020 3:50:31 PM	Info	General log, keep for 14 days. Minimum date to keep: 12/1/2020 3:50:31 PM
12/15/2020 3:50:31 PM	Debug	Cleaning Audit logs started
12/15/2020 3:50:31 PM	Info	Audit log, keep maximum rows 2000 - current count: 67
12/15/2020 3:50:31 PM	Info	Audit log, keep maximum rows - minimum date to keep: 12/13/2020 10:40:44 AM
12/15/2020 3:50:31 PM	Debug	LogDBExecNonQueryWithDateParam->12/13/2020 10:40:44 AM
12/15/2020 3:50:31 PM	Debug	LogDBExecNonQueryWithDateParam->Result: 12
12/15/2020 3:50:31 PM	Info	Audit log, keep for 14 days. Minimum date to keep: 12/1/2020 3:50:31 PM
12/15/2020 3:50:31 PM	Debug	Cleaning Audit logs  completed, seconds elapsed: 0.0029621
12/15/2020 3:50:31 PM	Debug	Cleaning Job logs started
12/15/2020 3:50:31 PM	Info	Job log, keep maximum rows 1000000 - current count: 391406
12/15/2020 3:50:31 PM	Info	Task started(SQL): SQL (387862899)
12/15/2020 3:50:31 PM	Info	Job log, keep maximum rows - minimum date to keep: 12/13/2020 1:32:26 AM
12/15/2020 3:50:31 PM	Debug	LogDBExecNonQueryWithDateParam->12/13/2020 1:32:26 AM
12/15/2020 3:50:31 PM	Info	Task started(Write file): Write database results to log (387862900)
12/15/2020 3:50:31 PM	Info	Task completed (Write file) (Success)->'Write database results to log' (387862900)
12/15/2020 3:50:31 PM	Info	Task completed (SQL) (Success)->'SQL' (387862899)
12/15/2020 3:50:32 PM	Info	Job started: Copy Faxes to Watched Directory
12/15/2020 3:50:32 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:33 PM	Info	Job started: BuildFileOutputs
12/15/2020 3:50:33 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:34 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:35 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:36 PM	Debug	LogDBExecNonQueryWithDateParam->Result: 78281
12/15/2020 3:50:36 PM	Debug	Cleaning Job logs completed, seconds elapsed: 4.7622375
12/15/2020 3:50:36 PM	Debug	Cleaning Task logs started
12/15/2020 3:50:36 PM	Info	Task started(Copy files): Back up XML file to Shelling files archive (387862901)
12/15/2020 3:50:36 PM	Info	Task log, keep maximum rows 1000000 - current count: 450898
12/15/2020 3:50:36 PM	Info	Task started(Copy files): Copy Files to Watched Directory (387862902)
12/15/2020 3:50:36 PM	Info	Task started(Execute): BuildFileOutputs (387862903)
12/15/2020 3:50:36 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:36 PM	Info	Task log, keep maximum rows - minimum date to keep: 12/13/2020 1:26:18 AM
12/15/2020 3:50:36 PM	Debug	LogDBExecNonQueryWithDateParam->12/13/2020 1:26:18 AM
12/15/2020 3:50:36 PM	Info	Task completed (Copy files) (Success)->'Copy Files to Watched Directory' (387862902)
12/15/2020 3:50:36 PM	Info	Job completed (Success)->'Finalize Queued Transactions'
12/15/2020 3:50:36 PM	Info	Task completed (Copy files) (Success)->'Back up XML file to Shelling files archive' (387862901)
12/15/2020 3:50:37 PM	Info	Job started: Send ExternalNotifications
12/15/2020 3:50:37 PM	Info	Job started: Finalize Queued Transactions
12/15/2020 3:50:38 PM	Info	Task completed (Execute) (Success)->'BuildFileOutputs' (387862903)
12/15/2020 3:50:41 PM	Info	Job started: SQL Blocking Process Log
12/15/2020 3:50:42 PM	Debug	LogDBExecNonQueryWithDateParam->Result: 90181
12/15/2020 3:50:42 PM	Debug	Cleaning Task logs completed, seconds elapsed: 5.9583693
12/15/2020 3:50:42 PM	Debug	Cleaning Notification logs started
12/15/2020 3:50:42 PM	Info	Notification log, keep maximum rows 2000 - current count: 27
12/15/2020 3:50:42 PM	Info	Task started(Copy files): Back up Flatfile to Shelling files archive (387862904)
12/15/2020 3:50:42 PM	Info	Notification log, keep maximum rows - minimum date to keep: 12/11/2020 8:16:35 PM
12/15/2020 3:50:42 PM	Debug	LogDBExecNonQueryWithDateParam->12/11/2020 8:16:35 PM
12/15/2020 3:50:42 PM	Debug	LogDBExecNonQueryWithDateParam->Result: 4
12/15/2020 3:50:42 PM	Debug	Cleaning Job logs completed, seconds elapsed: 0.0052345
12/15/2020 3:50:42 PM	Debug	CleanLocalDB - log size (after): 3660382208
12/15/2020 3:50:42 PM	Info	Task started(Execute): Run E:\Scripts\SendExternalNotifications\SendExternalNotifications.exe (387862905)
12/15/2020 3:50:42 PM	Info	Task started(SQL): SQL (387862906)
12/15/2020 3:50:42 PM	Info	Job completed (Success)->'Copy Faxes to Watched Directory'
12/15/2020 3:50:42 PM	Debug	Compacting local DB - start
12/15/2020 3:50:42 PM	Info	Task started(SQL): SQL (387862907)
12/15/2020 3:50:43 PM	Info	Job was not started because it was already running (BuildFileOutputs)
12/15/2020 3:50:43 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:44 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:45 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:46 PM	Info	Job started: Download HD65 for Participants
12/15/2020 3:50:46 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:47 PM	Info	Job was not started because it was already running (Send ExternalNotifications)
12/15/2020 3:50:47 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:48 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:49 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:50 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:51 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:52 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:53 PM	Info	Job was not started because it was already running (BuildFileOutputs)
12/15/2020 3:50:53 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:54 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:55 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:55 PM	Debug	IsInReconnectMode->False, File Trigger. Monitoring folder '{JOB(Active|Variable|watchedFolder)}', file mask '*.*' for events Created., InstanceId: 12, hashcode: 4908320, retries: 1/60
12/15/2020 3:50:56 PM	Info	Job was not started because it was already running (SQL Blocking Process Log)
12/15/2020 3:50:56 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:57 PM	Info	Job was not started because it was already running (Send ExternalNotifications)
12/15/2020 3:50:57 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:58 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:50:59 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:00 PM	Info	Job started: BuyingPoint1007BatchPrint
12/15/2020 3:51:00 PM	Info	Job started: FoodServiceBuyerQuery
12/15/2020 3:51:00 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:01 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:02 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:03 PM	Info	Job was not started because it was already running (BuildFileOutputs)
12/15/2020 3:51:03 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:04 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:05 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:06 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:07 PM	Info	Job started: Realtime Quotes
12/15/2020 3:51:07 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:07 PM	Info	Job was not started because it was already running (Send ExternalNotifications)
12/15/2020 3:51:08 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:09 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:10 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:11 PM	Info	Job was not started because it was already running (SQL Blocking Process Log)
12/15/2020 3:51:11 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:12 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:13 PM	Info	Job was not started because it was already running (BuildFileOutputs)
12/15/2020 3:51:13 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:14 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:15 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:16 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:17 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:17 PM	Info	Job was not started because it was already running (Send ExternalNotifications)
12/15/2020 3:51:18 PM	Info	Job started: FtpCcrGetAck
12/15/2020 3:51:18 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:19 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:20 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:21 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:22 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:23 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:23 PM	Info	Job was not started because it was already running (BuildFileOutputs)
12/15/2020 3:51:24 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:25 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:26 PM	Info	Job started: DocumentIdentifier
12/15/2020 3:51:26 PM	Info	Job was not started because it was already running (SQL Blocking Process Log)
12/15/2020 3:51:26 PM	Info	Job started: DocumentIdentifier - Shelling
12/15/2020 3:51:26 PM	Info	Job started: BasisEstimator Upload
12/15/2020 3:51:26 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:27 PM	Info	Job was not started because it was already running (Send ExternalNotifications)
12/15/2020 3:51:27 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:28 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:29 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:30 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:31 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:32 PM	Info	Job started: Copy Faxes to Watched Directory
12/15/2020 3:51:32 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:33 PM	Info	Job was not started because it was already running (BuildFileOutputs)
12/15/2020 3:51:33 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:34 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:35 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:36 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:37 PM	Info	Job was not started because it was already running (Send ExternalNotifications)
12/15/2020 3:51:37 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:38 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:39 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:40 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:41 PM	Info	Job was not started because it was already running (SQL Blocking Process Log)
12/15/2020 3:51:41 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:42 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:43 PM	Info	Job was not started because it was already running (BuildFileOutputs)
12/15/2020 3:51:43 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:44 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:45 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:46 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
12/15/2020 3:51:47 PM	Info	Job was not started because it was already running (Send ExternalNotifications)
12/15/2020 3:51:47 PM	Info	Job was not started because it was already running (Finalize Queued Transactions)
Sponsor
Forum information
MRomer
2020-12-17T23:17:59Z
It happened again today, at the same time (3:50 PM). The server was unresponsive until about 4:17 PM. I found this in the server log:

Line 389148: 12/17/2020 3:50:43 PM	Debug	Compacting local DB - start
***
Line 389593: 12/17/2020 4:16:51 PM	Debug	Compacting local DB - complete

I'm starting to suspect the "Compacting local DB" function is involved in our problems. Can I change the schedule for this?
Support
2021-01-07T12:20:40Z
Originally Posted by: MRomer 

It happened again today, at the same time (3:50 PM). The server was unresponsive until about 4:17 PM. I found this in the server log:

Line 389148: 12/17/2020 3:50:43 PM	Debug	Compacting local DB - start
***
Line 389593: 12/17/2020 4:16:51 PM	Debug	Compacting local DB - complete

I'm starting to suspect the "Compacting local DB" function is involved in our problems. Can I change the schedule for this?



Hi,

I just wanted to check up on you to see if you are still having issues or if its solved? If you are still having issues please send us an email to support@visualcron.com and include details
Michael
Support
http://www.visualcron.com 

Please like  VisualCron on facebook!
MRomer
2021-01-07T15:18:44Z
We fixed the problem by lowering our logging limits, which reduced the size of the database. I'm still wondering if there is a way to change the scheduling of the DB Compact job so that it doesn't happen during business hours.
Support
2021-01-11T16:00:34Z
Originally Posted by: MRomer 

We fixed the problem by lowering our logging limits, which reduced the size of the database. I'm still wondering if there is a way to change the scheduling of the DB Compact job so that it doesn't happen during business hours.



Thanks for updating. As for your other part - I suggest you create a feature request for that
Michael
Support
http://www.visualcron.com 

Please like  VisualCron on facebook!
MRomer
2021-01-11T19:16:58Z
Originally Posted by: Support 

Thanks for updating. As for your other part - I suggest you create a feature request for that



OK. Thanks.
Support
2021-01-22T10:30:32Z
You're welcome!
Michael
Support
http://www.visualcron.com 

Please like  VisualCron on facebook!
Scroll to Top