|
|
Rank: Fanatic
Joined: 11/24/2006
Posts: 322
Location: Stockholm, Sweden
|
I have an error log that fills up really quickly. Looking at the log using Umbraco stats there is at least one error per minute and I haven't a clue what causes them or how to get rid of the errors since the site seems to be working fine (although a bit slow sometimes due to poor hosting). Some sample entries: ...and so on. As I mentioned, the hosting provider sucks, for example the time setting at the server seems to be all wrong and we've been having trouble with other things. They don't have AJAX installed but I run the DLL's in my local bin catalog and it seems to be working. Please help me! I don't know what these errors mean or how to solve them!
Web Developer at Kärnhuset - http://www.karnhuset.net - Stockholm, Sweden
|
|
Rank: Fanatic
Joined: 11/24/2006
Posts: 322
Location: Stockholm, Sweden
|
Having checked my local development server I see that not only the live server but also my local development server has a lot of error log entries of the kind listed above. "/umbraco/ping.aspx" is very common in the log, but perhaps that's just because it's accessed often? Other common references are: "/umbraco/ImageGen.aspx" "/umbraco/keepalive.aspx" "/ScriptResource.axd" "/WebResource.axd" When using special tools like TH Umbraco Utilities I get log entries like this: "/umbraco/webservices/thUmbracoUtilities.asmx" I'm not expecting anyone to debug my installation for me - I'm just curious to know where I can find more detailed information about these errors. Is it normal to have roughly 200 error log entries in just 20 minutes? I have checked the permissions for the umbraco catalog and they seem to be fine. Since the web server does not have ASP.NET AJAX installed I have made sure that: System.Web.Extension.dll System.Web.Extension.Design.dll ...are in my local bin directory. I have also put a "WebResource.axd"-file in my root. The website is also running with directory URL's and the wildcard mapping has been routed to the aspnet_isapi.dll, just like it should. I really need to know what else to look at in order to debug my installation. As I said, the error log entries don't give me enough information. Thanks in advance! /Thomas
Web Developer at Kärnhuset - http://www.karnhuset.net - Stockholm, Sweden
|
|
Rank: Fanatic
Joined: 11/24/2006
Posts: 322
Location: Stockholm, Sweden
|
There is also a strange behavior when I look at the ordinary log entries. For some reason some application keeps starting all the time: Code:
25988 0 -1 11/1/2007 6:33:41 PM System Application started at 11/1/2007 6:33:41 PM SYSTEM DATA: umbraco master root
25987 0 -1 11/1/2007 6:33:41 PM System Application started at 11/1/2007 6:33:41 PM SYSTEM DATA: umbraco master root
25978 0 -1 11/1/2007 6:30:57 PM System Application started at 11/1/2007 6:30:57 PM SYSTEM DATA: umbraco master root
25953 0 -1 11/1/2007 6:25:08 PM System Application started at 11/1/2007 6:25:08 PM SYSTEM DATA: umbraco master root
25951 0 -1 11/1/2007 6:25:03 PM System Application started at 11/1/2007 6:25:03 PM SYSTEM DATA: umbraco master root
25930 0 -1 11/1/2007 6:20:25 PM System Application started at 11/1/2007 6:20:25 PM SYSTEM DATA: umbraco master root
25921 0 -1 11/1/2007 6:19:49 PM System Application started at 11/1/2007 6:19:49 PM SYSTEM DATA: umbraco master root
25920 0 -1 11/1/2007 6:19:48 PM System Application started at 11/1/2007 6:19:48 PM SYSTEM DATA: umbraco master root
25888 0 -1 11/1/2007 6:16:41 PM System Application started at 11/1/2007 6:16:41 PM SYSTEM DATA: umbraco master root
25887 0 -1 11/1/2007 6:16:41 PM System Application started at 11/1/2007 6:16:41 PM SYSTEM DATA: umbraco master root
25856 0 -1 11/1/2007 6:13:55 PM System Application started at 11/1/2007 6:13:55 PM SYSTEM DATA: umbraco master root
Is this normal? I'm starting to suspect that one of the extensions I'm running on this site is responsible for all these strange error log entries. For example, I'm running Morten Bock's SafeMailLink add-on. It's added to umbraco as an HTTPModule on all pages served which could explain the fequency(?) Right now my site is generating 1000 error log entries every hour(!) This won't work in the long run...
Web Developer at Kärnhuset - http://www.karnhuset.net - Stockholm, Sweden
|
|
Rank: Umbracoholic
Joined: 9/8/2006
Posts: 1,541
Location: KY, USA
|
The application starting could be caused by the application pool in IIS restarting. Can you put your umbraco site in its own app pool to isolate it? Might help. No idea about the other errors. cheers, doug.
MVP 2007-2009 - Official Umbraco Trainer for North America - Percipient Studios
|
|
Rank: Fanatic
Joined: 11/24/2006
Posts: 322
Location: Stockholm, Sweden
|
I've done some testing and everything so far indicates that it's the add-on "SafeMailLink" that is causing all the errors in the log. I disabled it on my developing server and the errors stopped there. I'm going to try to disable it on the live server as well to see if I get the same result. Both binaries and source code is available at: http://www.mortenbock.dk/protect-e-mail-links-in-umbraco-v3-58.htm. I have looked through the source code but I don't know what to look for. Perhaps someone else knows? Morten? Tobbe? Perhaps this add-on does not work if you are using directory URL's (with the wildcard application mapping to aspnet_isapi.dll)? Just a thought...
Web Developer at Kärnhuset - http://www.karnhuset.net - Stockholm, Sweden
|
|
Rank: Fanatic
Joined: 11/24/2006
Posts: 322
Location: Stockholm, Sweden
|
Thomas Kahn wrote:
I've done some testing and everything so far indicates that it's the add-on "SafeMailLink" that is causing all the errors in the log. I disabled it on my developing server and the errors stopped there. I'm going to try to disable it on the live server as well to see if I get the same result.
Yepp - that stopped the error log from being flooded. Now the big question is what makes this component generate all these errors?
Web Developer at Kärnhuset - http://www.karnhuset.net - Stockholm, Sweden
|
|
Rank: Devotee
Joined: 7/19/2006
Posts: 97
|
Hmm, sounds strange.
I will check my logs, but I have run this for long and didn't notice anything.
|
|
Rank: Fanatic
Joined: 11/24/2006
Posts: 322
Location: Stockholm, Sweden
|
Hi Tobbe! That would be great! If you have the same problem perhaps it's time to look at the source code. I have a feeling the problem isn't that easy to diagnose though. My guess is that the component somehow collides with Umbraco(?)
Web Developer at Kärnhuset - http://www.karnhuset.net - Stockholm, Sweden
|
|
Rank: Fanatic
Joined: 11/24/2006
Posts: 322
Location: Stockholm, Sweden
|
That would be great! If you are also getting these errors, perhaps it's time to take a closer look at the source code. I have a feeling this isn't going to be as easy as correcting a line of code. My guess is that the component and Umbraco collides somehow.
Web Developer at Kärnhuset - http://www.karnhuset.net - Stockholm, Sweden
|
|
Rank: Fanatic
Joined: 11/24/2006
Posts: 322
Location: Stockholm, Sweden
|
Did you find anything in the logs? Sorry for the impatience but it would be great if I could get this component running without the error logs. :)
Web Developer at Kärnhuset - http://www.karnhuset.net - Stockholm, Sweden
|
|
Rank: Fanatic
Joined: 11/24/2006
Posts: 322
Location: Stockholm, Sweden
|
Douglas Robar wrote:
The application starting could be caused by the application pool in IIS restarting. Can you put your umbraco site in its own app pool to isolate it? Might help.
Hi Doug! I tried this and I'm not sure if it worked. It seems like it might generate fewer restart log entries, but they still keep coming in. I'll just monitor the stats to see if I get any wiser. Thanks for the input! /T
Web Developer at Kärnhuset - http://www.karnhuset.net - Stockholm, Sweden
|
|
Rank: Fanatic
Joined: 11/24/2006
Posts: 322
Location: Stockholm, Sweden
|
I still keep getting lots of "Application started" entries in my Umbraco log: Code:
36083 0 -1 2007-11-21 09:43:37 System Application started at 11/21/2007 9:43:37 AM SYSTEM DATA: umbraco master root
36082 0 -1 2007-11-21 09:41:45 System Application started at 11/21/2007 9:41:45 AM SYSTEM DATA: umbraco master root
36081 0 -1 2007-11-21 09:41:45 System Application started at 11/21/2007 9:41:45 AM SYSTEM DATA: umbraco master root
36080 0 -1 2007-11-21 09:41:45 System Application started at 11/21/2007 9:41:45 AM SYSTEM DATA: umbraco master root
36079 0 -1 2007-11-21 09:40:12 System Application started at 11/21/2007 9:40:12 AM SYSTEM DATA: umbraco master root
36078 0 -1 2007-11-21 09:35:13 System Application started at 11/21/2007 9:35:13 AM SYSTEM DATA: umbraco master root
36077 0 -1 2007-11-21 09:29:44 System Application started at 11/21/2007 9:29:44 AM SYSTEM DATA: umbraco master root
36076 0 -1 2007-11-21 09:29:11 System Application started at 11/21/2007 9:29:11 AM SYSTEM DATA: umbraco master root
36075 0 -1 2007-11-21 09:29:11 System Application started at 11/21/2007 9:29:11 AM SYSTEM DATA: umbraco master root
36074 0 -1 2007-11-21 09:29:11 System Application started at 11/21/2007 9:29:11 AM SYSTEM DATA: umbraco master root
36073 0 -1 2007-11-21 09:29:11 System Application started at 11/21/2007 9:29:11 AM SYSTEM DATA: umbraco master root
36072 0 -1 2007-11-21 09:29:11 System Application started at 11/21/2007 9:29:11 AM SYSTEM DATA: umbraco master root
36071 0 -1 2007-11-21 09:28:20 System Application started at 11/21/2007 9:28:20 AM SYSTEM DATA: umbraco master root
36070 0 -1 2007-11-21 09:28:08 System Application started at 11/21/2007 9:28:08 AM SYSTEM DATA: umbraco master root
36069 0 -1 2007-11-21 09:24:50 System Application started at 11/21/2007 9:24:50 AM SYSTEM DATA: umbraco master root
36068 0 -1 2007-11-21 09:24:43 System Application started at 11/21/2007 9:24:43 AM SYSTEM DATA: umbraco master root
36067 0 -1 2007-11-21 09:24:43 System Application started at 11/21/2007 9:24:43 AM
The site should be placed in it's own application pool (I'm not 100% sure since I'm not that familiar with Plesk - the admin environment that my web hosting provider uses). Are there more things I could try? Any settings in the config files that I could try? Thanks in advance! /Thomas
Web Developer at Kärnhuset - http://www.karnhuset.net - Stockholm, Sweden
|
|
Rank: Enthusiast
Joined: 9/5/2006
Posts: 28
|
I've got the same problem on pretty much all of my sites on all of my servers. A look in the umbracoLog Code:
Select * From umbracoLog
Where logComment like 'Application started at%'
Order By Datestamp Desc
Gives me: Code:
25723 0 -1 2007-11-22 19:42:04.213 System Application started at 2007-11-22 19:42:04
25722 0 -1 2007-11-22 19:42:04.213 System Application started at 2007-11-22 19:42:04
25718 0 -1 2007-11-22 19:37:04.213 System Application started at 2007-11-22 19:37:04
25717 0 -1 2007-11-22 19:36:59.963 System Application started at 2007-11-22 19:36:59
25714 0 -1 2007-11-22 19:31:04.340 System Application started at 2007-11-22 19:31:04
25713 0 -1 2007-11-22 19:31:04.183 System Application started at 2007-11-22 19:31:04
25712 0 -1 2007-11-22 19:31:04.167 System Application started at 2007-11-22 19:31:04
25711 0 -1 2007-11-22 19:31:04.167 System Application started at 2007-11-22 19:31:04
25710 0 -1 2007-11-22 19:30:59.933 System Application started at 2007-11-22 19:30:59
25709 0 -1 2007-11-22 19:30:59.933 System Application started at 2007-11-22 19:30:59
25680 0 -1 2007-11-22 19:17:55.823 System Application started at 2007-11-22 19:17:55
25663 0 -1 2007-11-22 19:10:54.527 System Application started at 2007-11-22 19:10:54
25661 0 -1 2007-11-22 18:55:53.527 System Application started at 2007-11-22 18:55:53
25646 0 -1 2007-11-22 18:04:09.417 System Application started at 2007-11-22 18:04:09
25613 0 -1 2007-11-22 17:49:08.650 System Application started at 2007-11-22 17:49:08
etc...
The web sites in IIS (about 20 of em on five different machines) behaves all the same. All sites are isolated in separate Application Pools, that shuts down the worker processes after 20 minutes and recycles it after 1740 minutes (standard settings as far as i know). No dll's was updated during the timespan above. I run Windows 2003/Umbraco 3.0.3 on all the boxes. Nothing special when it comes to configuration... It's weird how the sites restarts all the time. I did see however that the restarts was alot fewer when i ran <?ASPNET_FORM disablescriptmanager="true"> on a couple of webs instead of false/default. - How could that be connected, does the AJAX-calls timeout or something? - Im not sure weather or not access to the /umbraco-files could have something to do with it or if they are even called. Any clues?
|
|
Rank: Fanatic
Joined: 11/24/2006
Posts: 322
Location: Stockholm, Sweden
|
Interesting! Your theory about AJAX might be correct. The site that I'm currently having problems with has the same setup as yours. I run Umbraco 2.x on another server though and on that installation I don't have the problem with the application restarting. I will try disabling the script manager on all my form tags on my site as well to see what happens.
Web Developer at Kärnhuset - http://www.karnhuset.net - Stockholm, Sweden
|
|
Rank: Enthusiast
Joined: 9/5/2006
Posts: 28
|
Please report back when possible.
: )
|
|
Rank: Fanatic
Joined: 11/24/2006
Posts: 322
Location: Stockholm, Sweden
|
I have nothing to report other than that I have to manually clean my logs regularly to prevent the database from growing too big (using Thomas Höhler's Umbraco Utilities). Today I wiped around 95000(!) log entries on one of my installations, and that was just messages of type "System". I'd really appreciate it if anyone could give us the clue that solves this puzzle.
Web Developer at Kärnhuset - http://www.karnhuset.net - Stockholm, Sweden
|
|
Rank: Aficionado
Joined: 10/30/2007
Posts: 196
Location: Bellingham
|
Thomas - As it turns out this is related to a defect in Umbraco 3.*. Niels is actively working on a fix and will release it with 3.0.5 soon. Here is the related thread: http://forum.umbraco.org/21426#post-21426-Paul
motusconnect.com :: level-2 certified :: MVP 2008/2009 See you at Codegarden08 US in Chicago!
|
|
Rank: Fanatic
Joined: 11/24/2006
Posts: 322
Location: Stockholm, Sweden
|
Thank you! Thank you! Thank you! :) I'm so glad to finally get an explanation! I must have spent several days tweaking and reconfiguring my sites, removing add-ons, reconfiguring servers, fiddling with macros etc. Well - at least now I know what the problem is. Hope to see a fix soon!
Web Developer at Kärnhuset - http://www.karnhuset.net - Stockholm, Sweden
|
|
Rank: Fanatic
Joined: 11/24/2006
Posts: 322
Location: Stockholm, Sweden
|
Has this issue been resolved yet? Today a client's web stopped working (unable to edit the site) due to a database that had grown totally out of proportions. When I open up the database it displays no less than 113462 log entries in the umbracoLog table(!) Almost all of the entries are: System Application started at 2008-06-21 19:36:05 I tried running Thomas Höhler's Umbraco Utilities, but it failed because there was no available space in the database. Can I clear the entire log manually (using SQL) or will this affect the functionality of Umbraco? I'm guessing not, just wanted to check. Thanks in advance! /Thomas K
Web Developer at Kärnhuset - http://www.karnhuset.net - Stockholm, Sweden
|
|
|
Guest |