2007-10-18 09:51 AEST by Arthur Barrett - If there are "conflicting" permissions (eg: the aclmode=compat, and the root 
has an inheritable "none" permission for the user but the module has a "read" 
permission) then the server does not return a correct "error", but also does 
not return any items to checkout.

2007-10-18 09:53 AEST by Arthur Barrett - 11:49:51: S -> run template proc
11:49:51: S -> run_trigger()
.
.
.
11:49:51: S -> run_trigger returning 0
11:49:51: S -> Name_Repository((null),test)
11:49:51: S -> map_repository(d:/acltest/test)
11:49:51: S -> lookup_module2 - continue_if_no_mod2=1 (default)
11:49:51: S -> lookup_module2(test,3)
11:49:51: S -> lookup_module2 !mod return 0 left,right(,test)
11:49:51: S -> Repository = d:/acltest/test
11:49:51: S -> Mapped repository = d:/acltest/test
11:49:51: S -> verify_access d:/acltest/test,test,BRANCH_A_ONE
11:49:51: S -> verify_perm(d:/acltest/test,(null),read,BRANCH_A_ONE,(null))
11:49:51: S -> verify_owner(d:/acltest/test)
11:49:51: S -> Find all the groups the user is in and add them to the list of 
valid names.
11:49:51: S -> cache_directory_permissions(d:/acltest/test)
11:49:51: S -> verify_valid_name(Administrator)
11:49:51: S -> Find all the groups the user is in and add them to the list of 
valid names.
11:49:51: S -> cache_directory_permissions(d:/acltest/test)
11:49:51: S -> ACL lookup on directory d:/acltest/test
11:49:51: S -> verify_acl(read,BRANCH_A_ONE,(null))
11:49:51: S -> verify_valid_name(abarrett)
11:49:51: S -> matched ACL user=abarrett, branch=BRANCH_A_ONE, merge=
11:49:51: S -> calculated ACL priority is 14
11:49:51: S -> new max priority is 14
11:49:51: S -> matched ACL user=, branch=_default_, merge=
11:49:51: S -> calculated ACL priority is 0
11:49:51: S -> user_state = 1, group_state = 0
11:49:51: S -> fileattr_startdir(d:/acltest/test)
11:49:51: S -> fileattr_write()
11:49:51: S -> fileattr_free()
11:49:51: S -> verify_access d:/acltest/test,test,(null)
11:49:51: S -> verify_perm(d:/acltest/test,(null),read,(null),(null))
11:49:51: S -> verify_owner(d:/acltest/test)
11:49:51: S -> Find all the groups the user is in and add them to the list of 
valid names.
11:49:51: S -> cache_directory_permissions(d:/acltest/test)
11:49:51: S -> verify_valid_name(Administrator)
11:49:51: S -> Find all the groups the user is in and add them to the list of 
valid names.
11:49:51: S -> cache_directory_permissions(d:/acltest/test)
11:49:51: S -> ACL lookup on directory d:/acltest/test
11:49:51: S -> verify_acl(read,HEAD,(null))
11:49:51: S -> verify_valid_name(abarrett)
11:49:51: S -> matched ACL user=, branch=_default_, merge=
11:49:51: S -> calculated ACL priority is 0
11:49:51: S -> user_state = 0, group_state = 0
11:49:51: S -> no match at this level
11:49:51: S -> ACL lookup on directory d:/acltest
11:49:51: S -> verify_acl(read,HEAD,(null))
11:49:51: S -> verify_valid_name(abarrett)
11:49:51: S -> verify_valid_name(Administrator)
11:49:51: S -> verify_valid_name(abarrett)
11:49:51: S -> matched ACL user=abarrett, branch=HEAD, merge=
11:49:51: S -> calculated ACL priority is 14
11:49:51: S -> new max priority is 14
11:49:51: S -> verify_valid_name(Administrator)
11:49:51: S -> verify_valid_name(MARCH-HARE\abarrett)
11:49:51: S -> verify_valid_name(abarrett)
11:49:51: S -> matched ACL user=abarrett, branch=_default_, merge=
11:49:51: S -> calculated ACL priority is 10
11:49:51: S -> user_state = -1, group_state = 0
11:49:51: S -> fileattr_write()
11:49:51: S -> fileattr_free()
11:49:51: S -> run postmodule proc
11:49:51: S -> run_trigger()
11:49:51: S -> Call pre-loaded 'email.dll'
11:49:51: S -> postmodule_proc(test)
11:49:51: S -> Call pre-loaded 'email.dll' returned 0, total ret 0
11:49:51: S -> Call pre-loaded 'info.dll'
11:49:51: S -> postmodule_proc(test)
11:49:51: S -> default_trigger: parse_info(CVSROOT/postmodule,%r/%p %c %o,,test)
11:49:51: S -> Call pre-loaded 'info.dll' returned 0, total ret 0
11:49:51: S -> run_trigger returning 0
11:49:51: S -> run postcommand proc server
11:49:51: S -> run_trigger()
11:49:51: S -> Call pre-loaded 'email.dll'
11:49:51: S -> postcommand_proc()
11:49:51: S -> Call pre-loaded 'email.dll' returned 0, total ret 0
11:49:51: S -> Call pre-loaded 'info.dll'
11:49:51: S -> postcommand_proc()
11:49:51: S -> default_trigger: parse_info(CVSROOT/postcommand,%r/%p %c,,test)
11:49:51: S -> Call pre-loaded 'info.dll' returned 0, total ret 0
11:49:51: S -> run_trigger returning 0
11:49:51: S -> Unloading email.dll
11:49:51: S -> Unloading - about to close
11:49:51: S -> Unloading - closed
11:49:51: S -> Unloading - about to destroy
11:49:51: S -> Unloading - destroyed
11:49:51: S -> Unloading - about to get lib
11:49:51: S -> Unloading - about to unload
11:49:51: S -> Unloading - unloaded
11:49:51: S -> Unloading - about to free
11:49:51: S -> Unloading - about to delete trigg
11:49:51: S -> Unloading - about to delete inf
11:49:51: S -> Unloading - complete
11:49:51: S -> Unloading info.dll
11:49:51: S -> Unloading - about to close
11:49:51: S -> Unloading - closed
11:49:51: S -> Unloading - about to destroy
11:49:51: S -> Unloading - destroyed
11:49:51: S -> Unloading - about to get lib
11:49:51: S -> Unloading - about to unload
11:49:51: S -> Unloading - unloaded
11:49:51: S -> Unloading - about to free
11:49:51: S -> Unloading - about to delete trigg
11:49:51: S -> Unloading - about to delete inf
11:49:51: S -> Unloading - complete
11:49:51: S -> Unloading - about to clear list
11:49:51: S -> Unloading - cleared list
11:49:51:   -> Entries_Close - isfile ("CVS/Entries.Log")
11:49:51:   -> Entries_Close - write_entries (list)
11:49:51:   -> write_entries() CVSADM_ENTBAK entfilename="CVS/Entries.Log"
11:49:51:   -> write_entries() CVSADM_ENTBAK ok
11:49:51:   -> write_entries() CVSADM_ENTEXBAK ok
11:49:51:   -> write_entries() !sawdir
11:49:51:   -> write_entries() now, atomically (on systems that support it) rena
me it
11:49:51:   -> rename(CVS/Entries.Backup,CVS/Entries)
11:49:51:   -> wnt_rename(CVS/Entries.Backup,CVS/Entries)
11:49:51:   -> rename(CVS/Entries.Extra.Backup,CVS/Entries.Extra)
11:49:51:   -> wnt_rename(CVS/Entries.Extra.Backup,CVS/Entries.Extra)
11:49:51:   -> write_entries() now, remove the log file
11:49:51:   -> unlink_file(CVS/Entries.Log)
11:49:51:   -> unlink_file(CVS/Entries.Extra.Log)
11:49:51:   -> write_entries() done
11:49:51:   -> Entries_Close - dellist (list)
11:49:51:   -> Entries_Open()
11:49:51:   -> Entries_Open ParseTag()
11:49:51:   -> Entries_Open aflag || dirtag || dirdate
11:49:51:   -> Entries_Open CVS_FOPEN("CVS/Entries","r")
11:49:51:   -> Entries_Open CVS_FOPEN CVSADM_ENT returned a file handle, now cal
l the (slow) fgetentent() / AddEntryNode
11:49:51:   -> Entries_Open fclose() CVSADM_ENT
11:49:51:   -> Entries_Open CVS_FOPEN("CVS/Entries.Extra","r")
11:49:51:   -> Entries_Open CVS_FOPEN CVSADM_ENTEXT returned a file handle
11:49:51:   -> Entries_Open fclose() CVSADM_ENTEXT
11:49:51:   -> Entries_Open CVS_FOPEN("CVS/Entries.Log","r")
11:49:51:   -> Entries_Open clean up and return
11:49:51:   -> Entries_Open done
11:49:51:   -> Entries_Close - isfile ("CVS/Entries.Log")
11:49:51:   -> Entries_Close - dellist (list)
11:49:51:   -> Entries_Open()
11:49:51:   -> Entries_Open ParseTag()
11:49:51:   -> Entries_Open CVS_FOPEN("CVS/Entries","r")
cvs checkout: in directory .:
cvs checkout: cannot open CVS/Entries for reading: No such file or directory

2007-10-18 09:55 AEST by Arthur Barrett - Created an attachment (id=1086)
trace as attachment

2007-10-18 10:01 AEST by Arthur Barrett - Added debugging to help resolve this in bug 5096.