Can't add new content after upgrade - redirected to 404 error page

[klendino]

I re-uploaded the cmsmadesimple1.6.5full version and performed the upgrade. Still the same problem.

Attached is a copy of the logfiles from the webserver:


Hope this information helps.

Thanks

Klendino

[klendino]

Hi

I digged a bit further into this problem.
What I did was installing a clean version of 1.6.5. I could add pages without the error. Then I imported my database into it. Now when adding content the error 404 appears again!
So it seems to me that it is something in the database that is causing this problem.

Any advise?

Klendino

[klendino]

I solved the problem by removing everything in the text area of the "global settings - custom 404 handling"
If you want to use custom 404 handling, you can but need to create template and css which you can attach here.
Now I can add new content without the enoying 404 error.

Hope this helps.

Klendino

[klendino]

Hi

The problem re-appeared after upgraded site to 1.11.7

Did some more investigation:

Did a checksum and no errors appeared other than 152 files not found which were from the install directory, which isn't there ofcourse.

Enabled debug mode in config and here is the output:
Generated in 0.454227 seconds by CMS Made Simple using 28 SQL queries and 12564256 bytes of memory (peak memory usage was 12784868)
Debug: (2.599999999997E-5) - (usage: 786668) - (peak: 819296)
done loading required files
Debug: (0.010045) - (usage: 792636) - (peak: 832332)
loading adodb
Debug: (0.01149) - (usage: 878728) - (peak: 913696)
loading page functions
Debug: (0.013427) - (usage: 1011784) - (peak: 1101448)
loading content functions
Debug: (0.013692) - (usage: 1021676) - (peak: 1101448)
loading translation functions
Debug: (0.013975) - (usage: 1034612) - (peak: 1101448)
loading php4 entity decode functions
Debug: (0.015691) - (usage: 1228756) - (peak: 1458388)
done loading files
Debug: (0.015742) - (usage: 1229016) - (peak: 1458388)
Initialize Database
Debug: (0.028016) - (usage: 1831256) - (peak: 1837124)
(mysql): SET
Error (1064): You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near '' at line 1
Debug: (0.028095) - (usage: 1829708) - (peak: 1879092)
Done Initializing Database
Debug: (0.028143) - (usage: 1829888) - (peak: 1879092)
Initialize Smarty
Debug: (0.041563) - (usage: 2920828) - (peak: 2923368)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.Smarty_CMS.php = 1089664 bytes for an approximate total of 1089664
Debug: (0.042468) - (usage: 2984420) - (peak: 2987404)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.CMSModuleDbTemplateResource.php = 54032 bytes for an approximate total of 1143696
Debug: (0.042982) - (usage: 3017512) - (peak: 3038932)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.CMSPageTemplateResource.php = 31944 bytes for an approximate total of 1175640
Debug: (0.043672) - (usage: 3060308) - (peak: 3078384)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.usertagoperations.inc.php = 41820 bytes for an approximate total of 1217460
Debug: (0.044256) - (usage: 3061620) - (peak: 3105324)
(mysql): SELECT * FROM cms_userplugins ORDER BY userplugin_name
Debug: (0.044707) - (usage: 3083604) - (peak: 3106656)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.cms_siteprefs.php = 19824 bytes for an approximate total of 1237284
Debug: (0.047425) - (usage: 3084792) - (peak: 3129164)
(mysql): SELECT sitepref_name,sitepref_value FROM cms_siteprefs
Debug: (0.048858) - (usage: 3139264) - (peak: 3219516)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.CMSContentTemplateResource.php = 18372 bytes for an approximate total of 1255656
Debug: (0.049143) - (usage: 3152696) - (peak: 3237024)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.CMSGlobalContentTemplateResource.php = 12760 bytes for an approximate total of 1268416
Debug: (0.050834) - (usage: 3154796) - (peak: 3248736)
Done Initialiing Smarty
Debug display of 'Loading Modules':(0.051045) - (usage: 3156124) - (peak: 3261076)
Debug: (0.053841) - (usage: 3375624) - (peak: 3445976)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.moduleoperations.inc.php = 218192 bytes for an approximate total of 1486608
Debug: (0.054422) - (usage: 3377424) - (peak: 3471464)
(mysql): SELECT * FROM cms_modules ORDER BY module_name
Debug: (0.054829) - (usage: 3390632) - (peak: 3472760)
loading module Captcha
Debug: (0.061452) - (usage: 3975956) - (peak: 4022864)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.CMSModule.php = 422912 bytes for an approximate total of 1909520
Debug: (0.062328) - (usage: 4062224) - (peak: 4137476)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.CmsLangOperations.php = 44852 bytes for an approximate total of 1954372
Debug: (0.062511) - (usage: 4061408) - (peak: 4137476)
loading module CMSPrinting
Debug: (0.063425) - (usage: 4160132) - (peak: 4186212)
loading module FormBuilder
Debug: (0.067578) - (usage: 4535108) - (peak: 4562696)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.CmsNlsOperations.php = 62228 bytes for an approximate total of 2016600
Debug: (0.068395) - (usage: 4575864) - (peak: 4613540)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.CmsNls.php = 30080 bytes for an approximate total of 2046680
Debug: (0.090043) - (usage: 5681196) - (peak: 5721500)
loading module CMSMailer
Debug: (0.091992) - (usage: 5867300) - (peak: 5898116)
loading module MenuManager
Debug: (0.093559) - (usage: 6006372) - (peak: 6049820)
loading module Showtime
Debug: (0.094403) - (usage: 6088252) - (peak: 6112332)
loading module TinyMCE
Debug display of 'End of Loading Modules':(0.097318) - (usage: 6345776) - (peak: 6370892)
Debug: (0.097917) - (usage: 6374140) - (peak: 6403804)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.CmsRegularTaskHandler.php = 27180 bytes for an approximate total of 2073860
Debug: (0.100288) - (usage: 6544508) - (peak: 6567504)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.contentoperations.inc.php = 172740 bytes for an approximate total of 2246600
Debug: (0.101254) - (usage: 6599012) - (peak: 6624520)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.cms_route_manager.php = 53720 bytes for an approximate total of 2300320
Debug: (0.101958) - (usage: 6639080) - (peak: 6659384)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.CmsRoute.php = 31236 bytes for an approximate total of 2331556
Debug: (0.102583) - (usage: 6662868) - (peak: 6689732)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.cms_content_cache.php = 28676 bytes for an approximate total of 2360232
Debug: (0.105478) - (usage: 6665256) - (peak: 6714144)
(mysql): SELECT * FROM cms_content WHERE content_alias = '__CMS_PREVIEW_PAGE__' AND active = 1 LIMIT 1
Debug: (0.106286) - (usage: 6619312) - (peak: 6716144)
(mysql): SELECT * FROM cms_content WHERE content_alias = 'error404' AND active = 1 LIMIT 1
Debug: (0.10727) - (usage: 6659472) - (peak: 6716144)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.module_meta.php = 33212 bytes for an approximate total of 2393444
Debug: (0.114232) - (usage: 7165608) - (peak: 7234976)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.ContentBase.php = 360416 bytes for an approximate total of 2753860
Debug: (0.114401) - (usage: 7194052) - (peak: 7234976)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/contenttypes/Content.inc.php = 497932 bytes for an approximate total of 3251792
Debug: (0.11455) - (usage: 7224928) - (peak: 7241112)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/contenttypes/ErrorPage.inc.php = 560800 bytes for an approximate total of 3812592
Debug: (0.116392) - (usage: 7235412) - (peak: 7271712)
(mysql): SELECT * FROM cms_content_props WHERE content_id = '156'
Debug: (0.116959) - (usage: 7237044) - (peak: 7285900)
process template top
Debug display of 'Fetch tpl_top:21 start':(0.117046) - (usage: 7237884) - (peak: 7288852)
Debug: (0.11892) - (usage: 7339200) - (peak: 7362168)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.templateoperations.inc.php = 93996 bytes for an approximate total of 3906588
Debug: (0.126699) - (usage: 7342644) - (peak: 7387968)
(mysql): SELECT template_id, template_name, template_content, stylesheet, encoding, active, default_template, modified_date
FROM cms_templates WHERE template_id = '21' LIMIT 1
Debug: (0.13342) - (usage: 7734404) - (peak: 7825640)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.Events.php = 86096 bytes for an approximate total of 3992684
Debug: (0.141456) - (usage: 7737184) - (peak: 7825640)
(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 display of 'Fetch tpl_top:21 end':(0.159048) - (usage: 9345668) - (peak: 9403568)
Debug: (0.159102) - (usage: 9345268) - (peak: 9403652)
process template body
Debug display of 'Fetch tpl_body:21 start':(0.159163) - (usage: 9346108) - (peak: 9403692)
Debug display of 'Start Load Smarty Plugin menu/function':(0.165443) - (usage: 9434212) - (peak: 9467692)
Debug display of 'End Load Smarty Plugin menu/function':(0.165525) - (usage: 9434912) - (peak: 9490872)
Debug display of 'Start Load Smarty Plugin search/function':(0.167226) - (usage: 9455400) - (peak: 9491644)
Debug: (0.167939) - (usage: 9505840) - (peak: 9531612)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.cms_module_smarty_plugin_manager.php = 48628 bytes for an approximate total of 4041312
Debug: (0.168729) - (usage: 9507708) - (peak: 9559040)
(mysql): SELECT * FROM cms_module_smarty_plugins ORDER BY module
Debug: (0.169475) - (usage: 9554264) - (peak: 9593264)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.cms_utils.php = 40076 bytes for an approximate total of 4081388
Debug: (0.171344) - (usage: 9740480) - (peak: 9763248)
Loading [[xxxxxxxxxxxxxx]]/modules/Search/Search.module.php = 183944 bytes for an approximate total of 4265332
Debug: (0.17614) - (usage: 9881380) - (peak: 9978712)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.CMS_Content_Block.php = 99084 bytes for an approximate total of 4364416
Debug display of 'Start Load Smarty Plugin news/function':(0.17748) - (usage: 9884556) - (peak: 9978712)
Debug: (0.17881) - (usage: 10027892) - (peak: 10047772)
Loading [[xxxxxxxxxxxxxx]]/modules/News/News.module.php = 139580 bytes for an approximate total of 4503996
Debug display of 'Start of Menu Manager Display':(0.182311) - (usage: 10165180) - (peak: 10251360)
Debug display of 'Start Loading Hierarchy Manager':(0.182364) - (usage: 10165380) - (peak: 10251360)
Debug display of 'starting tree':(0.182414) - (usage: 10165704) - (peak: 10251360)
Debug: (0.182969) - (usage: 10168256) - (peak: 10251360)
(mysql): SELECT modified_date FROM cms_content ORDER BY modified_date DESC LIMIT 1
Debug: (0.183119) - (usage: 10167028) - (peak: 10251360)
Content tree file needs loading
Debug: (0.184286) - (usage: 10283752) - (peak: 10319152)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.cms_tree.php = 35304 bytes for an approximate total of 4539300
Debug: (0.184359) - (usage: 10285764) - (peak: 10343576)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.cms_content_tree.php = 77988 bytes for an approximate total of 4617288
Debug display of 'ending tree':(0.184774) - (usage: 10333552) - (peak: 10363196)
Debug display of 'End Loading Hierarchy Manager':(0.184826) - (usage: 10315224) - (peak: 10379524)
Debug: (0.18586) - (usage: 10322340) - (peak: 10379524)
(mysql): SELECT * FROM cms_content FORCE INDEX (cms_index_content_by_idhier) WHERE content_id IN (15,51,53,55,52,72,54,71,69) AND active = 1 ORDER BY hierarchy
Debug: (0.187779) - (usage: 10347536) - (peak: 10379524)
(mysql): SELECT * FROM cms_content_props WHERE content_id IN (15,51,55,52,72,54,71,69) ORDER BY content_id
Debug: (0.191792) - (usage: 10458128) - (peak: 10552800)
(mysql): SELECT * FROM cms_content FORCE INDEX (cms_index_content_by_idhier) WHERE content_id IN (63,64,62,137,138,66,84,108) ORDER BY hierarchy
Debug: (0.197564) - (usage: 10568344) - (peak: 10597936)
(mysql): SELECT * FROM cms_content FORCE INDEX (cms_index_content_by_idhier) WHERE content_id IN (53) AND active = 1 ORDER BY hierarchy
Debug: (0.20132) - (usage: 10583544) - (peak: 10639000)
(mysql): SELECT * FROM cms_content FORCE INDEX (cms_index_content_by_idhier) WHERE content_id IN (56,57,58,59,60,61,65,67,68,75,76,78,77,79,80,81,82,83,85,86,87,88,89,94,95,96,98,97,99,100,101,103,104,105,106,107,110,111,112,113,114,115,116,117,118,120,121,122,123,124,125,126,127,130,131,132,135,133,134,136,139,140,141,142,143,144,145,146,147,148,149,150,151,152,153,154,155,157,158) ORDER BY hierarchy
Debug display of 'Fetch module_file_tpl:MenuManager;cssmenu.tpl start':(0.217927) - (usage: 11728344) - (peak: 11855084)
Debug display of 'Fetch module_file_tpl:MenuManager;cssmenu.tpl end':(0.303573) - (usage: 12007972) - (peak: 12141272)
Debug display of 'End of Menu Manager Display':(0.303637) - (usage: 12006700) - (peak: 12141272)
Debug display of 'Fetch module_db_tpl:Search;displaysearch start':(0.30813) - (usage: 12171252) - (peak: 12242908)
Debug display of 'CMSModuleDbTemplateResource startSearch;displaysearch':(0.308303) - (usage: 12176516) - (peak: 12264152)
Debug: (0.309432) - (usage: 12179648) - (peak: 12266960)
(mysql): SELECT * from cms_module_templates WHERE module_name = 'Search' and template_name = 'displaysearch' LIMIT 1
Debug display of 'CMSModuleDbTemplateResource endSearch;displaysearch':(0.309612) - (usage: 12178704) - (peak: 12268828)
Debug display of 'Fetch module_db_tpl:Search;displaysearch end':(0.323656) - (usage: 12189336) - (peak: 12292632)
Debug display of 'Fetch content:content_en start':(0.324647) - (usage: 12125148) - (peak: 12292788)
Debug display of 'Fetch content:content_en end':(0.325826) - (usage: 12136668) - (peak: 12292788)
Debug display of 'CMSModuleDbTemplateResource startNews;summarydc2-news':(0.327553) - (usage: 12273920) - (peak: 12344656)
Debug: (0.328327) - (usage: 12277656) - (peak: 12363488)
(mysql): SELECT * from cms_module_templates WHERE module_name = 'News' and template_name = 'summarydc2-news' LIMIT 1
Debug display of 'CMSModuleDbTemplateResource endNews;summarydc2-news':(0.328467) - (usage: 12278300) - (peak: 12364832)
Debug: (0.329807) - (usage: 12280516) - (peak: 12367572)
(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') < '2013-08-08 10:39:27') AND (( IFNULL(end_time, '1969-12-31 19:00:01') = '1969-12-31 19:00:01') OR (end_time > '2013-08-08 10:39:27')) LIMIT 1
Debug: (0.333036) - (usage: 12321808) - (peak: 12440308)
(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') < '2013-08-08 10:39:27') AND (( IFNULL(end_time, '1969-12-31 19:00:01') = '1969-12-31 19:00:01') OR (end_time > '2013-08-08 10:39:27')) ORDER BY news_date desc LIMIT 0, 5
Debug: (0.334592) - (usage: 12433092) - (peak: 12490032)
Loading [[xxxxxxxxxxxxxx]]/modules/News/lib/class.news_ops.php = 106156 bytes for an approximate total of 4723444
Debug: (0.335244) - (usage: 12431812) - (peak: 12513660)
(mysql): SELECT * FROM cms_module_news_fielddefs WHERE public = 1 ORDER BY item_order
Debug: (0.33849) - (usage: 12440568) - (peak: 12606240)
(mysql): SELECT * FROM cms_module_news_categories ORDER BY hierarchy
Debug: (0.355071) - (usage: 12444048) - (peak: 12606240)
(mysql): SELECT news_category_id,COUNT(news_id) AS cnt FROM cms_module_news WHERE news_category_id IN (1,2) AND (( IFNULL(end_time, '1969-12-31 19:00:01') = '1969-12-31 19:00:01') OR (end_time > '2013-08-08 10:39:27')) AND status = 'published' GROUP BY news_category_id
Debug display of 'Fetch module_db_tpl:News;summarydc2-news start':(0.355646) - (usage: 12445136) - (peak: 12606240)
Debug display of 'Fetch module_db_tpl:News;summarydc2-news end':(0.402567) - (usage: 12540076) - (peak: 12690380)
Debug display of 'Fetch globalcontent:dc-footer-gb start':(0.402702) - (usage: 12400208) - (peak: 12690380)
Debug: (0.402841) - (usage: 12405316) - (peak: 12690380)
start global_content_get_template
Debug: (0.404097) - (usage: 12497404) - (peak: 12690380)
Loading [[xxxxxxxxxxxxxx]]/lib/classes/class.globalcontentoperations.inc.php = 90592 bytes for an approximate total of 4814036
Debug: (0.4047) - (usage: 12499928) - (peak: 12690380)
(mysql): SELECT htmlblob_id, htmlblob_name, html, owner, use_wysiwyg, description, modified_date FROM cms_htmlblobs WHERE htmlblob_name = 'dc-footer-gb' LIMIT 1
Debug: (0.405431) - (usage: 12501692) - (peak: 12690380)
(mysql): INSERT INTO cms_adminlog (timestamp, user_id, username, item_id, item_name, action, ip_addr) VALUES (1375972767,'1','admin',-1,'Global Content Block: dc-footer-gb','Can not open or does not exist!','208.78.53.120')
Debug: (0.405503) - (usage: 12497584) - (peak: 12690380)
end global_content_get_template
Debug display of 'Fetch globalcontent:dc-footer-gb end':(0.406652) - (usage: 12503000) - (peak: 12690380)
Debug display of 'Fetch tpl_body:21 end':(0.406737) - (usage: 12466108) - (peak: 12690380)
Debug: (0.40679) - (usage: 12465668) - (peak: 12690380)
process template head
Debug display of 'Fetch tpl_head:21 start':(0.406845) - (usage: 12466508) - (peak: 12690380)
Debug display of 'Start Load Smarty Plugin cms_stylesheet/function':(0.411374) - (usage: 12571716) - (peak: 12690380)
Debug display of 'End Load Smarty Plugin cms_stylesheet/function':(0.411444) - (usage: 12572588) - (peak: 12690380)
Debug: (0.425983) - (usage: 12685728) - (peak: 12743808)
(mysql): SELECT DISTINCT A.css_id,A.css_name,A.css_text,A.modified_date,A.media_type,A.media_query,B.assoc_order
FROM cms_css A
LEFT JOIN cms_css_assoc B ON A.css_id = B.assoc_css_id WHERE B.assoc_type = 'template' AND B.assoc_to_id = '21' ORDER BY B.assoc_order
Debug: (0.435726) - (usage: 12690396) - (peak: 12784868)
(mysql): SELECT content_id FROM cms_content WHERE default_content = 1 LIMIT 1
Debug: (0.4511) - (usage: 12693684) - (peak: 12784868)
(mysql): SELECT * FROM cms_content FORCE INDEX (cms_index_content_by_idhier) WHERE content_id IN (91,92,93) AND active = 1 ORDER BY hierarchy
Debug display of 'Fetch tpl_head:21 end':(0.453352) - (usage: 12685132) - (peak: 12784868)
Debug: (0.453472) - (usage: 12695316) - (peak: 12784868)
calling module TinyMCE from event ContentPostRender
Debug: (0.453824) - (usage: 12696460) - (peak: 12784868)
calling module Showtime from event ContentPostRender

Hope this would make more sense in finding a solution.

Thanks

[velden]

I'm triggered by

(mysql): SELECT * FROM cms_content WHERE content_alias = '__CMS_PREVIEW_PAGE__' AND active = 1 LIMIT 1

That will probably not give any result hence might trigger a 404, but don't know why it's queried.

Would need a core developer to clarify that. Maybe it's normal behaviour although I guess not.

[klendino]

I'm triggered by

(mysql): SELECT * FROM cms_content WHERE content_alias = '__CMS_PREVIEW_PAGE__' AND active = 1 LIMIT 1

That will probably not give any result hence might trigger a 404, but don't know why it's queried.

Would need a core developer to clarify that. Maybe it's normal behaviour although I guess not.

Yes, me too. Did some further research and finally could fix the problem, and I hope it's now permanent.
What I did was to delete 404 template (legacy from older version), then deleted and recreated an error page in content - pages.

Applied this fix to the 3 sites I was experiences this problem and I can now, finally, add new content.