1.10.3 - Module Manager Not Working [solved]

[crankshaft]

Hi;

I am posting this under a new thread, as the old one I hijacked was for 1.10.2.

I am unable to install and new modules on a fresh install of 1.10.3, I have an older version of cmsms running on the same server which has no problem loading modules, but on this new version I am getting the:

"A problem occurred communicating with the module server"

Error. I have already searched this and have verified the following which are supposed to fix it but dont:

1. Version 1.10.3
2. Chunksize set to 256.
3. php.ini seperator character set to '&'
4. Module URL = http://www.cmsmadesimple.org/ModuleRepo ... equest/v2/
5. php.ini settings correct for upload size, post size, execution time etc.
6. Permissions on files / folders apper to pass the verify tests.
7. nslookup on cmsmadesimple.org is OK

In addition, I have verified that it is not a DNS issue, however other than that I am now stuck with a vanilla install that I am unable to add any modules to.

I have also checked all the log files (/var/logs) and cannot see any errors which are related to this failure, I have done all that I can and are now stuck with no way of being able to determine what the cause might be as I cannot see any errors.

I have now enabled debugging in config.php, here's the output when clicking the Module Manager:

Code: Select all

Debug: (2.1000000000021E-5) - (usage: 1734432) - (peak: 1765144)

done loading required files

Debug: (0.000116) - (usage: 1736816) - (peak: 1787864)

loading adodb

Debug: (0.00070500000000001) - (usage: 1891112) - (peak: 1955240)

loading page functions

Debug: (0.001716) - (usage: 2179816) - (peak: 2282232)

loading content functions

Debug: (0.001922) - (usage: 2220464) - (peak: 2282232)

loading translation functions

Debug: (0.002628) - (usage: 2400816) - (peak: 2447504)

loading php4 entity decode functions

Debug: (0.003375) - (usage: 2762944) - (peak: 3077520)

done loading files

Debug: (0.003405) - (usage: 2763512) - (peak: 3077520)

Initialize Database

Debug: (0.006498) - (usage: 3820864) - (peak: 3840008)

(mysqli): SET NAMES 'utf8'

Debug: (0.006574) - (usage: 3819624) - (peak: 3881584)

Done Initializing Database

Debug: (0.006624) - (usage: 3819976) - (peak: 3881744)

Initialize Smarty

Debug: (0.011247) - (usage: 4626640) - (peak: 4638360)

Loading /var/www/html/cms-dev/lib/classes/class.Smarty_CMS.php = 805056 bytes for an approximate total of 805056

Debug: (0.011686) - (usage: 4685832) - (peak: 4708960)

Loading /var/www/html/cms-dev/lib/classes/class.cms_siteprefs.php = 33480 bytes for an approximate total of 838536

Debug: (0.011935) - (usage: 4687072) - (peak: 4739968)

(mysqli): SELECT sitepref_name,sitepref_value FROM cms_siteprefs

Debug: (0.013617) - (usage: 4800752) - (peak: 4850392)

Done Initialiing Smarty

Debug display of 'Loading Modules':(0.013748) - (usage: 4802632) - (peak: 4876368)
Debug: (0.016018) - (usage: 5157888) - (peak: 5194816)

Loading /var/www/html/cms-dev/lib/classes/class.moduleoperations.inc.php = 353720 bytes for an approximate total of 1192256

Debug: (0.016246) - (usage: 5160784) - (peak: 5224984)

(mysqli): SELECT * FROM cms_modules ORDER BY module_name

Debug: (0.018399) - (usage: 5480856) - (peak: 5501664)

Loading /var/www/html/cms-dev/lib/classes/class.contentoperations.inc.php = 293728 bytes for an approximate total of 1485984

Debug: (0.019059) - (usage: 5550360) - (peak: 5584080)

Loading /var/www/html/cms-dev/lib/classes/class.module_meta.php = 56288 bytes for an approximate total of 1542272

Debug: (0.021602) - (usage: 5742832) - (peak: 5765280)

Loading /var/www/html/cms-dev/lib/classes/class.cms_utils.php = 39192 bytes for an approximate total of 1581464

Debug: (0.021844) - (usage: 5798728) - (peak: 5823144)

Loading /var/www/html/cms-dev/lib/classes/class.cms_userprefs.php = 55752 bytes for an approximate total of 1637216

Debug: (0.02197) - (usage: 5802320) - (peak: 5852656)

(mysqli): SELECT preference,value FROM cms_userprefs WHERE user_id = '1'

Debug: (0.02691) - (usage: 6995800) - (peak: 7064312)

Loading /var/www/html/cms-dev/lib/classes/class.CMSModule.php = 568464 bytes for an approximate total of 2205680

Debug: (0.026987) - (usage: 7060968) - (peak: 7114616)

Loading /var/www/html/cms-dev/modules/CMSMailer/CMSMailer.module.php = 890768 bytes for an approximate total of 3096448

Debug: (0.027505) - (usage: 7221152) - (peak: 7274120)

Loading /var/www/html/cms-dev/modules/CMSPrinting/CMSPrinting.module.php = 154080 bytes for an approximate total of 3250528

Debug: (0.029203) - (usage: 7662000) - (peak: 7730048)

Loading /var/www/html/cms-dev/modules/FileManager/FileManager.module.php = 343712 bytes for an approximate total of 3594240

Debug: (0.029975) - (usage: 7889496) - (peak: 7947560)

Loading /var/www/html/cms-dev/modules/MenuManager/MenuManager.module.php = 222224 bytes for an approximate total of 3816464

Debug: (0.030786) - (usage: 8042096) - (peak: 8075552)

Loading /var/www/html/cms-dev/modules/MicroTiny/MicroTiny.module.php = 101528 bytes for an approximate total of 3917992

Debug: (0.031219) - (usage: 8164512) - (peak: 8194608)

Loading /var/www/html/cms-dev/modules/ModuleManager/ModuleManager.module.php = 116784 bytes for an approximate total of 4034776

Debug: (0.032013) - (usage: 8424144) - (peak: 8448184)

Loading /var/www/html/cms-dev/modules/News/News.module.php = 254248 bytes for an approximate total of 4289024

Debug: (0.033976) - (usage: 8843088) - (peak: 8939240)

Loading /var/www/html/cms-dev/modules/Search/Search.module.php = 311504 bytes for an approximate total of 4600528

Debug: (0.034794) - (usage: 9000160) - (peak: 9049936)

Loading /var/www/html/cms-dev/modules/ThemeManager/ThemeManager.module.php = 113648 bytes for an approximate total of 4714176

Debug display of 'End of Loading Modules':(0.034957) - (usage: 8999368) - (peak: 9084808)
Debug: (0.035169) - (usage: 9044336) - (peak: 9097944)

Loading /var/www/html/cms-dev/lib/classes/class.CmsRegularTaskHandler.php = 43664 bytes for an approximate total of 4757840

Debug: (0.035233) - (usage: 8956192) - (peak: 9129624)

Session found.  Moving on...

Debug: (0.035265) - (usage: 8956640) - (peak: 9129624)

Session found.  Moving on...

Debug: (0.037548) - (usage: 9724392) - (peak: 9873672)

Session found.  Moving on...

Debug: (0.037591) - (usage: 9725176) - (peak: 9873672)

Session found.  Moving on...

Debug: (0.037619) - (usage: 9725296) - (peak: 9873672)

before theme load

Debug: (0.038785) - (usage: 10067816) - (peak: 10108600)

Loading /var/www/html/cms-dev/lib/classes/class.useroperations.inc.php = 132376 bytes for an approximate total of 4890216

Debug: (0.038965) - (usage: 10073096) - (peak: 10138560)

(mysqli): SELECT ug.user_id FROM cms_user_groups ug
                     WHERE ug.user_id = '1' AND ug.group_id = 1 LIMIT  1

Debug: (0.039123) - (usage: 10074328) - (peak: 10141904)

(mysqli): 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.039767) - (usage: 10224640) - (peak: 10255792)

Loading /var/www/html/cms-dev/lib/classes/class.globalcontentoperations.inc.php = 153696 bytes for an approximate total of 5043912

Debug: (0.039883) - (usage: 10228440) - (peak: 10291144)

(mysqli): SELECT htmlblob_id FROM cms_additional_htmlblob_users WHERE user_id = '1'

Debug: (0.040009) - (usage: 10231928) - (peak: 10293944)

(mysqli): SELECT group_id FROM cms_user_groups WHERE user_id = '1'

Debug: (0.040123) - (usage: 10230544) - (peak: 10299496)

(mysqli): SELECT htmlblob_id FROM cms_additional_htmlblob_users WHERE user_id IN (-1)

Debug: (0.040267) - (usage: 10230088) - (peak: 10301160)

(mysqli): SELECT content_id FROM cms_content WHERE owner_id = '1'

Debug: (0.040437) - (usage: 10235216) - (peak: 10301816)

(mysqli): SELECT user_id,content_id FROM cms_additional_users

Debug: (0.040795) - (usage: 10237824) - (peak: 10304768)

after theme load

Debug: (0.040851) - (usage: 10239008) - (peak: 10305704)

before populate admin navigation

Debug: (0.040879) - (usage: 10239360) - (peak: 10306712)

before menu items

Debug: (0.04868) - (usage: 10323800) - (peak: 10340984)

after menu items

Debug: (0.049008) - (usage: 10327688) - (peak: 10401696)

before syste modules

Debug: (0.049184) - (usage: 10342504) - (peak: 10442872)

before module menu items

Debug: (0.049311) - (usage: 10343168) - (peak: 10443544)

after module menu items

Debug: (0.050568) - (usage: 10394280) - (peak: 10479216)

after populate admin navigation

Debug: (0.050596) - (usage: 10394640) - (peak: 10520408)

before theme-y stuff

Debug: (0.051393) - (usage: 10395416) - (peak: 10524376)

after theme-y stuff

Debug: (0.051429) - (usage: 10396480) - (peak: 10524784)

before notifications

Debug: (0.051535) - (usage: 10404760) - (peak: 10524784)

(mysqli): SELECT version from cms_version LIMIT  1

Debug: (0.051886) - (usage: 10405648) - (peak: 10525424)

after notifications

Debug: (0.052319) - (usage: 10476280) - (peak: 10562720)

Loading /var/www/html/cms-dev/lib/classes/class.bookmarkoperations.inc.php = 68936 bytes for an approximate total of 5112848

Debug: (0.052435) - (usage: 10481072) - (peak: 10590928)

(mysqli): SELECT bookmark_id, user_id, title, url FROM cms_admin_bookmarks WHERE user_id = '1' ORDER BY title

Debug: (0.054751) - (usage: 10665624) - (peak: 10764328)

Loading /var/www/html/cms-dev/modules/ModuleManager/lib/class.modmgr_utils.php = 123704 bytes for an approximate total of 5236552

Debug: (0.055038) - (usage: 10700112) - (peak: 10789128)

Loading /var/www/html/cms-dev/modules/ModuleManager/lib/class.modmgr_cached_request.php = 33776 bytes for an approximate total of 5270328

Debug: (0.056169) - (usage: 11004072) - (peak: 11070272)

Loading /var/www/html/cms-dev/lib/classes/class.cms_http_request.php = 305904 bytes for an approximate total of 5576232

Debug: (0.742172) - (usage: 12636944) - (peak: 12661824)

Loading /var/www/html/cms-dev/lib/classes/class.Events.php = 141432 bytes for an approximate total of 5717664

Debug: (0.742388) - (usage: 12640744) - (peak: 12694896)

(mysqli): 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

Please help.

Thanks

PeterC

[kendo451]

That error is almost always the result of a network connection problem. Is this installation on XAMPP or a local development environment?

[crankshaft]

Hi;

No this is a Debian (Ubuntu) Server running APACHE2, and as mentioned, I have already determined that there is no DNS problem.

Furthermore, there is an existing install of CMSMS on the SAME SERVER ( ver: 1.5.1 ) which has no problem at all with the Module Manager and does not have the same problems.

Thanks

[kendo451]

I don't think this is your problem, but I usually reduce the chunk size to 64. This reduces the frequency of checksum errors.

Also, have you run the checksum test on your CMSMS installation? You may have some corrupted files in your install.

[crankshaft]

Hi and many thanks for the suggestions, I am really stuck with this.

OK, Chunk Size set to: 64 - No difference, Same Error:"A problem occurred communicating with the module server"

Checksum Passed Successfully: "All checksums match those in the uploaded file"

[staartmees]

http://dev.cmsmadesimple.org/bug/view/7290

[crankshaft]

Thanks

Yes, I read that post a few days ago and have already applied the fixes recommended in the bug report.

No difference, the error is still there and I am unable to add any new modules.

[staartmees]

install a new 1.10.3 in a different dir and see if the problem still occurs.

[crankshaft]

Hi;

Thanks for the suggestion, but it already passed the checksum test, so I am pretty confifent that it's not related to my install, I have a suspicion that the cmsmadesimple.org server is actively blocking the request.

Maybe someone can check the access logs for the cmsmadesimple.org server and see if they can offer any more info that will point to the cause of this problem ??

[crankshaft]

Hi;

Yes, please see first post:

5. php.ini settings correct for upload size, post size, execution time etc.
6. Permissions on files / folders apper to pass the verify tests.

[crankshaft]

Hi;

The problem was resolved by installing php5-curl.

Maybe the installer / system verifiy test can include a check for php5-curl ?

Thanks