Bug 1614820

Summary: 389-ds-base: Crash in vslapd_log_emergency_error [rhel-7.6]
Product: Red Hat Enterprise Linux 7 Reporter: German Parente <gparente>
Component: 389-ds-baseAssignee: mreynolds
Status: CLOSED ERRATA QA Contact: RHDS QE <ds-qe-bugs>
Severity: high Docs Contact: Marc Muehlfeld <mmuehlfe>
Priority: high    
Version: 7.7-AltCC: dmoppert, mhonek, mreynolds, msauton, nkinder, rmeggins, sfowler, spichugi, tbordaz, vashirov
Target Milestone: rcKeywords: Security, SecurityTracking, ZStream
Target Release: ---   
Hardware: All   
OS: Linux   
Whiteboard:
Fixed In Version: 389-ds-base-1.3.8.4-11.el7 Doc Type: If docs needed, set a value
Doc Text:
Story Points: ---
Clone Of:
: 1623247 (view as bug list) Environment:
Last Closed: 2018-10-30 10:15:00 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:
Bug Depends On:    
Bug Blocks: 1619450, 1623247    
Attachments:
Description Flags
core_backtrace of the coredump
none
another stacktrace. none

Description German Parente 2018-08-10 13:40:54 UTC 
Description of problem:

we can see a crash in directry with this stacktrace:


(gdb) bt
#0  0x00007f748a9c1373 in PR_Write (fd=0x55683c42e4e0, buf=0x7f742b404bd0, amount=107) at ../../../nspr/pr/src/io/priometh.c:114
#1  0x000055683a6458a5 in slapi_write_buffer (fd=<optimized out>, buf=<optimized out>, amount=<optimized out>) at ldap/servers/slapd/fileio.c:48
#2  0x00007f748ca6c66b in vslapd_log_emergency_error (fp=0x55683c42e4e0, msg=0x7f748cade1a8 "Insufficent buffer capacity to fit timestamp and message!", locked=0) at ldap/servers/slapd/log.c:2260
#3  0x00007f748ca73779 in vslapd_log_access (fmt=fmt@entry=0x7f748cadf994 "conn=%lu op=%d MOD dn=\"%s\"%s\n", ap=ap@entry=0x7f742b4064a0) at ldap/servers/slapd/log.c:2535
#4  0x00007f748ca74971 in slapi_log_access (level=level@entry=256, fmt=fmt@entry=0x7f748cadf994 "conn=%lu op=%d MOD dn=\"%s\"%s\n") at ldap/servers/slapd/log.c:2568
#5  0x00007f748ca7c15f in op_shared_modify (pb=pb@entry=0x55683f960ea0, pw_change=pw_change@entry=0, old_pw=0x0) at ldap/servers/slapd/modify.c:668
#6  0x00007f748ca7e05b in do_modify (pb=pb@entry=0x55683f960ea0) at ldap/servers/slapd/modify.c:391
#7  0x000055683a63d2ee in connection_dispatch_operation (pb=0x55683f960ea0, op=0x55685ff5aa80, conn=0x55683e872780) at ldap/servers/slapd/connection.c:625
#8  0x000055683a63d2ee in connection_threadmain () at ldap/servers/slapd/connection.c:1785
#9  0x00007f748a9db9bb in _pt_root (arg=0x55683f95ed00) at ../../../nspr/pr/src/pthreads/ptthread.c:216
#10 0x00007f748a37be25 in start_thread (arg=0x7f742b407700) at pthread_create.c:396
#11 0x00007f7489c5d34d in lsetxattr () at ../sysdeps/unix/syscall-template.S:81
#12 0x0000000000000000 in None ()



Version-Release number of selected component (if applicable): 389-ds-base-1.3.7.5-24.el7_5.x86_64


How reproducible: not easily.
Comment 3 German Parente 2018-08-10 13:48:22 UTC 
Created attachment 1475048 [details]
core_backtrace of the coredump
Comment 4 German Parente 2018-08-10 13:48:53 UTC 
Created attachment 1475049 [details]
another stacktrace.
Comment 6 mreynolds 2018-08-17 20:03:12 UTC 
I can not reproduce the issue.  I can get the error to be logged by adding an entry with a really large DN:

[17/Aug/2018:15:56:33.905658222 -0400]  - EMERG - Insufficent buffer capacity to fit timestamp and message!

But no crash.  The core dump shows the error log FD is corrupted and trying to write to it fails, but I don't know how that happened.   I think this is a case where we need valgrind or an ASAN build to make any progress on this.
Comment 15 Viktor Ashirov 2018-08-30 13:00:56 UTC 
Build tested: 389-ds-base-1.3.8.4-12.el7.x86_64

Reproducer from https://bugzilla.redhat.com/show_bug.cgi?id=1614820#c7 no longer crashes the server, error messages are formatted correctly:

[30/Aug/2018:12:59:44.570829721 +0000]  - EMERG - Insufficent buffer capacity to fit timestamp and message!
[30/Aug/2018:12:59:44.593972036 +0000]  - EMERG - Insufficent buffer capacity to fit timestamp and message!
[30/Aug/2018:12:59:44.610915031 +0000]  - EMERG - Insufficent buffer capacity to fit timestamp and message!

Marking as VERIFIED.
Comment 19 Tomas Hoger 2018-09-21 20:34:01 UTC 
*** Bug 1623721 has been marked as a duplicate of this bug. ***
Comment 22 errata-xmlrpc 2018-10-30 10:15:00 UTC 
Since the problem described in this bug report should be
resolved in a recent advisory, it has been closed with a
resolution of ERRATA.

For information on the advisory, and where to find the updated
files, follow the link below.

If the solution does not work for you, open a new bug report.

https://access.redhat.com/errata/RHSA-2018:3127