This object is in archive! 
Upvote 2

Often 500 server error on New 3.1

Archived Jirka Menion Mlavec 9 years ago

Quite ofter (let's say every fifth request), new UseResponse 3.1 ends on timeout with error message on attached screenshot (probably screen from my webhosting provider?).


Most annoying it's when you write some post and press "Send". Text disappear and change for a "loading", so it's now possible to "copy" text for a later "paste" in second attempt.


Is there possibility to enable some "debug" mode to see what happens? Thanks

Replies (9)

photo
1

Jirka,

What does you apache error log says?

The debug mode could be switched on in configs/config.php

Just make show_errors to true, but better check apache error log to see what the exact error is.

photo
1

Unfortunately I do not have direct access to apache on my webhosting. I'll give a try your "show_errors" parameter and if there will be nothing useful, I'll try to contact my Webhosting provider for extra help with obtaining error logs. Thanks

photo
1

You better contact hosting provider now, as 500 error caused by the server is initially because hosting can't perform requested actions. It can be either db queue with requests, or too many requests at one time. Let us know what will they provide to you, when you get answer from them

photo
1

So after communication with my web provider, I get these data (I'll translate it from Czech to English for you):


Good day, we store only access logs for counting of statistics. So it is only possible to find out with which status, task ends. So here is list of results for your domain help.locusmap.eu which ends with code 500:


  1. Nov 19 00:01:11 jack pound: 91.200.12.66 GET

    /topic/update_locus_to_new_version_of_vector_map_library_mapsforge_0_3_0

    HTTP/1.1 - HTTP/1.1 500 Internal Server Error (help.locusmap.eu/- ->

    127.0.0.1:5080) 60.007 sec

  2. Nov 19 00:01:43 jack pound: 5.255.253.113

    GET /responses/filter_geocaches_by_more_attributes HTTP/1.1 - HTTP/1.1

    500 Internal Server Error (help.locusmap.eu/- -> 127.0.0.1:5080)

    60.062 sec

  3. Nov 19 00:02:11 jack pound: 66.249.78.81 GET

    /topic/some-questions-about-track-recording HTTP/1.1 - HTTP/1.1 500

    Internal Server Error (help.locusmap.eu/- -> 127.0.0.1:5080) 61.065

    sec

  4. Nov 19 00:06:52 jack pound: 5.255.253.113 GET

    /search/Tracks/page/13 HTTP/1.1 - HTTP/1.1 500 Internal Server Error

    (help.locusmap.eu/- -> 127.0.0.1:5080) 60.062 sec

  5. Nov 19 00:07:10

    jack pound: 66.249.78.88 GET

    /topic/shading-maps-is-too-slow-in-small-zooms HTTP/1.1 - HTTP/1.1 500

    Internal Server Error (help.locusmap.eu/- -> 127.0.0.1:5080) 60.061

    sec

  6. Nov 19 00:08:05 jack pound: 2a01:430:1::115:4 GET

    /responses/gpx-import-incorrect-time-zone/rss HTTP/1.1 - HTTP/1.1 500

    Internal Server Error (help.locusmap.eu/- -> 127.0.0.1:5080) 60.061

    sec

  7. Nov 19 00:08:34 jack pound: 5.255.253.113 GET

    /search/store/page/10 HTTP/1.1 - HTTP/1.1 500 Internal Server Error

    (help.locusmap.eu/- -> 127.0.0.1:5080) 60.061 sec

  8. Nov 19 00:10:50

    jack pound: 5.255.253.113 GET /search/Tracks/page/30 HTTP/1.1 - HTTP/1.1

    500 Internal Server Error (help.locusmap.eu/- -> 127.0.0.1:5080)

    60.062 sec

  9. Nov 19 00:11:10 jack pound: 66.249.78.74 GET

    /topic/automatic_poi_inclusion_as_waypoints_into_around_active_followed_track

    HTTP/1.1 - HTTP/1.1 500 Internal Server Error (help.locusmap.eu/- ->

    127.0.0.1:5080) 60.042 sec

  10. Nov 19 00:11:13 jack pound: 93.100.100.95

    GET /topic/greater-res-satellite HTTP/1.1 - HTTP/1.1 500 Internal

    Server Error (help.locusmap.eu/- -> 127.0.0.1:5080) 60.062 sec

  11. Nov

    19 00:11:17 jack pound: 93.100.100.95 GET

    /topic/how-can-i-navigate-with-this-software-on-foreign-land HTTP/1.1 -

    HTTP/1.1 500 Internal Server Error (help.locusmap.eu/- ->

    127.0.0.1:5080) 60.062 sec

  12. Nov 19 00:11:30 jack pound: 93.100.100.95

    GET

    /topics/all/status/all/category/all/sort/new_updated/page/3?object_type=all&status=all&category_slug=all&order=new_updated&archived=0&page=3

    HTTP/1.1 - HTTP/1.1 500 Internal Server Error (help.locusmap.eu/- ->

    127.0.0.1:5080) 60.062 sec

-----


I have also found a possibility to enable PHP logging thanks to ".user.ini" fileso I've enabled


log_errors = 1

error_reporting = E_ALL


And here is the result:


  1. [19-Nov-2014 10:03:33 GMT] PHP Fatal error: Uncaught exception 'Zend_Controller_Router_Exception' with message 'No route matched the request' in /hosting/www/locusmap.eu/help/application/3.1/library/hotFiles.php:30194
  2. Stack trace:
  3. #0 /hosting/www/locusmap.eu/help/application/3.1/library/Singular/Core/Router.php(70): Zend_Controller_Router_Rewrite->route(Object(Singular_Controller_Request_Http))
  4. #1 /hosting/www/locusmap.eu/help/application/3.1/library/Singular/Core/Environment.php(221): Singular_Core_Router->getRouteName()
  5. #2 /hosting/www/locusmap.eu/help/application/3.1/library/Singular/Core/Environment.php(551): Singular_Core_Environment->getRouteName()
  6. #3 /hosting/www/locusmap.eu/help/application/3.1/library/Singular/View/HtmlElement.php(130): Singular_Core_Environment->getPreferredInterface()
  7. #4 /hosting/www/locusmap.eu/help/application/3.1/library/Singular/View/Helper/ImageSrc.php(19): Singular_View_HtmlElement->compile(NULL)
  8. #5 /hosting/www/locusmap.eu/help/application/3.1/library/Singular/View.php(183): Singular_View_Helper_ImageSrc->imageSrc('br in /hosting/www/locusmap.eu/help/application/3.1/library/hotFiles.php on line 30194

Whole file is filled with only this!! error message, nothing more.


If there will be something more i can do, feel free to write me.[

photo
2

I have faced the same problem and got the same error message in Apache server error log. It is blocking migrating to Useresponse 3.1. Could you please let us know solution of this issue?

Thanks

photo
1

Govind,

We can't identify the problem without more detailed crash report from apache error log or from crash report of the system that you should get via email.

How can we debug and catch the problem in your system?

photo
1

There is log from apache error log when mentioned error occurs

  1. [Tue Nov 25 15:42:42 2014] [error] [client 68.180.228.162] PHP Fatal error: Uncaught exception 'Zend_Controller_Router_Exception' with message 'No route matched the request' in /var/www/locusmap.eu/help/application/3.1/library/hotFiles.php:30194\nStack trace:\n#0 /var/www/locusmap.eu/help/application/3.1/library/Singular/Core/Router.php(70): Zend_Controller_Router_Rewrite->route(Object(Singular_Controller_Request_Http))\n#1 /var/www/locusmap.eu/help/application/3.1/library/Singular/Core/Environment.php(221): Singular_Core_Router->getRouteName()\n#2 /var/www/locusmap.eu/help/application/3.1/library/Singular/Core/Environment.php(551): Singular_Core_Environment->getRouteName()\n#3 /var/www/locusmap.eu/help/application/3.1/library/Singular/View/HtmlElement.php(130): Singular_Core_Environment->getPreferredInterface()\n#4 /var/www/locusmap.eu/help/application/3.1/library/Singular/View/Helper/ImageSrc.php(19): Singular_View_HtmlElement->compile(NULL)\n#5 /var/www/locusmap.eu/help/application/3.1/library/Singular/View.php(183): Singular_View_Helper_ImageSrc->imageSrc('brand/icon.png', 'system')\n#6 in /var/www/locusmap.eu/help/application/3.1/library/hotFiles.php on line 30194

photo
1

The problem is that this errror is very general and by that log we can't identify the issue. BTW, no matter how many times we tried to click on topics, pages in your installation - we never got 500 error.

photo
1

It seems that on new server we don't get error 500 (probably different time-out). But from my opinion is the situation similar. Every 3th ot 4th request takes more then 60 second to load. I've changed apache LogLevel=debug but the output into error.log is similar. There is new message:


  1. [Tue Nov 25 16:21:53 2014] [debug] mod_deflate.c(700): [client 81.90.255.82] Zlib: Compressed 754212 to 171129 : URL /static/6211c40af080ee0a877cb83d3734d3bd.min.js, referer: http://help.locusmap.eu/dashboard
  2. [Tue Nov 25 16:22:15 2014] [debug] mod_deflate.c(700): [client 88.209.208.4] Zlib: Compressed 1119 to 398 : URL /index.php, referer: http://www.locusmap.eu/

Is there any way how to indetify this issue more detailed?

photo
1

We've tried to click system one more time but everything seems to work fine. Also, we've switched off mod_deflate in .htaccess, maybe this is the reason! Let me know if it works fine now.

If not, please create team viewer session in order for us to see the problem.

photo
1

We are finally able to dig deeper to settings of MySQL and PHP and seems that problems are caused by some queries to DB. I spend on it almost half a day till I discovered how to set and get some better feedback for you, anyway seems I have something to start from:


Check please screenshots, there is exactly clear what happen - query takes too long and all other queries are waiting due to DB lock.


I've enabled logging for a queries longer then 15 second and I've attached it to this post. There is clearly visible request that was performed when I refreshed page.

photo
2

Fixed in 3.1.1

photo
1

I wants to confirm, that since update to 3.1.1 during weekend, our server runs smooth and without any problems. Good work, thanks, topic solved.

photo
1

You are welcome

Replies have been locked on this page!