Oh, and I'm now on CMSMS 1.8.1 and news module 2.10.6 (issue started on 1.6.7 and 2.10.4 this morning).
Ok, here is the debug info from loading the "edit" page of that post:
Code: Select all
Debug: (5.4E-5) - (647456)
loading smartyDebug: (0.004692) - (922916)
loading adodbDebug: (0.006389) - (1011412)
loading page functionsDebug: (0.010197) - (1218516)
loading content functionsDebug: (0.013316) - (1422848)
loading pageinfo functionsDebug: (0.014017) - (1456588)
loading translation functionsDebug: (0.015274) - (1518248)
loading events functionsDebug: (0.016725) - (1603280)
loading php4 entity decode functionsDebug: (0.019497) - (1806920)
done loading filesDebug: (0.034249) - (2503288)
(mysql): SELECT sitepref_name, sitepref_value from cms_siteprefs
Debug: (0.037417) - (2577568)
(mysql): SELECT * FROM cms_userplugins
Debug: (0.044423) - (3008776)
(mysql): SELECT * FROM cms_modules WHERE active = 1 ORDER by module_name
Debug display of 'End of include':(0.125712) - (6359168)
Debug: (0.126347) - (6328704)
Session found. Moving on...Debug: (0.1264) - (6328944)
Session found. Moving on...Debug: (0.126637) - (6332220)
(mysql): SELECT preference, value FROM cms_userprefs WHERE user_id = '1'
Debug: (0.133738) - (6779076)
Session found. Moving on...Debug: (0.133802) - (6779472)
Session found. Moving on...Debug: (0.133845) - (6779372)
before theme loadDebug display of 'Load Content Operations':(0.135433) - (6885896)
Debug display of 'End Load Content Operations':(0.144008) - (7452500)
Debug: (0.144145) - (7454332)
(mysql): SELECT content_id FROM cms_content WHERE default_content = 1
Debug: (0.144345) - (7455888)
(mysql): SELECT * FROM cms_content WHERE content_id = '15'
Debug: (0.14858) - (7710576)
(mysql): SELECT ug.user_id FROM cms_user_groups ug
WHERE ug.user_id = '1' AND ug.group_id = 1
Debug: (0.148794) - (7712136)
(mysql): SELECT DISTINCT permission_name FROM cms_user_groups ug INNER JOIN cms_group_perms gp ON gp.group_id = ug.group_id INNER JOIN cms_permissions p ON p.permission_id = gp.permission_id INNER JOIN cms_groups gr ON gr.group_id = ug.group_id WHERE ug.user_id = '1' AND gr.active = 1
Debug: (0.17983) - (7889808)
(mysql): SELECT htmlblob_id FROM cms_additional_htmlblob_users WHERE user_id = '1'
Debug: (0.180009) - (7891396)
(mysql): SELECT group_id FROM cms_user_groups WHERE user_id = '1'
Debug: (0.180165) - (7890816)
(mysql): SELECT htmlblob_id FROM cms_additional_htmlblob_users WHERE user_id IN (-1)
Debug: (0.180373) - (7890912)
(mysql): SELECT content_id FROM cms_content WHERE owner_id = '1'
Debug: (0.181024) - (7901816)
(mysql): SELECT user_id,content_id FROM cms_additional_users
Debug: (0.181472) - (7883416)
after theme loadDebug: (0.181562) - (7883904)
before populate admin navigationDebug: (0.181608) - (7884136)
before menu itemsDebug: (0.194841) - (8091160)
after menu itemsDebug: (0.195193) - (8093220)
before syste modulesDebug: (0.195518) - (8099280)
before module menu itemsDebug: (0.195806) - (8100968)
after module menu itemsDebug: (0.198674) - (8119692)
after populate admin navigationDebug: (0.198719) - (8119852)
before theme-y stuffDebug: (0.19942) - (8120008)
after theme-y stuffDebug: (0.200193) - (8126444)
(mysql): SELECT count(news_id) FROM cms_module_news n
WHERE status != 'published'
AND (( IFNULL(end_time, '1969-12-31 19:00:01') = '1969-12-31 19:00:01') OR (end_time > '2010-07-15 19:18:36'))
Debug: (0.200307) - (8121928)
before notificationsDebug: (0.200415) - (8122560)
after notificationsDebug: (0.200536) - (8123956)
(mysql): SELECT version from cms_version
Debug: (0.201772) - (8172376)
(mysql): SELECT bookmark_id, user_id, title, url FROM cms_admin_bookmarks WHERE user_id = '1' ORDER BY title
Debug: (0.204787) - (8272964)
(mysql): SELECT * FROM cms_module_news WHERE news_id = '6'
Debug: (0.205335) - (8276984)
(mysql): SELECT * FROM cms_module_news_categories ORDER BY hierarchy
Debug: (0.207594) - (8393204)
(mysql): SELECT username, password, active, first_name, last_name, admin_access, email FROM cms_users WHERE user_id = '1'
Debug: (0.211225) - (8438664)
(mysql): SELECT * FROM cms_module_news_fieldvals
WHERE news_id = '6'
Debug: (0.211384) - (8437716)
(mysql): SELECT * FROM cms_module_news_fielddefs ORDER BY item_order
Debug: (0.221922) - (9229160)
(mysql): SELECT eh.tag_name, eh.module_name, e.originator, e.event_name, eh.handler_order, eh.handler_id, eh.removable FROM cms_event_handlers eh
INNER JOIN cms_events e ON e.event_id = eh.event_id
ORDER BY eh.handler_order ASC
Obviously I can't submit it to see any further info as it just takes me to a white page/black text internal server error page again with no further debug stuff.
When viewing the news item with the correct "in" text that works, the debug info looks like this:
Code: Select all
Generated in 0.32706 seconds by CMS Made Simple using 47 SQL queries and 8601544 bytes of memory (peak memory usage was 9217048)
Debug: (0.010256) - (668452)
loading smartyDebug: (0.014898) - (943916)
loading adodbDebug: (0.016578) - (1032412)
loading page functionsDebug: (0.020391) - (1239496)
loading content functionsDebug: (0.023511) - (1443852)
loading pageinfo functionsDebug: (0.024215) - (1477592)
loading translation functionsDebug: (0.025471) - (1539252)
loading events functionsDebug: (0.02693) - (1624296)
loading php4 entity decode functionsDebug: (0.029669) - (1827916)
done loading filesDebug: (0.045077) - (2524008)
(mysql): SELECT sitepref_name, sitepref_value from cms_siteprefs
Debug: (0.048366) - (2598276)
(mysql): SELECT * FROM cms_userplugins
Debug: (0.054943) - (3028896)
(mysql): SELECT * FROM cms_modules WHERE admin_only = 0 AND active = 1 ORDER by module_name
Debug display of 'End of include':(0.099488) - (5699700)
Debug display of 'Load Content Operations':(0.100135) - (5713688)
Debug display of 'End Load Content Operations':(0.108771) - (6280292)
Debug: (0.109349) - (6282936)
(mysql): SELECT c.content_id, c.content_name, c.content_alias, c.menu_text, c.titleattribute, c.hierarchy, c.metadata, c.id_hierarchy, c.prop_names, c.create_date AS c_create_date, c.modified_date AS c_date, c.cachable, c.last_modified_by, t.template_id, t.encoding, t.modified_date AS t_date FROM cms_templates t INNER JOIN cms_content c ON c.template_id = t.template_id WHERE (c.content_alias = '15' OR c.content_id = '15') AND c.active = 1
Debug: (0.109567) - (6284440)
(mysql): SELECT MAX(modified_date) AS thedate FROM cms_content c
Debug display of 'Start Loading Hierarchy Manager':(0.110105) - (6281632)
Debug display of 'starting tree':(0.110146) - (6282176)
Debug: (0.112484) - (6424296)
file needs loadingDebug display of 'ending tree':(0.113926) - (6620676)
Debug display of 'End Loading Hierarchy Manager':(0.113972) - (6585412)
Debug: (0.11401) - (6585696)
start findNodeByTagDebug: (0.114064) - (6585924)
end findNodeByTagDebug: (0.114252) - (6589336)
(mysql): SELECT * FROM cms_content WHERE content_id = '15'
Debug: (0.11692) - (6768272)
load from data is loading propertiesDebug: (0.117107) - (6771328)
(mysql): SELECT * FROM cms_content_props WHERE content_id = '15'
Debug: (0.119632) - (6904084)
(mysql): SELECT template_id, template_name, template_content, stylesheet, encoding, active, default_template, modified_date FROM cms_templates WHERE template_id = '19'
Debug: (0.132087) - (7595392)
(mysql): SELECT eh.tag_name, eh.module_name, e.originator, e.event_name, eh.handler_order, eh.handler_id, eh.removable FROM cms_event_handlers eh
INNER JOIN cms_events e ON e.event_id = eh.event_id
ORDER BY eh.handler_order ASC
Debug: (0.161547) - (7760184)
(mysql): SELECT DISTINCT A.css_id,A.media_type,B.assoc_order
FROM cms_css A, cms_css_assoc B
WHERE A.css_id = B.assoc_css_id
AND B.assoc_type = 'template'
AND B.assoc_to_id = '19'
ORDER BY B.assoc_order
Debug display of 'Start of Menu Manager Display':(0.162985) - (7841804)
Debug: (0.163424) - (7849692)
(mysql): SELECT * FROM cms_content WHERE content_id = '16'
Debug: (0.163722) - (7866856)
load from data is loading propertiesDebug: (0.163896) - (7870084)
(mysql): SELECT * FROM cms_content_props WHERE content_id = '16'
Debug: (0.164324) - (7874232)
(mysql): SELECT * FROM cms_content WHERE parent_id = '16' ORDER BY hierarchy
Debug: (0.167985) - (8121620)
(mysql): SELECT * FROM cms_content WHERE content_id = '123'
Debug: (0.168271) - (8138632)
load from data is loading propertiesDebug: (0.168446) - (8141620)
(mysql): SELECT * FROM cms_content_props WHERE content_id = '123'
Debug: (0.168848) - (8144612)
(mysql): SELECT * FROM cms_content WHERE parent_id = '123' ORDER BY hierarchy
Debug: (0.169618) - (8177744)
(mysql): SELECT * FROM cms_content WHERE content_id = '138'
Debug: (0.169906) - (8194740)
load from data is loading propertiesDebug: (0.170081) - (8197728)
(mysql): SELECT * FROM cms_content_props WHERE content_id = '138'
Debug: (0.170486) - (8199944)
(mysql): SELECT * FROM cms_content WHERE parent_id = '138' ORDER BY hierarchy
Debug: (0.171257) - (8233040)
(mysql): SELECT * FROM cms_content WHERE content_id = '60'
Debug: (0.171546) - (8250048)
load from data is loading propertiesDebug: (0.171719) - (8253028)
(mysql): SELECT * FROM cms_content_props WHERE content_id = '60'
Debug: (0.172126) - (8254960)
(mysql): SELECT * FROM cms_content WHERE parent_id = '60' ORDER BY hierarchy
Debug: (0.174952) - (8439992)
(mysql): SELECT * FROM cms_content WHERE content_id = '17'
Debug: (0.175245) - (8457016)
load from data is loading propertiesDebug: (0.175418) - (8459996)
(mysql): SELECT * FROM cms_content_props WHERE content_id = '17'
Debug: (0.175901) - (8461620)
(mysql): SELECT * FROM cms_content WHERE content_id = '19'
Debug: (0.17619) - (8478580)
load from data is loading propertiesDebug: (0.176374) - (8481556)
(mysql): SELECT * FROM cms_content_props WHERE content_id = '19'
Debug: (0.176887) - (8482264)
(mysql): SELECT * FROM cms_content WHERE content_id = '130'
Debug: (0.17718) - (8499252)
load from data is loading propertiesDebug: (0.177349) - (8502236)
(mysql): SELECT * FROM cms_content_props WHERE content_id = '130'
Debug: (0.17882) - (8599140)
(mysql): SELECT content from cms_module_templates WHERE module_name = 'MenuManager' and template_name = 'Aura-I menu'
Debug: (0.17904) - (8599720)
(mysql): SELECT module_name,template_name,modified_date
FROM cms_module_templates
Debug display of 'End of Menu Manager Display':(0.213273) - (8556120)
Debug: (0.214781) - (8557308)
(mysql): SELECT mn.*, mnc.news_category_name FROM cms_module_news mn LEFT OUTER JOIN cms_module_news_categories mnc ON mnc.news_category_id = mn.news_category_id WHERE status = 'published' AND news_id = 6
Debug: (0.21634) - (8636056)
(mysql): SELECT username, password, active, first_name, last_name, admin_access, email FROM cms_users WHERE user_id = '1'
Debug: (0.219992) - (8752920)
(mysql): SELECT A.value,B.id,B.name,B.type FROM cms_module_news_fieldvals A, cms_module_news_fielddefs B WHERE A.fielddef_id = B.id AND A.news_id = '6' AND B.public = 1 ORDER BY B.item_order
Debug: (0.220635) - (8754536)
start findNodeByTagDebug: (0.220685) - (8754740)
end findNodeByTagDebug: (0.22164) - (8806176)
(mysql): SELECT content from cms_module_templates WHERE module_name = 'News' and template_name = 'detailSample'
Debug display of 'Start of Menu Manager Display':(0.251746) - (8783488)
Debug: (0.253492) - (8838212)
(mysql): SELECT content from cms_module_templates WHERE module_name = 'MenuManager' and template_name = 'Aura-I menu'
Debug display of 'End of Menu Manager Display':(0.281784) - (8787528)
Debug: (0.283852) - (8842980)
(mysql): SELECT content_id FROM cms_content WHERE content_alias = 'news'
Debug: (0.283935) - (8840256)
start findNodeByTagDebug: (0.28463) - (8842716)
end findNodeByTagDebug: (0.285348) - (8847660)
(mysql):
SELECT count(mn.news_id) as count
FROM cms_module_news mn
LEFT OUTER JOIN cms_module_news_categories mnc
ON mnc.news_category_id = mn.news_category_id
LEFT OUTER JOIN cms_users u
ON u.user_id = mn.author_id
WHERE
status = 'published'
AND
( IFNULL(start_time, '1969-12-31 19:00:01') < '2010-07-15 19:19:44') AND (( IFNULL(end_time, '1969-12-31 19:00:01') = '1969-12-31 19:00:01') OR (end_time > '2010-07-15 19:19:44'))
Debug: (0.287206) - (8851544)
(mysql):
SELECT
mn.*,
mnc.news_category_name,
mnc.long_name,
u.username,
u.first_name,
u.last_name
FROM cms_module_news mn
LEFT OUTER JOIN cms_module_news_categories mnc
ON mnc.news_category_id = mn.news_category_id
LEFT OUTER JOIN cms_users u
ON u.user_id = mn.author_id
WHERE
status = 'published'
AND
( IFNULL(start_time, '1969-12-31 19:00:01') < '2010-07-15 19:19:44') AND (( IFNULL(end_time, '1969-12-31 19:00:01') = '1969-12-31 19:00:01') OR (end_time > '2010-07-15 19:19:44')) ORDER BY news_date desc LIMIT 0, 3
Debug: (0.287468) - (8861928)
(mysql): SELECT A.value,B.id,B.name,B.type FROM cms_module_news_fieldvals A, cms_module_news_fielddefs B WHERE A.fielddef_id = B.id AND B.public = 1 AND A.news_id = '5' ORDER BY B.item_order
Debug: (0.289857) - (8869440)
(mysql): SELECT A.value,B.id,B.name,B.type FROM cms_module_news_fieldvals A, cms_module_news_fielddefs B WHERE A.fielddef_id = B.id AND B.public = 1 AND A.news_id = '6' ORDER BY B.item_order
Debug: (0.292156) - (8871172)
(mysql): SELECT A.value,B.id,B.name,B.type FROM cms_module_news_fieldvals A, cms_module_news_fielddefs B WHERE A.fielddef_id = B.id AND B.public = 1 AND A.news_id = '4' ORDER BY B.item_order
Debug: (0.294585) - (8871632)
(mysql):
SELECT news_category_id, news_category_name, hierarchy, long_name
FROM cms_module_news_categories
WHERE hierarchy not like ''
ORDER by hierarchy
Debug: (0.295072) - (8875692)
(mysql): SELECT COUNT(news_id) as cnt FROM cms_module_news WHERE news_category_id='1' AND (( IFNULL(end_time, '1969-12-31 19:00:01') = '1969-12-31 19:00:01') OR (end_time > '2010-07-15 19:19:44')) AND status = 'published'
Debug: (0.29568) - (8923264)
(mysql): SELECT content from cms_module_templates WHERE module_name = 'News' and template_name = 'summarySample'
Debug: (0.323081) - (8725712)
start findNodeByTagDebug: (0.323132) - (8725916)
end findNodeByTagDebug: (0.323295) - (8729344)
(mysql): SELECT content_id FROM cms_content WHERE content_alias = 'home'
Debug: (0.323373) - (8726868)
start findNodeByTagDebug: (0.323419) - (8727072)
end findNodeByTagDebug: (0.323571) - (8729868)
(mysql): SELECT content_id FROM cms_content WHERE content_alias = 'home'
Debug: (0.323647) - (8727392)
start findNodeByTagDebug: (0.323696) - (8727596)
end findNodeByTagDebug: (0.323888) - (8771224)
start global_content_get_templateDebug: (0.325481) - (8861964)
(mysql): SELECT htmlblob_id, htmlblob_name, html, owner, modified_date FROM cms_htmlblobs WHERE htmlblob_name = 'footer'
Debug: (0.325652) - (8859968)
end global_content_get_templateDebug: (0.325707) - (8860068)
start global_content_get_timestampDebug: (0.325757) - (8860392)
end global_content_get_timestampDebug: (0.326671) - (8745360)
calling module TinyMCE from event ContentPostRender
And the server error info is as follows:
Code: Select all
[Thu Jul 15 19:22:22 2010] [error] [client (IP Removed)] File does not exist: /home/(Path Removed)/public_html/404.shtml, referer: http://www.mikrondigital.com/index.php?mact=News,cntnt01,detail,0&cntnt01articleid=6&cntnt01origid=15&cntnt01returnid=15
[Thu Jul 15 19:22:22 2010] [error] [client (IP Removed)] File does not exist: /home/(Path Removed)/public_html/uploads/flash/banner/js, referer: http://www.mikrondigital.com/index.php?mact=News,cntnt01,detail,0&cntnt01articleid=6&cntnt01origid=15&cntnt01returnid=15
[Thu Jul 15 19:19:44 2010] [error] [client (IP Removed)] File does not exist: /home/(Path Removed)/public_html/404.shtml, referer: http://www.mikrondigital.com/index.php?mact=News,cntnt01,detail,0&cntnt01articleid=6&cntnt01origid=15&cntnt01returnid=15
[Thu Jul 15 19:19:44 2010] [error] [client (IP Removed)] File does not exist: /home/(Path Removed)/public_html/uploads/flash/banner/js, referer: http://www.mikrondigital.com/index.php?mact=News,cntnt01,detail,0&cntnt01articleid=6&cntnt01origid=15&cntnt01returnid=15
[Thu Jul 15 19:19:38 2010] [error] [client (IP Removed)] File does not exist: /home/(Path Removed)/public_html/404.shtml, referer: http://www.mikrondigital.com/
[Thu Jul 15 19:19:38 2010] [error] [client (IP Removed)] File does not exist: /home/(Path Removed)/public_html/uploads/flash/banner/js, referer: http://www.mikrondigital.com/
[Thu Jul 15 19:18:38 2010] [error] [client (IP Removed)] File does not exist: /home/(Path Removed)/public_html/404.shtml
[Thu Jul 15 19:18:38 2010] [error] [client (IP Removed)] File does not exist: /home/(Path Removed)/public_html/favicon.ico
[Thu Jul 15 19:18:37 2010] [error] [client (IP Removed)] File does not exist: /home/(Path Removed)/public_html/404.shtml, referer: http://www.mikrondigital.com/modules/FCKeditorX/FCKeditor/editor/fckeditor.html?InstanceName=m1_summary&Toolbar=Default
[Thu Jul 15 19:18:37 2010] [error] [client (IP Removed)] File does not exist: /home/(Path Removed)/public_html/modules/FCKeditorX/FCKeditor/editor/plugins/tablecommands/lang, referer: http://www.mikrondigital.com/modules/FCKeditorX/FCKeditor/editor/fckeditor.html?InstanceName=m1_summary&Toolbar=Default
[Thu Jul 15 19:18:25 2010] [error] [client (IP Removed)] File does not exist: /home/(Path Removed)/public_html/500.shtml, referer: http://www.mikrondigital.com/admin/moduleinterface.php?mact=News,m1_,editarticle,0&sp_=ef8d0279&m1_articleid=6
[Thu Jul 15 19:18:09 2010] [error] [client (IP Removed)] File does not exist: /home/(Path Removed)/public_html/404.shtml, referer: http://www.mikrondigital.com/modules/FCKeditorX/FCKeditor/editor/fckeditor.html?InstanceName=m1_summary&Toolbar=Default
[Thu Jul 15 19:18:09 2010] [error] [client (IP Removed)] File does not exist: /home/(Path Removed)/public_html/modules/FCKeditorX/FCKeditor/editor/plugins/tablecommands/lang, referer: http://www.mikrondigital.com/modules/FCKeditorX/FCKeditor/editor/fckeditor.html?InstanceName=m1_summary&Toolbar=Default
