<html xmlns:o="urn:schemas-microsoft-com:office:office" xmlns:w="urn:schemas-microsoft-com:office:word" xmlns:m="http://schemas.microsoft.com/office/2004/12/omml" xmlns="http://www.w3.org/TR/REC-html40">
<head>
<meta http-equiv="Content-Type" content="text/html; charset=utf-8">
<meta name="Generator" content="Microsoft Word 15 (filtered medium)">
<style><!--
/* Font Definitions */
@font-face
{font-family:"Cambria Math";
panose-1:2 4 5 3 5 4 6 3 2 4;}
@font-face
{font-family:Calibri;
panose-1:2 15 5 2 2 2 4 3 2 4;}
@font-face
{font-family:Monaco;
panose-1:0 0 0 0 0 0 0 0 0 0;}
@font-face
{font-family:Menlo;
panose-1:2 11 6 9 3 8 4 2 2 4;}
/* Style Definitions */
p.MsoNormal, li.MsoNormal, div.MsoNormal
{margin:0cm;
font-size:11.0pt;
font-family:"Calibri",sans-serif;
mso-fareast-language:EN-US;}
pre
{mso-style-priority:99;
mso-style-link:"HTML Preformatted Char";
margin:0cm;
margin-bottom:.0001pt;
font-size:10.0pt;
font-family:"Courier New";}
span.EmailStyle17
{mso-style-type:personal-compose;
font-family:"Calibri",sans-serif;
color:windowtext;}
span.HTMLPreformattedChar
{mso-style-name:"HTML Preformatted Char";
mso-style-priority:99;
mso-style-link:"HTML Preformatted";
font-family:"Courier New";
mso-fareast-language:EN-GB;}
p.p1, li.p1, div.p1
{mso-style-name:p1;
margin:0cm;
background:white;
font-size:10.5pt;
font-family:Menlo;
color:black;}
span.s1
{mso-style-name:s1;}
span.apple-converted-space
{mso-style-name:apple-converted-space;}
.MsoChpDefault
{mso-style-type:export-only;
font-family:"Calibri",sans-serif;
mso-fareast-language:EN-US;}
@page WordSection1
{size:612.0pt 792.0pt;
margin:72.0pt 72.0pt 72.0pt 72.0pt;}
div.WordSection1
{page:WordSection1;}
--></style>
</head>
<body lang="en-FR" link="#0563C1" vlink="#954F72" style="word-wrap:break-word">
<div class="WordSection1">
<p class="MsoNormal"><span lang="FR">Hello,<o:p></o:p></span></p>
<p class="MsoNormal"><span lang="FR"><o:p> </o:p></span></p>
<p class="MsoNormal"><span lang="EN-US">I am trying to understand why there is a difference between the logs from a simple erl node compared to a node that has logger configuration with a handler that customizes the output and logs to a file.<o:p></o:p></span></p>
<p class="MsoNormal"><span lang="EN-US"><o:p> </o:p></span></p>
<p class="MsoNormal"><span lang="EN-US">The message logged comes from a handshake error due to a bad certificate when another node is trying to connect through the TLS distribution.<o:p></o:p></span></p>
<p class="MsoNormal"><span lang="EN-US"><o:p> </o:p></span></p>
<p class="MsoNormal"><span lang="EN-US">On the simple erl node we get the following error:<o:p></o:p></span></p>
<p class="MsoNormal"><span lang="EN-US"><o:p> </o:p></span></p>
<p class="MsoNormal" style="mso-margin-top-alt:3.0pt;margin-right:0cm;margin-bottom:3.0pt;margin-left:0cm">
<span style="font-size:9.0pt;font-family:Monaco;color:#1D1C1D;mso-fareast-language:EN-GB">=NOTICE REPORT==== 14-Oct-2021::11:23:43.108059 ===<o:p></o:p></span></p>
<p class="MsoNormal" style="mso-margin-top-alt:3.0pt;margin-right:0cm;margin-bottom:3.0pt;margin-left:0cm">
<span style="font-size:9.0pt;font-family:Monaco;color:#1D1C1D;mso-fareast-language:EN-GB">TLS client: In state certify at ssl_handshake.erl:1901 generated CLIENT ALERT: Fatal - Handshake Failure<o:p></o:p></span></p>
<p class="MsoNormal" style="mso-margin-top-alt:3.0pt;margin-right:0cm;margin-bottom:3.0pt;margin-left:0cm">
<span style="font-size:9.0pt;font-family:Monaco;color:#1D1C1D;mso-fareast-language:EN-GB">- {bad_cert,hostname_check_failed}<o:p></o:p></span></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal"><span lang="EN-US">On the node with logger configuration we get this :<o:p></o:p></span></p>
<p class="MsoNormal"><span lang="EN-US"><o:p> </o:p></span></p>
<pre style="mso-margin-top-alt:3.0pt;margin-right:0cm;margin-bottom:3.0pt;margin-left:0cm"><span style="font-size:9.0pt;font-family:Monaco;color:#1D1C1D">2021-10-14 11:23:43.108216+02:00 [noti] <0.575.0> TLS server: In state certify received CLIENT ALERT: Fatal - Handshake Failure<o:p></o:p></span></pre>
<pre style="mso-margin-top-alt:3.0pt;margin-right:0cm;margin-bottom:3.0pt;margin-left:0cm"><span style="font-size:9.0pt;font-family:Monaco;color:#1D1C1D">2021-10-14 11:23:43.108216+02:00 [noti] <0.575.0><o:p></o:p></span></pre>
<p class="MsoNormal"><span lang="EN-US"><o:p> </o:p></span></p>
<p class="MsoNormal"><span lang="EN-US">We are missing the bad_cert line and that’s bad because that line is fairly important for debugging the issue.<o:p></o:p></span></p>
<p class="MsoNormal"><span lang="EN-US"><o:p> </o:p></span></p>
<p class="MsoNormal"><span lang="EN-US">I have tracked down the error up until the call to ssl_logger:format/1. It receives the following argument:<o:p></o:p></span></p>
<p class="MsoNormal"><span lang="EN-US"><o:p> </o:p></span></p>
<p class="p1"><span class="s1">#{alert =></span><o:p></o:p></p>
<p class="p1"><span class="apple-converted-space"> </span><span class="s1">{alert,2,40,</span><o:p></o:p></p>
<p class="p1"><span class="apple-converted-space"> </span><span class="s1">#{file => "ssl_alert.erl",line => 136,</span><o:p></o:p></p>
<p class="p1"><span class="apple-converted-space"> </span><span class="s1">mfa => {ssl_alert,decode,3}},</span><o:p></o:p></p>
<p class="p1"><span class="apple-converted-space"> </span><span class="s1">client,undefined},</span><o:p></o:p></p>
<p class="p1"><span class="apple-converted-space"> </span><span class="s1">alerter => peer,protocol => "TLS",role => server,</span><o:p></o:p></p>
<p class="p1"><span class="apple-converted-space"> </span><span class="s1">statename => certify}</span><o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal"><span lang="EN-US">As far as I can tell, based on this data, the output is expected.<o:p></o:p></span></p>
<p class="MsoNormal"><span lang="EN-US"><o:p> </o:p></span></p>
<p class="MsoNormal"><span lang="EN-US">For what it’s worth, the Meta information doesn’t have the extra info either:<o:p></o:p></span></p>
<p class="MsoNormal"><span lang="EN-US"><o:p> </o:p></span></p>
<p class="p1"><span class="s1">#{depth => 20,file => "ssl_alert.erl",</span><o:p></o:p></p>
<p class="p1"><span class="apple-converted-space">
</span><span class="s1">gl => <0.46.0>,line => 136,</span><o:p></o:p></p>
<p class="p1"><span class="apple-converted-space">
</span><span class="s1">mfa => {ssl_alert,decode,3},</span><o:p></o:p></p>
<p class="p1"><span class="apple-converted-space">
</span><span class="s1">pid => <0.690.0>,</span><o:p></o:p></p>
<p class="p1"><span class="apple-converted-space">
</span><span class="s1">report_cb => fun ssl_logger:format/1,</span><o:p></o:p></p>
<p class="p1"><span class="apple-converted-space">
</span><span class="s1">time => 1634210440978557}</span><o:p></o:p></p>
<p class="MsoNormal"><span lang="EN-US"><o:p> </o:p></span></p>
<p class="MsoNormal"><span lang="EN-US">My question therefore is: how can we get the {bad_cert,hostname_check_failed} line logged as well with our custom logger handler? Why is it in the notice report above but not in the logger event?<o:p></o:p></span></p>
<p class="MsoNormal"><span lang="EN-US"><o:p> </o:p></span></p>
<p class="MsoNormal"><span lang="EN-US">Thanks,<o:p></o:p></span></p>
<p class="MsoNormal"><o:p> </o:p></p>
<div>
<div>
<p class="MsoNormal"><span style="mso-fareast-language:EN-GB">-- <o:p></o:p></span></p>
</div>
</div>
<p class="MsoNormal"><span lang="EN-US">Loïc Hoguin</span><o:p></o:p></p>
</div>
</body>
</html>