Item10106: foswiki-ldapcontrib errors on ldap cache update

We have encountered what looks like a bug in foswiki-ldapcontrib. foswiki-ldapcontrib_091118-212_all.deb works for us without errors. However, with version foswiki-ldapcontrib_101123-212_all.deb, when our ldap cache seeks to update itself, we get the following:

[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view: Can't call method "normalizeWebTopicName" on an undefined value at /var/lib/foswiki/lib/Foswiki/Func.pm line 1197, <DATA> line 522., referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome
[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view:  at /var/lib/foswiki/lib/Foswiki/Func.pm line 1197, referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome
[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view: \tFoswiki::Func::topicExists('Main', 'ESCAPESFTPTransfer') called at /var/lib/foswiki/lib/Foswiki/Contrib/LdapContrib.pm line 1316, referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome
[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view: \tFoswiki::Contrib::LdapContrib::normalizeWikiName('Foswiki::Contrib::LdapContrib=HASH(0x2ab6978)', ' ESCAPE (SFTP) Transfer') called at /var/lib/foswiki/lib/Foswiki/Contrib/LdapContrib.pm line 1087, referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome
[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view: \tFoswiki::Contrib::LdapContrib::cacheUserFromEntry('Foswiki::Contrib::LdapContrib=HASH(0x2ab6978)', 'Net::LDAP::Entry=HASH(0x2bdad38)', 'HASH(0x2951350)', 'HASH(0x295cfe8)', 'HASH(0x295d030)') called at /var/lib/foswiki/lib/Foswiki/Contrib/LdapContrib.pm line 787, referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome
[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view: \tFoswiki::Contrib::LdapContrib::refreshUsersCache('Foswiki::Contrib::LdapContrib=HASH(0x2ab6978)', 'HASH(0x2951350)') called at /var/lib/foswiki/lib/Foswiki/Contrib/LdapContrib.pm line 694, referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome
[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view: \tFoswiki::Contrib::LdapContrib::refreshCache('Foswiki::Contrib::LdapContrib=HASH(0x2ab6978)') called at /var/lib/foswiki/lib/Foswiki/Contrib/LdapContrib.pm line 658, referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome
[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view: \tFoswiki::Contrib::LdapContrib::initCache('Foswiki::Contrib::LdapContrib=HASH(0x2ab6978)') called at /var/lib/foswiki/lib/Foswiki/Contrib/LdapContrib.pm line 287, referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome
[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view: \tFoswiki::Contrib::LdapContrib::getLdapContrib('Foswiki=HASH(0x183df30)') called at /var/lib/foswiki/lib/Foswiki/Users/LdapPasswdUser.pm line 59, referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome
[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view: \tFoswiki::Users::LdapPasswdUser::new('Foswiki::Users::LdapPasswdUser', 'Foswiki=HASH(0x183df30)') called at /var/lib/foswiki/lib/Foswiki/Users/TopicUserMapping.pm line 83, referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome
[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view: \tFoswiki::Users::TopicUserMapping::new('Foswiki::Users::LdapUserMapping', 'Foswiki=HASH(0x183df30)') called at /var/lib/foswiki/lib/Foswiki/Users/LdapUserMapping.pm line 52, referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome
[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view: \tFoswiki::Users::LdapUserMapping::new('Foswiki::Users::LdapUserMapping', 'Foswiki=HASH(0x183df30)') called at /var/lib/foswiki/lib/Foswiki/Users.pm line 123, referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome
[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view: \tFoswiki::Users::new('Foswiki::Users', 'Foswiki=HASH(0x183df30)') called at /var/lib/foswiki/lib/Foswiki.pm line 1442, referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome
[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view: \tFoswiki::new('Foswiki', undef, 'Foswiki::Request=HASH(0x1e276e8)', 'HASH(0x1a93888)') called at /var/lib/foswiki/lib/Foswiki/UI.pm line 293, referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome
[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view: \tFoswiki::UI::_execute('Foswiki::Request=HASH(0x1e276e8)', 'CODE(0x1e27088)', 'view', 1) called at /var/lib/foswiki/lib/Foswiki/UI.pm line 275, referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome
[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view: \tFoswiki::UI::handleRequest('Foswiki::Request=HASH(0x1e276e8)') called at /var/lib/foswiki/lib/Foswiki/Engine/CGI.pm line 29, referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome
[Wed Dec 01 15:09:36 2010] [error] [client 129.255.60.41] [Wed Dec  1 15:09:36 2010] view: \tFoswiki::Engine::CGI::run('Foswiki::Engine::CGI=HASH(0x1c4ef40)') called at /var/lib/foswiki/bin/view line 45, referer: https://www.i-clic.uihc.uiowa.edu/wiki/bin/view/Main/WebHome

When this error occurs, the cache.db_tmp (generated during the cache update) is not deleted. Therefore, it seems, the cache update is never again attempted, because it sees the presence of this tmp file. Therefore, the cache becomes increasingly more and more stale. If you then delete this old tmp file, the cache will again attempt to update. However, the errors above are regenerated. Finally, if I downgrade the deb package to foswiki-ldapcontrib_091118-212_all.deb, these errors go away and the cache update process is again successful.

-- BryanWalton - 01 Dec 2010

Also reported in Item10104.

Bryan, please upgrade to the latest LdapContrib. This has been fixed there. And let me know if that cured the problem.

-- MichaelDaum - 02 Dec 2010

Hi Michael,

Thanks for the update. This new package does seem to eliminate the errors shown above. However, this package seems to have a different problem now. After installing it, the LDAP groups are not seen at all. I have {Ldap}{MapGroups} turned on, but the WikiGroups page doesn't show them anymore. Again, if I downgrade the ldapcontrib package to 091118-212, the groups all return. Please let me know if I can provide more information for you. Thanks.

-- BryanWalton - 02 Dec 2010

Hi Bryan, that's a known error in 4.20 which has been fixed yesterday releasing 4.21. See Item10108. Okay, so I will close this one. Please open another bug item if you have got more. Thanks.

-- MichaelDaum - 03 Dec 2010

Commenting is disabled while not logged in