On Wed, 2013-02-20 at 23:56 +0100, Rainer Jung wrote:


That's strange, the additional stderr output

"crypt_r returned NULL"

or

"crypt_r returned '%s'"

is not shown here.


Indeed, I'm running :
LogLevel debug auth_basic:trace8 authn_dbd:trace8

Briefly ran trace8 globally, but only briefly for obvious reasons, my eyes were starting to bleed :)

As an alternative one could use strace to check the call to crypt_r and

strace only shows...

29311 gettimeofday({1361405772, 894610}, NULL) = 0
29311 poll([{fd=17, events=POLLIN|POLLPRI}], 1, 0) = 0 (Timeout)
29311 write(17, "*\0\0\0\26SELECT Password FROM users WHERE User = ?"..., 46) = 46
29311 read(17, "\f\0\0\1\0\1\0\0\0\1\0\1\0\0\0\0\27\0\0\2\3def\0\0\0\1?\0\f?\0\0\0\0\0\375\200\0\0\0\0\5\0\0\3\376\0\0\2\0007\0\0\4\3def\7members\5users\5users\10Password\10Password\f\10\0\0\1\0\0\375\201\20\0\0\0\5\0\0\5\376\0\0\2\0"..., 16384) = 120
29311 poll([{fd=17, events=POLLIN|POLLPRI}], 1, 0) = 0 (Timeout)
29311 poll([{fd=17, events=POLLIN|POLLPRI}], 1, 0) = 0 (Timeout)
29311 write(17, "\23\0\0\0\27\1\0\0\0\0\1\0\0\0\0\1\375\0\4noel"..., 23) = 23
29311 read(17, "\1\0\0\1\0017\0\0\2\3def\7members\5users\5users\10Password\10Password\f\10\0\0\1\0\0\375\201\20\0\0\0\5\0\0\3\376\0\0\2\0m\0\0\4\0\0j$6$xxxxxxxxxxxx\5\0\0\5\376\0\0\2\0"..., 16384) = 195
29311 gettimeofday({1361405772, 895721}, NULL) = 0
29311 write(8, "[Thu Feb 21 10:16:12.895721 2013] [authn_dbd:trace2] [pid 29307:tid 3046349680] mod_authn_dbd.c(178): [client fd1d:c01d:1ce::145:59592] Got hashed password '$6$xxxxxxxxxxxxx' for user 'noel'\n"..., 281) = 281
29311 gettimeofday({1361405772, 895975}, NULL) = 0
29311 write(8, "[Thu Feb 21 10:16:12.895975 2013] [authn_dbd:debug] [pid 29307:tid 3046349680] mod_authn_dbd.c(199): (70024)passwords do not match: [client fd1d:c01d:1ce::145:59592] Call to apr_password_validate for user 'noel' and hashed password '$6$xxxxxxxxxxx"..., 368) = 368
29311 gettimeofday({1361405772, 896212}, NULL) = 0
29311 write(8, "[Thu Feb 21 10:16:12.896212 2013] [auth_basic:trace1] [pid 29307:tid 3046349680] mod_auth_basic.c(246): [client fd1d:c01d:1ce::145:59592] Checking password for user 'noel' using provider 'dbd', result: 0\n"..., 204) = 204
29311 gettimeofday({1361405772, 896399}, NULL) = 0
29311 write(8, "[Thu Feb 21 10:16:12.896399 2013] [auth_basic:error] [pid 29307:tid 3046349680] [client fd1d:c01d:1ce::145:59592] AH01617: user noel: authentication failure for \"/\": Password Mismatch\n"..., 184) = 184
29311 gettimeofday({1361405772, 896750}, NULL) = 0
29311 read(16, 0x8537248, 8000)         = -1 EAGAIN (Resource temporarily unavailable)
29311 gettimeofday({1361405772, 896880}, NULL) = 0
29311 gettimeofday({1361405772, 896933}, NULL) = 0


BTW I am now on dev@apr, I'll leave it to you Rainer if you want this continued on both or either lists.
Cheers
Noel