IOException causing extra file generation to stop

[Adrian Hudson]

Hi Steve,

The program was minding its own business this morning - no one touching the PC etc. All of a sudden it decided it couldn't access one of the extra txttmp files. I suspect it was the system making a drive snapshot at the beginning of my daily backup - so we will have to put that one down to experience...

... but, more importantly, thereafter Cumulus didn't process any of the extra files until I got home this evening and ended/restarted the program. You can see from this log the program generating the extra files before the error but not afterwards.

Can you do anything about that?

Log file snippet follows:

19/01/2015 09:20:00 Updating log files
Writing today.ini, LastUpdateTime = 19/01/2015 09:20:00 raindaystart = 79 rain counter = 79
2015-01-19 09:20:01.017 Creating standard HTML files
2015-01-19 09:20:01.028 http://weatherstation.wunderground.com/ ... =updateraw
2015-01-19 09:20:01.028 http://www.pwsweather.com/pwsupdate/pws ... =updateraw
2015-01-19 09:20:01.029 http://wow.metoffice.gov.uk/automaticre ... =updateraw
2015-01-19 09:20:01.029 http://data.backyard2.weatherbug.com/da ... =updateraw
2015-01-19 09:20:01.030 Updating CWOP
2015-01-19 09:20:01.256 Sending user and pass to CWOP
2015-01-19 09:20:01.262 Done creating standard HTML files, creating graph data files
2015-01-19 09:20:01.291 PWS Response: OK: OK
2015-01-19 09:20:01.639 WU Response: OK: OK
2015-01-19 09:20:01.662 Wbug Response: OK: OK
2015-01-19 09:20:01.920 Done creating graph data files, creating extra files
2015-01-19 09:20:02.579 Done creating extra files
2015-01-19 09:20:02.600 Uploading extra files
2015-01-19 09:20:02.603 Done uploading extra files, uploading standard files
2015-01-19 09:20:02.920 Done uploading standard files, uploading graph data files
2015-01-19 09:20:03.215 Done uploading graph data files
2015-01-19 09:20:04.257 Sending: CW3517>APRS,TCPIP*:@190920z5053.05N/00322.42W_000/000g001t028r000p000P000h95b10163L097eCumulusDsVP
2015-01-19 09:20:22.796 WOW Response: OK: OK
2015-01-19 09:25:00.626 Creating standard HTML files
2015-01-19 09:25:01.533 Done creating standard HTML files, creating graph data files
2015-01-19 09:25:02.199 Done creating graph data files, creating extra files
System.IO.IOException: The process cannot access the file 'D:\Cumulus\cumuluswebtags.txttmp' because it is being used by another process.
at System.IO.__Error.WinIOError(Int32 errorCode, String maybeFullPath)
at System.IO.FileStream.Init(String path, FileMode mode, FileAccess access, Int32 rights, Boolean useRights, FileShare share, Int32 bufferSize, FileOptions options, SECURITY_ATTRIBUTES secAttrs, String msgPath, Boolean bFromProxy, Boolean useLongPath, Boolean checkHost)
at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share, Int32 bufferSize, FileOptions options, String msgPath, Boolean bFromProxy, Boolean useLongPath, Boolean checkHost)
at System.IO.StreamWriter.CreateFile(String path, Boolean append, Boolean checkHost)
at System.IO.StreamWriter..ctor(String path, Boolean append, Encoding encoding, Int32 bufferSize, Boolean checkHost)
at System.IO.StreamWriter..ctor(String path, Boolean append, Encoding encoding)
at CumulusMX.Cumulus.DoHTMLFiles()
at System.Threading.ThreadHelper.ThreadStart_Context(Object state)
at System.Threading.ExecutionContext.RunInternal(ExecutionContext executionContext, ContextCallback callback, Object state, Boolean preserveSyncCtx)
at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state, Boolean preserveSyncCtx)
at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state)
at System.Threading.ThreadHelper.ThreadStart()
19/01/2015 09:30:00 Updating log files
Writing today.ini, LastUpdateTime = 19/01/2015 09:30:00 raindaystart = 79 rain counter = 79
2015-01-19 09:30:01.187 http://weatherstation.wunderground.com/ ... =updateraw
2015-01-19 09:30:01.188 http://www.pwsweather.com/pwsupdate/pws ... =updateraw
2015-01-19 09:30:01.188 http://wow.metoffice.gov.uk/automaticre ... =updateraw
2015-01-19 09:30:01.189 http://data.backyard2.weatherbug.com/da ... =updateraw
2015-01-19 09:30:01.189 Updating CWOP
2015-01-19 09:30:01.531 Sending user and pass to CWOP
2015-01-19 09:30:01.731 PWS Response: OK: OK
2015-01-19 09:30:02.064 WU Response: OK: OK
2015-01-19 09:30:02.123 Wbug Response: OK: OK
2015-01-19 09:30:02.574 WOW Response: OK: OK
2015-01-19 09:30:04.535 Sending: CW3517>APRS,TCPIP*:@190930z5053.05N/00322.42W_199/001g002t030r000p000P000h94b10164L116eCumulusDsVP
19/01/2015 09:40:00 Updating log files
Writing today.ini, LastUpdateTime = 19/01/2015 09:40:00 raindaystart = 79 rain counter = 79
2015-01-19 09:40:00.669 http://weatherstation.wunderground.com/ ... =updateraw
2015-01-19 09:40:00.669 http://www.pwsweather.com/pwsupdate/pws ... =updateraw
2015-01-19 09:40:00.669 http://wow.metoffice.gov.uk/automaticre ... =updateraw
2015-01-19 09:40:00.670 http://data.backyard2.weatherbug.com/da ... =updateraw
2015-01-19 09:40:00.670 Updating CWOP
2015-01-19 09:40:01.037 Sending user and pass to CWOP
2015-01-19 09:40:01.280 PWS Response: OK: OK
2015-01-19 09:40:01.397 WU Response: OK: OK
2015-01-19 09:40:01.707 Wbug Response: OK: OK
2015-01-19 09:40:01.757 WOW Response: OK: OK

[steve]

It probably killed the thread (like the today.ini problem that I discovered). I'll see if I can move the code which traps the exception closer to the problem so the thread should continue.

[Adrian Hudson]

Hi Steve,

It did it again. A can't find a reason that txttmp file should be being used. Did you ever try to see if you could move the code?

Regards,
Adrian

2015-02-11 20:50:00.457 Updating CWOP
2015-02-11 20:50:00.709 Done creating standard HTML files
2015-02-11 20:50:00.709 Creating graph data files
2015-02-11 20:50:00.756 PWS Response: OK: OK
2015-02-11 20:50:00.788 Sending user and pass to CWOP
2015-02-11 20:50:00.908 Done creating graph data files
2015-02-11 20:50:00.908 Creating extra files
2015-02-11 20:50:01.043 WU Response: OK: OK
2015-02-11 20:50:01.105 Wbug Response: OK: OK
2015-02-11 20:50:01.449 WOW Response: OK: OK
System.IO.IOException: The process cannot access the file 'D:\Cumulus\cumuluswebtags.txttmp' because it is being used by another process.
at System.IO.__Error.WinIOError(Int32 errorCode, String maybeFullPath)
at System.IO.FileStream.Init(String path, FileMode mode, FileAccess access, Int32 rights, Boolean useRights, FileShare share, Int32 bufferSize, FileOptions options, SECURITY_ATTRIBUTES secAttrs, String msgPath, Boolean bFromProxy, Boolean useLongPath, Boolean checkHost)
at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share, Int32 bufferSize, FileOptions options, String msgPath, Boolean bFromProxy, Boolean useLongPath, Boolean checkHost)
at System.IO.StreamWriter.CreateFile(String path, Boolean append, Boolean checkHost)
at System.IO.StreamWriter..ctor(String path, Boolean append, Encoding encoding, Int32 bufferSize, Boolean checkHost)
at System.IO.StreamWriter..ctor(String path, Boolean append, Encoding encoding)
at CumulusMX.Cumulus.DoHTMLFiles() in c:\Users\steve\Documents\Visual Studio 2013\Projects\CumulusMX\CumulusMX\Cumulus.cs:line 4158
at System.Threading.ThreadHelper.ThreadStart_Context(Object state)
at System.Threading.ExecutionContext.RunInternal(ExecutionContext executionContext, ContextCallback callback, Object state, Boolean preserveSyncCtx)
at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state, Boolean preserveSyncCtx)
at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state)
at System.Threading.ThreadHelper.ThreadStart()
2015-02-11 20:50:03.789 Sending: CW3517>APRS,TCPIP*:@112050z5053.05N/00322.42W_057/001g003t039r000p000P000h86b10216L000eCumulusDsVP
2015-02-11 20:50:23.044 Reading reception stats
2015-02-11 20:50:23.599 0A-0D-4F-4B-0A-0D-34-38-36-34-20-31-30-32-20-31-20-33-37-36-20-33-35-0A-0D-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00
2015-02-11 20:50:23.599 4864 102 1 376 35

2015-02-11 20:51:00.771 Reading reception stats
2015-02-11 20:51:01.462 0A-0D-4F-4B-0A-0D-34-38-37-39-20-31-30-32-20-31-20-33-37-36-20-33-35-0A-0D-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00
2015-02-11 20:51:01.462 4879 102 1 376 35

2015-02-11 20:51:38.796 Reading reception stats
2015-02-11 20:51:39.173 0A-0D-4F-4B-0A-0D-34-38-39-33-20-31-30-33-20-31-20-33-37-36-20-33-35-0A-0D-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00
2015-02-11 20:51:39.174 4893 103 1 376 35

[steve]

I'm trapping exceptions writing to the file - I can't remember whether that was a change - but not opening it - I'll do that.