# Logging error since 2790

**URL:** https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884
**Category:** Bugs
**Created:** [January 8, 2014, 11:59am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884 "2014-01-08T11:59:57Z")
**Posts on this page:** 20
**Page:** 1

<div class="post-metadata">

### Author: ![christian.leh](https://www.finalbuilder.com/forums/letter_avatar_proxy/v4/letter/c/67e7ee/32.png) [@christian.leh](https://www.finalbuilder.com/forums/u/christian.leh)
#### Post date: [January 8, 2014, 11:59am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/1 "2014-01-08T11:59:57Z")

</div>

Since we installed 2790, we get this error some times:

Build Log Writer&nbsp;

An unexpected error occurred while logging the start of a build action execution: Exception: Exception&nbsp;  
Message: Failed to write using log writer of type 'Continua.Modules.Builds.Logging.TextLogWriterSession': Exception: IOException&nbsp;  
Message: The process cannot access the file 'C:\BuildServer\Ws\care4\4397\log.txt' because it is being used by another process.&nbsp;  
Stack Trace: at System.IO.\_\_Error.WinIOError(Int32 errorCode, String maybeFullPath)&nbsp;  
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)&nbsp;  
at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share, Int32 bufferSize, FileOptions options, String msgPath, Boolean bFromProxy)&nbsp;  
at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share)&nbsp;  
at Continua.Modules.Builds.Logging.TextLogWriterSession.OpenWriter()&nbsp;  
at Continua.Modules.Builds.Logging.TextLogWriterSession.BeginExecution(LogAction action, Execution execution, Execution parentExecution)&nbsp;  
at Continua.Modules.Builds.Logging.CompositeLogWriterSession.ForEachLogWriter(Action`1 action)&nbsp;  
  
  
Stack Trace: at Continua.Modules.Builds.Logging.CompositeLogWriterSession.ForEachLogWriter(Action`1 action)&nbsp;  
at Continua.Modules.Builds.Logging.BuildLogWriter.OnExecutionBegin(LogAction action, Execution execution)&nbsp;

---

<div class="post-metadata">

### Author: ![Sparky](https://www.finalbuilder.com/forums/user_avatar/www.finalbuilder.com/sparky/32/10_2.png) [@Sparky](https://www.finalbuilder.com/forums/u/Sparky)
#### Post date: [January 9, 2014, 9:39am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/2 "2014-01-09T09:39:33Z")

</div>

Hi Christian,  
  
I’ve uploaded a new build[&nbsp;v1.0.0.2832](https://www.finalbuilder.com/Downloads/ContinuaCI.aspx)&nbsp;which fixes this issue

---

<div class="post-metadata">

### Author: ![christian.leh](https://www.finalbuilder.com/forums/letter_avatar_proxy/v4/letter/c/67e7ee/32.png) [@christian.leh](https://www.finalbuilder.com/forums/u/christian.leh)
#### Post date: [January 9, 2014, 9:59am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/3 "2014-01-09T09:59:00Z")

</div>

Now i’m getting this:   
  
 UnitTests   
  
 [code]System.IO.IOException: The process cannot access the file ‘C:\BuildServer\Ws\care4\4422\log.txt’ because it is being used by another process. at Continua.Modules.Builds.Logging.LogSessionManager.GetAvailableSession[T](String folder) at Continua.Modules.Builds.Logging.LogSessionManager.GetOpenSession[T](String folder) at Continua.Modules.Builds.UnitTests.UnitTestManager.RegisterUnitTests(Int32 buildId, Guid stageId, IList`1 unitTests, IEnumerable`1 metrics, Boolean updateMetrics)[/code]

---

<div class="post-metadata">

### Author: ![christian.leh](https://www.finalbuilder.com/forums/letter_avatar_proxy/v4/letter/c/67e7ee/32.png) [@christian.leh](https://www.finalbuilder.com/forums/u/christian.leh)
#### Post date: [January 9, 2014, 4:12am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/4 "2014-01-09T04:12:54Z")

</div>

This error occurs when the second MSTest action will write its results.

---

<div class="post-metadata">

### Author: ![Sparky](https://www.finalbuilder.com/forums/user_avatar/www.finalbuilder.com/sparky/32/10_2.png) [@Sparky](https://www.finalbuilder.com/forums/u/Sparky)
#### Post date: [January 10, 2014, 6:59am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/5 "2014-01-10T06:59:06Z")

</div>

Hi Christian,  
  
I've uploaded a new build&nbsp;[v1.0.0.2840](https://www.finalbuilder.com/Downloads/ContinuaCI.aspx)&nbsp;which includes various updates to the log writer connection pool which now seems to erradicate this issue (at least for us...)

---

<div class="post-metadata">

### Author: ![christian.leh](https://www.finalbuilder.com/forums/letter_avatar_proxy/v4/letter/c/67e7ee/32.png) [@christian.leh](https://www.finalbuilder.com/forums/u/christian.leh)
#### Post date: [January 10, 2014, 8:04am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/6 "2014-01-10T08:04:30Z")

</div>

thanks in advance. your support is great and fast. this is the cause we still use your products even if they do not work sometimes (hell ya, its software produced by humans ;-)).

---

<div class="post-metadata">

### Author: ![christian.leh](https://www.finalbuilder.com/forums/letter_avatar_proxy/v4/letter/c/67e7ee/32.png) [@christian.leh](https://www.finalbuilder.com/forums/u/christian.leh)
#### Post date: [January 10, 2014, 10:31am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/7 "2014-01-10T10:31:11Z")

</div>

seems to work. statistics match results. no more event log entries. good work.

---

<div class="post-metadata">

### Author: ![christian.leh](https://www.finalbuilder.com/forums/letter_avatar_proxy/v4/letter/c/67e7ee/32.png) [@christian.leh](https://www.finalbuilder.com/forums/u/christian.leh)
#### Post date: [January 10, 2014, 10:39am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/8 "2014-01-10T10:39:39Z")

</div>

well, it works now, thats most important. but let me note, that as long as the second **RegisterUnitTests** did not work, the duration for the test stage was 3 minutes. now its 5 minutes (build state details) but when i open the log, only 3 minutes were understandable. maybe you can check improving this process.   
  
 our test count for our biggest MSTest action is 2400.

---

<div class="post-metadata">

### Author: ![christian.leh](https://www.finalbuilder.com/forums/letter_avatar_proxy/v4/letter/c/67e7ee/32.png) [@christian.leh](https://www.finalbuilder.com/forums/u/christian.leh)
#### Post date: [January 10, 2014, 12:20pm UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/9 "2014-01-10T12:20:34Z")

</div>

lalala … no worry, the build is successful and seems to be ok.

```auto
Build Log Writer An unexpected error occurred while logging the completion of a build action: 
Exception: Exception Message: Failed to write using log writer of type 'Continua.Modules.Builds.Logging.TextLogWriterSession': 
Exception: KeyNotFoundException 
Message: The given key was not present in the dictionary. Stack Trace: at 
Continua.Modules.Builds.Logging.TextLogWriterSession.EndAction(LogAction action) at 
Continua.Modules.Builds.Logging.CompositeLogWriterSession.ForEachLogWriter(Action `1 action) Stack Trace: at 
Continua.Modules.Builds.Logging.CompositeLogWriterSession.ForEachLogWriter(Action` 1 action) at 
Continua.Modules.Builds.Logging.BuildLogWriter.OnActionComplete(LogAction action)

```

---

<div class="post-metadata">

### Author: ![Vincent](https://www.finalbuilder.com/forums/user_avatar/www.finalbuilder.com/vincent/32/938_2.png) [@Vincent](https://www.finalbuilder.com/forums/u/Vincent)
#### Post date: [January 10, 2014, 1:17am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/10 "2014-01-10T01:17:09Z")

</div>

Arrrgggghh! this one is proving difficult to sort out.   
  
 With regards to the timing, will look into it. I suspect we are hitting a database insert speed issue, or to be more correct, and nhibernate insert issue. Might be time to switch that part of the code to dapper (which we use on other parts).

---

<div class="post-metadata">

### Author: ![christian.leh](https://www.finalbuilder.com/forums/letter_avatar_proxy/v4/letter/c/67e7ee/32.png) [@christian.leh](https://www.finalbuilder.com/forums/u/christian.leh)
#### Post date: [January 10, 2014, 1:54am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/11 "2014-01-10T01:54:23Z")

</div>

One of our recent builds hang up again. I stopped it after one hour. But no additional log entries. Only that above.

---

<div class="post-metadata">

### Author: ![Vincent](https://www.finalbuilder.com/forums/user_avatar/www.finalbuilder.com/vincent/32/938_2.png) [@Vincent](https://www.finalbuilder.com/forums/u/Vincent)
#### Post date: [January 13, 2014, 7:30am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/12 "2014-01-13T07:30:22Z")

</div>

Hi Christian

I have uploaded a build with a fix for the logging issue (which I hope is resolved once and for all!) and also a major performance boost for recording unit tests on the server. The performance issue &nbsp;was due to a query being run for every test, when it could be replaced with a single query up front (finding shelving information). I also improved the data structures used between the agent and the server to remove duplicate information.&nbsp;

[http://downloads.finalbuilder.com/d...0.2849.exe](http://downloads.finalbuilder.com/downloads/continua/1.0/ContinuaCI.Server.Setup_x64_1.0.0.2849.exe)

[http://downloads.finalbuilder.com/d...0.2849.exe](http://downloads.finalbuilder.com/downloads/continua/1.0/ContinuaCI.Agent.Setup_x64_1.0.0.2849.exe)

[http://downloads.finalbuilder.com/d...0.2849.exe](http://downloads.finalbuilder.com/downloads/continua/1.0/ContinuaCI.Server.Setup_1.0.0.2849.exe)

[http://downloads.finalbuilder.com/d...0.2849.exe](http://downloads.finalbuilder.com/downloads/continua/1.0/ContinuaCI.Agent.Setup_1.0.0.2849.exe)

---

<div class="post-metadata">

### Author: ![Vincent](https://www.finalbuilder.com/forums/user_avatar/www.finalbuilder.com/vincent/32/938_2.png) [@Vincent](https://www.finalbuilder.com/forums/u/Vincent)
#### Post date: [January 13, 2014, 7:34am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/13 "2014-01-13T07:34:00Z")

</div>

Stupid forums keeps messing with my links! The top links are for x64, bottom two for x86

---

<div class="post-metadata">

### Author: ![christian.leh](https://www.finalbuilder.com/forums/letter_avatar_proxy/v4/letter/c/67e7ee/32.png) [@christian.leh](https://www.finalbuilder.com/forums/u/christian.leh)
#### Post date: [January 13, 2014, 9:37am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/14 "2014-01-13T09:37:42Z")

</div>

[code]Build Log Writer An unexpected error occurred while logging metric details: Exception: Exception Message: Failed to write using log writer of type 'Continua.Modules.Builds.Logging.SqlCeLogWriterSession': Exception: SqlCeException Message: There was an error parsing the query. [Token line number = 1,Token line offset = 29,Token in error = 00] Stack Trace: at System.Data.SqlServerCe.SqlCeCommand.ProcessResults(Int32 hr) at System.Data.SqlServerCe.SqlCeCommand.CompileQueryPlan() at System.Data.SqlServerCe.SqlCeCommand.ExecuteCommand(CommandBehavior behavior, String method, ResultSetOptions options) at System.Data.SqlServerCe.SqlCeCommand.ExecuteNonQuery() at Continua.Modules.Builds.Logging.SqlCeLogWriterSession.AppendMetric(Stage stage, Metric metric) at Continua.Modules.Builds.Logging.CompositeLogWriterSession.ForEachLogWriter(Action`1 action) Stack Trace: at Continua.Modules.Builds.Logging.CompositeLogWriterSession.ForEachLogWriter(Action`1 action) at Continua.Modules.Builds.Logging.BuildLogWriter.OnMetric(Metric metric) This event has been reported 2 times.[/code]   
  
 The build itself is successful and the unit tests are now very fast (1 minute 40 seconds, _thumbs up_).

---

<div class="post-metadata">

### Author: ![christian.leh](https://www.finalbuilder.com/forums/letter_avatar_proxy/v4/letter/c/67e7ee/32.png) [@christian.leh](https://www.finalbuilder.com/forums/u/christian.leh)
#### Post date: [January 13, 2014, 9:39am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/15 "2014-01-13T09:39:16Z")

</div>

but the unit tests are not recorded correctly. only the results of the first of the two actions are recorded.

---

<div class="post-metadata">

### Author: ![christian.leh](https://www.finalbuilder.com/forums/letter_avatar_proxy/v4/letter/c/67e7ee/32.png) [@christian.leh](https://www.finalbuilder.com/forums/u/christian.leh)
#### Post date: [January 13, 2014, 9:44am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/16 "2014-01-13T09:44:05Z")

</div>

[code]UnitTests System.Exception: Failed to write using log writer of type 'Continua.Modules.Builds.Logging.SqlCeLogWriterSession': Exception: SqlCeException Message: There was an error parsing the query. [Token line number = 1,Token line offset = 31,Token in error = 00] Stack Trace: at System.Data.SqlServerCe.SqlCeCommand.ProcessResults(Int32 hr) at System.Data.SqlServerCe.SqlCeCommand.CompileQueryPlan() at System.Data.SqlServerCe.SqlCeCommand.ExecuteCommand(CommandBehavior behavior, String method, ResultSetOptions options) at System.Data.SqlServerCe.SqlCeCommand.ExecuteNonQuery() at Continua.Modules.Builds.Logging.SqlCeLogWriterSession.AppendMetric(Stage stage, Metric metric) at Continua.Modules.Builds.Logging.CompositeLogWriterSession.ForEachLogWriter(Action`1 action) at Continua.Modules.Builds.Logging.CompositeLogWriterSession.ForEachLogWriter(Action`1 action) at Continua.Modules.Builds.UnitTests.UnitTestManager.RegisterUnitTests(Int32 buildId, Guid stageId, IList`1 unitTests, IEnumerable`1 metrics, Boolean updateMetrics) This event has been reported 3 times.[/code]

---

<div class="post-metadata">

### Author: ![Vincent](https://www.finalbuilder.com/forums/user_avatar/www.finalbuilder.com/vincent/32/938_2.png) [@Vincent](https://www.finalbuilder.com/forums/u/Vincent)
#### Post date: [January 13, 2014, 11:06am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/17 "2014-01-13T11:06:34Z")

</div>

Hmm… do you have any shelved tests? I haven’t been able to reproduce the problem here so far… still looking into it.

---

<div class="post-metadata">

### Author: ![Vincent](https://www.finalbuilder.com/forums/user_avatar/www.finalbuilder.com/vincent/32/938_2.png) [@Vincent](https://www.finalbuilder.com/forums/u/Vincent)
#### Post date: [January 13, 2014, 12:44pm UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/18 "2014-01-13T12:44:36Z")

</div>

Hi Chistian

I wasn't able to reproduce this here, but I refactored the area to improve error handling. At the very least it shouldn't crash the build, it will just log the error.

[http://downloads.finalbuilder.com/d...0.2852.exe](http://downloads.finalbuilder.com/downloads/continua/1.0/ContinuaCI.Server.Setup_x64_1.0.0.2852.exe)

[http://downloads.finalbuilder.com/d...0.2852.exe](http://downloads.finalbuilder.com/downloads/continua/1.0/ContinuaCI.Agent.Setup_x64_1.0.0.2852.exe)

[http://downloads.finalbuilder.com/d...0.2852.exe](http://downloads.finalbuilder.com/downloads/continua/1.0/ContinuaCI.Server.Setup_1.0.0.2852.exe)

[http://downloads.finalbuilder.com/d...0.2852.exe](http://downloads.finalbuilder.com/downloads/continua/1.0/ContinuaCI.Agent.Setup_1.0.0.2852.exe)

---

<div class="post-metadata">

### Author: ![christian.leh](https://www.finalbuilder.com/forums/letter_avatar_proxy/v4/letter/c/67e7ee/32.png) [@christian.leh](https://www.finalbuilder.com/forums/u/christian.leh)
#### Post date: [January 13, 2014, 3:23am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/19 "2014-01-13T03:23:04Z")

</div>

hm ok, for now, no event log entries occur, everything works and performance is good.

---

<div class="post-metadata">

### Author: ![Vincent](https://www.finalbuilder.com/forums/user_avatar/www.finalbuilder.com/vincent/32/938_2.png) [@Vincent](https://www.finalbuilder.com/forums/u/Vincent)
#### Post date: [January 14, 2014, 5:52am UTC](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884/20 "2014-01-14T05:52:41Z")

</div>

Hi Christian   
  
 Thanks for letting us know, and thanks for your patience. We’ll be rolling out an official update in a day or so.

[Next page](https://www.finalbuilder.com/forums/t/logging-error-since-2790/5884.md?page=2)
