[krbdev.mit.edu #3277] doubled kerberos logging to stderr

[Denis Vlasenko via RT]

On Saturday 17 December 2005 04:19, Ken Raeburn wrote:
> Could you try setting a breakpoint (or instrumenting the code) to see  
> what profile_init is getting called with?  (The first argument is  
> char **, points to a null-terminated list of string pointers.)
> 
> It should be a list of filenames; my hunch is somehow you've got the  
> same file being used twice, or the same data in two files.  (They're  
> merged, it's not using just the first one found.)  If you could show  
> us the names and the files, that may help solve the problem...

I instrumented code as shown in attached patch.

log of "env - krb5kdc -n" running (failing to start, in fact, because
of misconfiguration). Timestamps are added by logging daemon:

2005-12-18 14:26:49.560868500 conf_files
2005-12-18 14:26:49.561030500 conf_file:/etc/krb5.conf
2005-12-18 14:26:49.561846500 conf_file:/etc/krb5.conf
2005-12-18 14:26:49.562310500 kdc
2005-12-18 14:26:49.562357500 krb5kdc
2005-12-18 14:26:49.562407500 FILE:/proc/self/fd/2
2005-12-18 14:26:49.562564500 FILE:/proc/self/fd/2
2005-12-18 14:26:49.562744500 FILE
2005-12-18 14:26:49.562795500 FILE
2005-12-18 14:26:49.562928500 conf_files
2005-12-18 14:26:49.562954500 conf_file:/usr/app/krb5-1.4.3/var/krb5kdc/kdc.conf - can't read it
2005-12-18 14:26:49.563218500 conf_files
2005-12-18 14:26:49.563244500 conf_file:/etc/krb5.conf
2005-12-18 14:26:49.563313500 conf_file:/etc/krb5.conf
2005-12-18 14:26:49.563485500 conf_files
2005-12-18 14:26:49.563509500 conf_file:/usr/app/krb5-1.4.3/var/krb5kdc/kdc.conf - can't read it
2005-12-18 14:26:49.564024500 krb5kdc: Cannot find/read stored master key - while fetching master key K/M for realm ILPORT.INTERNAL
2005-12-18 14:26:49.564092500 krb5kdc: Cannot find/read stored master key - while fetching master key K/M for realm ILPORT.INTERNAL
2005-12-18 14:26:49.564164500 krb5kdc: cannot initialize realm ILPORT.INTERNAL - see log file for details

IOW: it does list /etc/krb5.conf twice.

same log but with "env - KRB5_CONFIG=/etc/krb5.conf krb5kdc -n" run:

2005-12-18 14:27:43.615314500 conf_files
2005-12-18 14:27:43.615319500 conf_file:/etc/krb5.conf
2005-12-18 14:27:43.615321500 kdc
2005-12-18 14:27:43.615322500 krb5kdc
2005-12-18 14:27:43.615324500 FILE:/proc/self/fd/2
2005-12-18 14:27:43.615325500 FILE
2005-12-18 14:27:43.615326500 conf_files
2005-12-18 14:27:43.615327500 conf_file:/usr/app/krb5-1.4.3/var/krb5kdc/kdc.conf - can't read it
2005-12-18 14:27:43.615330500 conf_files
2005-12-18 14:27:43.615331500 conf_file:/etc/krb5.conf
2005-12-18 14:27:43.615333500 conf_files
2005-12-18 14:27:43.615334500 conf_file:/usr/app/krb5-1.4.3/var/krb5kdc/kdc.conf - can't read it
2005-12-18 14:27:43.615467500 krb5kdc: Cannot find/read stored master key - while fetching master key K/M for realm ILPORT.INTERNAL
2005-12-18 14:27:43.615470500 krb5kdc: cannot initialize realm ILPORT.INTERNAL - see log file for details
--
vda