Mailing-List: contact log4net-user-help@logging.apache.org; run by ezmlm
Precedence: bulk
Reply-To: "Log4NET User" <log4net-user@logging.apache.org>
MIME-Version: 1.0
In-Reply-To: <969a7bab-789c-956f-d8ca-ca9ca7cf6482@1sig.cz>
References: <969a7bab-789c-956f-d8ca-ca9ca7cf6482@1sig.cz>
From: Dominik Psenner <dpsenner@gmail.com>
Date: Tue, 7 Feb 2017 14:53:05 +0100
Message-ID: <CA+0K2P_bEuWhiT3ph38tHdy9z+rM879XRmZUSoJg6gKk+zaPRA@mail.gmail.com>
Subject: Re: Performance of PatternLayout
To: Log4NET User <log4net-user@logging.apache.org>
Content-Type: multipart/alternative; boundary=001a114948f445bb8b0547f11156
archived-at: Tue, 07 Feb 2017 13:53:14 -0000

--001a114948f445bb8b0547f11156
Content-Type: text/plain; charset=UTF-8

I dont have the issue number at hand, but this story sounds familiar and
there should be one filed on jira. If you have valuable information to add,
(ideally a patch) please use the search function to find the issue.

On 7 Feb 2017 9:02 a.m., "Martin Kozubek" <kozubek@1sig.cz> wrote:

> Hello,
>
> I have noticed performance hit after adding a custom property and using a
> PatternConverter to render it. It's the same for any context
> property(global,thread,event).  If there is a %property{smthing} in
> PatternConverter's string, the logging process results into call of
> Principal.WindowsIdentity.GetCurrent().Name.
>
> The code containing this call is in the LoggingEvent class. It is getter
> of property UserName. Since log4net v 1.2.12 the
> LoggingEvent.CreateCompositeProperties() is getting this property.  The
> problem is that this method is called once for logging event whenever there
> is some %property{} to be rendered.
>
> This results in that even if I don't use the username, but I add and use
> some custom property in the event, the logging process is ~10 times slower.
>
> I can solve it by programming my own layout instead of PatternLayout, but
> I am wondering if is it an expected behavior. Should that be reported to
> developers?
>
> MK
>
>
>

--001a114948f445bb8b0547f11156
Content-Type: text/html; charset=UTF-8
Content-Transfer-Encoding: quoted-printable

<div dir=3D"auto">I dont have the issue number at hand, but this story soun=
ds familiar and there should be one filed on jira. If you have valuable inf=
ormation to add, (ideally a patch) please use the search function to find t=
he issue.</div><div class=3D"gmail_extra"><br><div class=3D"gmail_quote">On=
 7 Feb 2017 9:02 a.m., &quot;Martin Kozubek&quot; &lt;<a href=3D"mailto:koz=
ubek@1sig.cz">kozubek@1sig.cz</a>&gt; wrote:<br type=3D"attribution"><block=
quote class=3D"gmail_quote" style=3D"margin:0 0 0 .8ex;border-left:1px #ccc=
 solid;padding-left:1ex">Hello,<br>
<br>
I have noticed performance hit after adding a custom property and using a P=
atternConverter to render it. It&#39;s the same for any context property(gl=
obal,thread,event).<wbr>=C2=A0 If there is a %property{smthing} in PatternC=
onverter&#39;s string, the logging process results into call of Principal.W=
indowsIdentity.GetC<wbr>urrent().Name.<br>
<br>
The code containing this call is in the LoggingEvent class. It is getter of=
 property UserName. Since log4net v 1.2.12 the LoggingEvent.CreateComposite=
Pr<wbr>operties() is getting this property.=C2=A0 The problem is that this =
method is called once for logging event whenever there is some %property{} =
to be rendered.<br>
<br>
This results in that even if I don&#39;t use the username, but I add and us=
e some custom property in the event, the logging process is ~10 times slowe=
r.<br>
<br>
I can solve it by programming my own layout instead of PatternLayout, but I=
 am wondering if is it an expected behavior. Should that be reported to dev=
elopers?<br>
<br>
MK<br>
<br>
<br>
</blockquote></div></div>

--001a114948f445bb8b0547f11156--