La propiedad ThreadContext no se escribe en AdoNetAppender

Question

Estoy usando log4net 1.2.10.0. He ampliado ILog y LogManager para incluir un nuevo nivel, 'AUDIT'. Quiero usar un AdoNetAppender para registrar el % message en una base de datos. Necesito otra información registrada y traté de usar log4net.ThreadContext.Properties .

No obtengo resultados cuando trato de usar una propiedad de contexto como valor para un parámetro de SQL.

<log4net>
  <level>
    <name value="AUDIT" />
    <value value="35000" />
  </level>
  <appender name="AdoNetAppender.Audit" type="log4net.Appender.AdoNetAppender">
    <filter type="log4net.Filter.LevelMatchFilter">
      <levelToMatch value="AUDIT" />
    </filter>
    <filter type="log4net.Filter.DenyAllFilter" />
    <connectionType value="System.Data.OracleClient.OracleConnection, System.Data.OracleClient, Version=1.0.3300.0, Culture=neutral, PublicKeyToken=b77a5c561934e089" />
    <connectionString value="data source=db1;User ID=user;Password=pass" />
    <commandText value="INSERT INTO table1 VALUES(:custom_prop, :message)" />
    <parameter>
      <parameterName value=":custom_prop" />
      <dbType value="String" />
      <layout type="log4net.Layout.PatternLayout">
        <conversionPattern value="%property{custom_prop}" />
      </layout>
    </parameter>
    <parameter>
      <parameterName value=":message" />
      <dbType value="String" />
      <layout type="log4net.Layout.PatternLayout">
        <conversionPattern value="%message" />
      </layout>
    </parameter>
  </appender>
  <root>
    <level value="ALL" />
    <appender-ref ref="AdoNetAppender.Audit" />
  </root>
</log4net>

El código de ejecución es (asumiendo que log4net se ha iniciado y se ha creado un registrador, 'log').

log4net.ThreadContext.Properties["custom_prop"] = "value";
log.Audit("a message");

El appender funciona con el filtro AUDIT si yo codifico un valor en lugar de usar un parámetro de SQL.

...
<commandText value="INSERT INTO table1 VALUES('value', :message)" />
...

El appender funciona con la propiedad de contexto si yo filtro en un nivel incorporado, como INFO.

...
<filter type="log4net.Filter.LevelMatchFilter">
  <levelToMatch value="INFO" />
</filter>
...

Un FileAppender funciona con el filtro AUDIT y la propiedad de contexto.

<appender name="FileAppender" type="log4net.Appender.FileAppender" >
  <filter type="log4net.Filter.LevelMatchFilter">
    <levelToMatch value="AUDIT" />
    </filter>
  <filter type="log4net.Filter.DenyAllFilter" />
  <file value="test.log" />
  <layout type="log4net.Layout.PatternLayout" >
    <conversionPattern value="%-5level [%property{custom_prop}] - %message%newline" />
  </layout>
</appender>

Resultando en

AUDIT [value] - a message

El uso de una propiedad de contexto global funciona con el AdoNetAppender y el filtro AUDIT. Pero, necesito resolución de contexto de subproceso.

log4net.GlobalContext.Properties["custom_prop"] = "value";
log.Audit("a message");

Resultando en

table1
custom_prop message
=========== =======
value       a message

Por lo tanto, no sé si es un problema con el AdoNetAppender o mis clases extendidas o mi configuración.

Answer 1

El problema se debió a un aborto temprano del subproceso en primer plano. Creo que log4net estaba ejecutando una operación asíncrona. Poner en reposo al final de la aplicación de prueba solucionó el problema

...
log4net.ThreadContext.Properties["custom_prop"] = "value";
log.Audit("a message");
...
Thread.Sleep(1000);

o, cuando estés en un hilo separado, uniéndote a él

Thread t = new Thread(new ThreadStart(delegate
{
    log4net.ThreadContext.Properties["custom_prop"] = "value";
    log.Audit("a message");        
}));
t.Start();
...
t.Join();

En el funcionamiento normal, log4net debe tener tiempo suficiente entre la última llamada de AUDIT y el final de la ejecución.