Earlier on the log there are some references to some plugins not loading, eg this one: 2020-12-28 09:28:53,795 Trac[loader] DEBUG: Loading plugin "acct_mgr.opt.announcer.uid_chg" from "/..../trac/venv/lib/python2.7/site-packages" 2020-12-28 09:28:53,805 Trac[loader] DEBUG: Skipping "acct_mgr.opt.announcer.uid_chg = acct_mgr.opt.announcer.uid_chg [announcer]": DistributionNotFound: The 'TracAnnouncer; extra == "announcer"' distribution was not found and is required by the application
I haven't ever loaded or installed this plugin so I'm not sure why it's even being loaded, but maybe it's a core part of Trac? If so, does it matter if they don't load? Could one of these be behind my problem? On Monday, 28 December 2020 at 21:37:46 UTC+8 Rob Hills wrote: > Unfortunately the debug log doesn't seem to help much AFAICT. No > exceptions or errors seen. Here is the log from when I click on a > Milestone to edit it (some identifying details obfuscated)... > 2020-12-28 10:00:57,746 Trac[main] DEBUG: Dispatching <RequestWithSession > "GET '/milestone/Release%20Edition%205.3'"> > 2020-12-28 10:00:57,747 Trac[main] DEBUG: Chosen handler is <Component > trac.ticket.roadmap.MilestoneModule> > 2020-12-28 10:00:57,749 Trac[session] DEBUG: Retrieving session for ID > u'rob' > 2020-12-28 10:00:57,752 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing ACCTMGR_USER_ADMIN on None > 2020-12-28 10:00:57,752 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing MILESTONE_VIEW on <Resource > u'milestone:Release%20Edition%205.3'> > 2020-12-28 10:00:57,752 Trac[chrome] DEBUG: Prepare chrome data for request > 2020-12-28 10:00:57,754 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing ACCTMGR_CONFIG_ADMIN on None > 2020-12-28 10:00:57,754 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TRAC_ADMIN on <Resource u'admin:general/basics'> > 2020-12-28 10:00:57,754 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TRAC_ADMIN on <Resource u'admin:general/logging'> > 2020-12-28 10:00:57,755 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing PERMISSION_GRANT on <Resource u'admin:general/perm'> > 2020-12-28 10:00:57,755 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TRAC_ADMIN on <Resource u'admin:general/plugin'> > 2020-12-28 10:00:57,755 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_ADMIN on <Resource u'admin:ticket/components'> > 2020-12-28 10:00:57,755 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing MILESTONE_ADMIN on <Resource u'admin:ticket/milestones'> > 2020-12-28 10:00:57,755 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_ADMIN on <Resource u'admin:ticket/versions'> > 2020-12-28 10:00:57,756 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_ADMIN on <Resource u'admin:ticket/priority'> > 2020-12-28 10:00:57,756 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_ADMIN on <Resource u'admin:ticket/resolution'> > 2020-12-28 10:00:57,756 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_ADMIN on <Resource u'admin:ticket/severity'> > 2020-12-28 10:00:57,756 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_ADMIN on <Resource u'admin:ticket/type'> > 2020-12-28 10:00:57,757 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing VERSIONCONTROL_ADMIN on <Resource > u'admin:versioncontrol/repository'> > 2020-12-28 10:00:57,757 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TAGS_ADMIN on None > 2020-12-28 10:00:57,758 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing SEARCH_VIEW on None > 2020-12-28 10:00:57,759 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing ROADMAP_VIEW on None > 2020-12-28 10:00:57,759 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_VIEW on <Resource 'ticket'> > 2020-12-28 10:00:57,759 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing REPORT_VIEW on <Resource u'report:-1'> > 2020-12-28 10:00:57,759 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_CREATE on None > 2020-12-28 10:00:57,760 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TIMELINE_VIEW on <Resource 'timeline'> > 2020-12-28 10:00:57,760 Trac[PyGIT] DEBUG: PyGIT.Storage instance for > '/..../repos/git/wacg/wacg_tracker.git' is constructed > 2020-12-28 10:00:57,761 Trac[PyGIT] DEBUG: requested weak PyGIT.Storage > instance for '/..../repos/git/wacg/wacg_tracker.git' > 2020-12-28 10:00:57,761 Trac[git_fs] DEBUG: disabled CachedRepository for > '/..../repos/git/wacg/wacg_tracker.git' > 2020-12-28 10:00:57,761 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing BROWSER_VIEW on <Resource u'repository, source:/'> > 2020-12-28 10:00:57,761 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing WIKI_VIEW on <Resource u'wiki:WikiStart'> > 2020-12-28 10:00:57,762 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing WIKI_VIEW on <Resource u'wiki:TracGuide'> > 2020-12-28 10:00:57,762 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_VIEW_HOURS on None > 2020-12-28 10:00:57,762 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TAGS_VIEW on None > 2020-12-28 10:00:57,763 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing MILESTONE_CREATE on <Resource > u'milestone:Release%20Edition%205.3'> > 2020-12-28 10:00:57,769 Trac[main] DEBUG: Rendering response with template > milestone_edit.html > 2020-12-28 10:00:57,855 Trac[main] DEBUG: Dispatching <RequestWithSession > "GET '/chrome/site/your_project_logo.png'"> > 2020-12-28 10:00:57,856 Trac[main] DEBUG: Chosen handler is <Component > trac.web.chrome.Chrome> > 2020-12-28 10:00:57,857 Trac[session] DEBUG: Retrieving session for ID > u'rob' > 2020-12-28 10:00:57,859 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing ACCTMGR_USER_ADMIN on None > 2020-12-28 10:00:57,859 Trac[chrome] WARNING: File your_project_logo.png > not found in any of ['/..../trac/venv/trac_project/htdocs'] > 2020-12-28 10:00:57,860 Trac[main] WARNING: [124.188.231.240] > HTTPNotFound: 404 Not Found (File your_project_logo.png not found), > <RequestWithSession "GET '/chrome/site/your_project_logo.png'">, referrer ' > http://tracker.blah.thing.com.au/milestone/Release%20Edition%205.3' > 2020-12-28 10:00:57,860 Trac[chrome] DEBUG: Prepare chrome data for request > 2020-12-28 10:00:57,862 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing ACCTMGR_CONFIG_ADMIN on None > 2020-12-28 10:00:57,862 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TRAC_ADMIN on <Resource u'admin:general/basics'> > 2020-12-28 10:00:57,862 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TRAC_ADMIN on <Resource u'admin:general/logging'> > 2020-12-28 10:00:57,862 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing PERMISSION_GRANT on <Resource u'admin:general/perm'> > 2020-12-28 10:00:57,862 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TRAC_ADMIN on <Resource u'admin:general/plugin'> > 2020-12-28 10:00:57,863 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_ADMIN on <Resource u'admin:ticket/components'> > 2020-12-28 10:00:57,863 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing MILESTONE_ADMIN on <Resource u'admin:ticket/milestones'> > 2020-12-28 10:00:57,863 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_ADMIN on <Resource u'admin:ticket/versions'> > 2020-12-28 10:00:57,863 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_ADMIN on <Resource u'admin:ticket/priority'> > 2020-12-28 10:00:57,863 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_ADMIN on <Resource u'admin:ticket/resolution'> > 2020-12-28 10:00:57,863 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_ADMIN on <Resource u'admin:ticket/severity'> > 2020-12-28 10:00:57,864 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_ADMIN on <Resource u'admin:ticket/type'> > 2020-12-28 10:00:57,864 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing VERSIONCONTROL_ADMIN on <Resource > u'admin:versioncontrol/repository'> > 2020-12-28 10:00:57,864 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TAGS_ADMIN on None > 2020-12-28 10:00:57,865 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing SEARCH_VIEW on None > 2020-12-28 10:00:57,865 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing ROADMAP_VIEW on None > 2020-12-28 10:00:57,866 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_VIEW on <Resource 'ticket'> > 2020-12-28 10:00:57,866 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing REPORT_VIEW on <Resource u'report:-1'> > 2020-12-28 10:00:57,866 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_CREATE on None > 2020-12-28 10:00:57,867 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TIMELINE_VIEW on <Resource 'timeline'> > 2020-12-28 10:00:57,867 Trac[PyGIT] DEBUG: PyGIT.Storage instance for > '/..../repos/git/wacg/wacg_tracker.git' is constructed > 2020-12-28 10:00:57,867 Trac[PyGIT] DEBUG: requested weak PyGIT.Storage > instance for '/..../repos/git/wacg/wacg_tracker.git' > 2020-12-28 10:00:57,868 Trac[git_fs] DEBUG: disabled CachedRepository for > '/..../repos/git/wacg/wacg_tracker.git' > 2020-12-28 10:00:57,868 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing BROWSER_VIEW on <Resource u'repository, source:/'> > 2020-12-28 10:00:57,868 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing WIKI_VIEW on <Resource u'wiki:WikiStart'> > 2020-12-28 10:00:57,869 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing WIKI_VIEW on <Resource u'wiki:TracGuide'> > 2020-12-28 10:00:57,869 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TICKET_VIEW_HOURS on None > 2020-12-28 10:00:57,869 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing TAGS_VIEW on None > 2020-12-28 10:00:57,897 Trac[main] DEBUG: Dispatching <RequestWithSession > "GET '/chrome/common/trac.ico'"> > 2020-12-28 10:00:57,898 Trac[main] DEBUG: Chosen handler is <Component > trac.web.chrome.Chrome> > 2020-12-28 10:00:57,899 Trac[session] DEBUG: Retrieving session for ID > u'rob' > 2020-12-28 10:00:57,901 Trac[perm] DEBUG: DefaultPermissionPolicy allows > rob performing ACCTMGR_USER_ADMIN on None > > I can't see anything in the above that helps me with this problem. > > On Monday, 28 December 2020 at 03:52:43 UTC+8 RjOllos wrote: > >> On Saturday, December 26, 2020 at 7:25:25 PM UTC-8 rcp....@gmail.com >> wrote: >> >>> Oh, and for the record, I've just tried adding a Milestone without >>> spaces in its name (I added "Release_Edition_5.6") and after adding that, I >>> was able to edit it without any problem. So it's definitely the spaces >>> that are the issue! >>> >>> Noting that the database stores the records with spaces rather than the >>> URL-encoding, presumable at some point in the "Edit" sequence, the >>> URL-encoding is decoded back to normal ASCII. Might I be able to get >>> greater visibility of what's going on at that level by cranking up the >>> logging? >>> >> >> Yeah, good idea. The URL value should be decoded by the Trac front-end >> and stored as space-separated in the database. Here are some select >> excerpts from the log when navigating to "Release Edition 5.3" with >> TracStandalone as the webserver: >> >> 11:43:52 Trac[main] DEBUG: Dispatching <RequestWithSession "GET >> '/milestone/Release Edition 5.3'"> >> ... >> 11:47:13 Trac[perm] DEBUG: DefaultPermissionPolicy allows user1 >> performing MILESTONE_VIEW on <Resource u'milestone:Release Edition 5.3'> >> ... >> 11:47:13 Trac[main] WARNING: [127.0.0.1] HTTPNotFound: 404 Not Found >> (File your_project_logo.png not found), <RequestWithSession "GET >> '/chrome/site/your_project_logo.png'">, referrer ' >> http://localhost:8001/proj-1.4/milestone/Release%20Edition%205.3' >> >> >> At the moment I don't remember where the decoding occurs, but I'm pretty >> sure it's in the Request class: >> https://trac.edgewall.org/browser/tags/trac-1.4.2/trac/web/api.py >> >> Ryan >> > -- You received this message because you are subscribed to the Google Groups "Trac Users" group. To unsubscribe from this group and stop receiving emails from it, send an email to trac-users+unsubscr...@googlegroups.com. To view this discussion on the web visit https://groups.google.com/d/msgid/trac-users/c364c7e1-d6e8-4250-a33a-e67a89bbd6ean%40googlegroups.com.
