I have a new production site up at http://www.nscfundalaska.org/

I have one group, the Ecovillage Training Center that has developed an unusual problem. Any attempt to edit the content of the group will trigger a 501 error, which ultimately results in a Page Not Found error message. 501 not implemented means the server will not support the ability that is required to fill the original request. This is the best fitted response when a web server is not familiar with the request method and is not capable of supporting it for any resource.

Redirecting the error to phpinfo() shows that the HTTP Method is, in fact, POST, which is what you expect. I've tracked discussions that talk about permissions and alias and tried all those tricks, to no avail.

_SERVER["REDIRECT_REQUEST_METHOD"]	POST
_SERVER["REDIRECT_STATUS"]	501
_SERVER["REDIRECT_UNIQUE_ID"]	TwehD9HsR0IACNLKJ5cAAABA
_SERVER["REDIRECT_URL"]	/node/21/edit
_SERVER["REMOTE_ADDR"]	72.208.46.28
_SERVER["REMOTE_PORT"]	54381
_SERVER["REQUEST_METHOD"]	GET
_SERVER["REQUEST_URI"]	/node/21/edit

You can see the page at:

http://www.nscfundalaska.org/group/north-star-ecovillage-training-center

When I hit the save or preview button, all I get is:

Page not found
The requested page could not be found.

It has some feeds, other than that it is just like all the rest of the groups and editing them works just fine. The other group configured with feeds edits.

ANY ideas on how to debug to find what is wrong or figure out why this one group won't edit would be appreciated.

Comments

ezra-g’s picture

Status: Active » Postponed (maintainer needs more info)

Are there any errors in PHP/Apache error log or Drupal DB/Syslog at admin/reports?

Cyberflyer’s picture

Good question, Ezra-g!

I cleared all the logs, provoked the error and found three entries in the System Log in this order:

1: Unknown column 'nscf_prod_activity_log_templates.pid' --- Error deleted. Part of Activity Stream display bug.

2: Unknown column 'nscf_prod_activity_log_templates.pid' --- Error deleted. Part of Activity Stream display bug.

3: 501.shtml

There were no access denied errors logged.

There was one 'File not Found" error logged, for page "501.shtml'.

And here are the relevant time period server access logs, FWIW:

72.208.46.28 - - [08/Jan/2012:14:00:50 -0700] "GET /modules/dblog/dblog.css?W HTTP/1.1" 304 - "http://www.nscfundalaska.org/admin/reports/page-not-found" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:00:50 -0700] "GET /sites/default/files/css/css_7b8e72fe4c9407ee74e8b3d32abc49cc.css HTTP/1.1" 304 - "http://www.nscfundalaska.org/admin/reports/page-not-found" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:00:50 -0700] "GET /profiles/drupal_commons/themes/fusion_core/css/grid16-960.css HTTP/1.1" 304 - "http://www.nscfundalaska.org/admin/reports/page-not-found" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:00:50 -0700] "GET /sites/all/themes/nscf_commons/css/local.css HTTP/1.1" 304 - "http://www.nscfundalaska.org/admin/reports/page-not-found" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:00:51 -0700] "GET /profiles/drupal_commons/themes/commons_origins/images/body-bg.png HTTP/1.1" 304 - "http://www.nscfundalaska.org/admin/reports/page-not-found" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:00:51 -0700] "GET /sites/all/themes/nscf_commons/images/body-bg.jpg HTTP/1.1" 304 - "http://www.nscfundalaska.org/admin/reports/page-not-found" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:00:51 -0700] "GET /sites/all/themes/nscf_commons/logo.png HTTP/1.1" 304 - "http://www.nscfundalaska.org/admin/reports/page-not-found" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:00:51 -0700] "GET /sites/default/files/imagecache/user_picture_meta/pictures/picture-1.png HTTP/1.1" 304 - "http://www.nscfundalaska.org/admin/reports/page-not-found" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:00:47 -0700] "GET /admin/reports/page-not-found HTTP/1.1" 200 82676 "http://www.nscfundalaska.org/admin/reports" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:00:51 -0700] "GET /profiles/drupal_commons/modules/contrib/admin/images/sprite.png HTTP/1.1" 304 - "http://www.nscfundalaska.org/admin/reports/page-not-found" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:00:51 -0700] "GET /profiles/drupal_commons/themes/commons_origins/images/inner-shadow.png HTTP/1.1" 304 - "http://www.nscfundalaska.org/admin/reports/page-not-found" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:00:51 -0700] "GET /misc/arrow-asc.png HTTP/1.1" 304 - "http://www.nscfundalaska.org/admin/reports/page-not-found" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:00:51 -0700] "GET /profiles/drupal_commons/themes/commons_origins/images/search-submit.png HTTP/1.1" 304 - "http://www.nscfundalaska.org/admin/reports/page-not-found" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:00:51 -0700] "GET /profiles/drupal_commons/modules/contrib/admin/images/black.png HTTP/1.1" 304 - "http://www.nscfundalaska.org/admin/reports/page-not-found" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:00:51 -0700] "GET /profiles/drupal_commons/modules/contrib/admin/images/bleeds.png HTTP/1.1" 304 - "http://www.nscfundalaska.org/admin/reports/page-not-found" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:00:51 -0700] "GET /profiles/drupal_commons/themes/commons_origins/images/dropdown.png HTTP/1.1" 304 - "http://www.nscfundalaska.org/admin/reports/page-not-found" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:01:04 -0700] "GET /groups HTTP/1.1" 200 149782 "http://www.nscfundalaska.org/admin/reports/page-not-found" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:01:07 -0700] "GET /sites/default/files/js/js_15c2681e4c428c5978b9c65ccfb6415f.js HTTP/1.1" 200 264342 "http://www.nscfundalaska.org/groups" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:01:19 -0700] "GET /sites/default/files/css/css_b5c49467ac310f463fc1630699df12ff.css HTTP/1.1" 200 160175 "http://www.nscfundalaska.org/group/north-star-ecovillage-training-center" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:01:14 -0700] "GET /group/north-star-ecovillage-training-center HTTP/1.1" 200 296993 "http://www.nscfundalaska.org/groups" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:01:20 -0700] "GET /sites/default/files/js/js_b33ca1ae05de93cd85f9acebdf2a73d3.js HTTP/1.1" 200 2619 "http://www.nscfundalaska.org/group/north-star-ecovillage-training-center" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:01:19 -0700] "GET /sites/default/files/js/js_c8ee70f1834d0026cc3c738f842604db.js HTTP/1.1" 200 308130 "http://www.nscfundalaska.org/group/north-star-ecovillage-training-center" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:01:21 -0700] "GET /sites/default/files/imagecache/group_images_showcase/group_images/nsevtc-logo.jpg HTTP/1.1" 200 4184 "http://www.nscfundalaska.org/group/north-star-ecovillage-training-center" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:01:21 -0700] "GET /editablefields_html/21/field_featured_group/0 HTTP/1.1" 200 6291 "http://www.nscfundalaska.org/group/north-star-ecovillage-training-center" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:01:26 -0700] "GET /node/21/edit HTTP/1.1" 200 206839 "http://www.nscfundalaska.org/group/north-star-ecovillage-training-center" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:01:28 -0700] "GET /sites/default/files/css/css_48ea3f1117f64689d109b3eb72e72922.css HTTP/1.1" 200 157483 "http://www.nscfundalaska.org/node/21/edit" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:01:29 -0700] "GET /sites/default/files/js/js_c4a26b6ce6c63c0b44cc352d8d94ddf4.js HTTP/1.1" 200 8621 "http://www.nscfundalaska.org/node/21/edit" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:01:28 -0700] "GET /profiles/drupal_commons/libraries/ckeditor/ckeditor.js?W HTTP/1.1" 200 363774 "http://www.nscfundalaska.org/node/21/edit" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:01:28 -0700] "GET /sites/default/files/js/js_9c0b53e8425176a405ece05d1fb0b1a0.js HTTP/1.1" 200 355844 "http://www.nscfundalaska.org/node/21/edit" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:01:30 -0700] "GET /sites/default/files/imagefield_thumbs/group_images/nsevtc-logo.jpg?1325717157 HTTP/1.1" 200 2687 "http://www.nscfundalaska.org/node/21/edit" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:01:41 -0700] "POST /node/21/edit HTTP/1.1" 404 78202 "http://www.nscfundalaska.org/node/21/edit" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:01:54 -0700] "GET /admin/reports HTTP/1.1" 200 82496 "http://www.nscfundalaska.org/node/21/edit" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:02:05 -0700] "GET /admin/reports/dblog HTTP/1.1" 200 85481 "http://www.nscfundalaska.org/admin/reports" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:02:08 -0700] "GET /sites/default/files/js/js_ccf76f9b7d984bb8bceeb15743896e6c.js HTTP/1.1" 200 251123 "http://www.nscfundalaska.org/admin/reports/dblog" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:02:09 -0700] "GET /misc/watchdog-warning.png HTTP/1.1" 200 339 "http://www.nscfundalaska.org/admin/reports/dblog" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:02:09 -0700] "GET /misc/watchdog-error.png HTTP/1.1" 200 799 "http://www.nscfundalaska.org/admin/reports/dblog" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:02:23 -0700] "GET /admin/reports/event/1 HTTP/1.1" 200 83121 "http://www.nscfundalaska.org/admin/reports/dblog" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:03:08 -0700] "GET /admin/reports/event/1 HTTP/1.1" 200 83121 "http://www.nscfundalaska.org/admin/reports/dblog" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:05:42 -0700] "GET /admin/reports/event/2 HTTP/1.1" 200 84761 "http://www.nscfundalaska.org/admin/reports/dblog" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"
72.208.46.28 - - [08/Jan/2012:14:06:23 -0700] "GET /admin/reports/event/3 HTTP/1.1" 200 82139 "http://www.nscfundalaska.org/admin/reports/dblog" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_6_8) AppleWebKit/535.7 (KHTML, like Gecko) Chrome/16.0.912.75 Safari/535.7"

Hope this helps. I appreciate your looking into this.

Cy

Cyberflyer’s picture

Status: Postponed (maintainer needs more info) » Active

Oddly, there was nothing in the Server Error Log file in Control Panel, which claims to show the last 300 errors for the site.

Cyberflyer’s picture

OK. The problem seems to have to do with using table prefixes.

This thread http://drupal.org/node/1317814 talks about the activity stream.

I applied the patch and the activity stream showed up. I was wondering why it was not reporting. So that patch works.

Searching for similar problems to see if I can find an existing thread or patch.

Cyberflyer’s picture

Turns out that (a) the first two reports were associated with the activity stream bug. The patch above fixed that on both my local server and my production server.

The editing bug does NOT appear on my local machine, but is still in place on the Production server. But we can disregard the two error log entries that worry about a missing table.

Just double checked and the group configuration is identical on local host for dev as it is on production server.

ezra-g’s picture

Status: Active » Postponed (maintainer needs more info)

Does this problem persist with the latest nightly dev snapshot of Commons?

Cyberflyer’s picture

Version: » 6.x-2.x-dev

Let me check.

Problem only evidences itself on my production server. Nervous about going to a dev release there.

The link you sent includes : 6.x-2.4 tar.gz (16 MB) 2012-Jan-18 as the latest recommended and a bunch of devs.

I'd assume the 6.x-2.4 is later than what I have, which is 6.x-2.3. That is recommended, right, safe to use on production systems?

Cyberflyer’s picture

I installed 6.x-2.4 on my local dev version and when Update completed I got this:

user warning: Duplicate key name 'uid' query: ALTER TABLE nscf_prod_og_uid ADD INDEX uid (uid) in /Users/larrylowe/Sites/nscf-prod/includes/database.mysql-common.inc on line 442.

Do I worry? Or did MySQL prevent the duplicate key name -- because the index was already there?

Further down, among the confirmations, was this:

og module
Update #6205
Failed: ALTER TABLE {og_uid} ADD INDEX uid (uid)

PHP MyAdmin shows this:

Keyname	Type	Unique	Packed	Column	Cardinality	Collation	Null	Comment
PRIMARY	BTREE	Yes	No	nid	22	A		
                                uid	44	A	
uid	BTREE	No	No	uid	22	A		

ezra-g’s picture

No need to worry - as per the release notes, it is safe to ignore that error.

Cyberflyer’s picture

Status: Postponed (maintainer needs more info) » Active

OK, thanks.

1) Read the release notes, ;-)
2) Installed 6.x-2.4 on production.
3) Completed the update, cache flush and all reset procedures in the release notes.
4) Cleared the cache again, for good luck/safety's sake.
5) Went to the target page, which has a custom URL based on title.
6) Pressed the 'Edit' tab which loaded the /node/21/edit page, based on the node number.
7) Did a minor edit.
8) Clicked 'save' button
9) Got a page containing the error "File not Found" at the same URL I was just on.
10) Put the cursor in the browser address field and hit the return key.
11) Got the same URL with the editor fully populated.
12) The edited data had not been stored.

So the error is now friendlier -- it does not invoke the 501 error visibly.

But I am in the same state in this page on this installation -- I cannot edit this group.

Cyberflyer’s picture

Issue summary: View changes

Added more info on output.

japerry’s picture

Issue summary: View changes
Status: Active » Closed (won't fix)

Marking as won't fixed as this is no longer relevant for newest versions of commons, and the 6.x-2.x is only getting security fixes. You can also visit #2142923: Upgrading From Drupal 1.5 to 2.x or 3.x for the update status of legacy commons installs and more information on upgrading to the latest version of Commons.