Try our conversational search powered by Generative AI!

EPiServer returns Server Error 500 sometimes - but not allways

Vote:
 

When one clients browser navigates to the site he gets a "server error 500" while another client at the same moment can visit the site without problem.

I would like to show you the log file from when the site crashes and when it doesn't:

Here is when the error occurs:

2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadString - 18.3.1 Scheduler info:  3028> Reading string...
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Parse - 18.3.1 Scheduler info:  Parse: IN: url = pipe://_LM_W3SVC_2_ROOT/Callback.rem
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Parse - 18.3.1 Scheduler info:  Parse: OUT: pipename = _LM_W3SVC_2_ROOT, objuri = Callback.rem
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadUShort - 18.3.1 Scheduler info:  3028> Reading ushort...
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadString - 18.3.1 Scheduler info:  3028> Reading string...
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadString - 18.3.1 Scheduler info:  3028> Reading string...
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadHeaders - 18.3.1 Scheduler info:  3028> Reading ushort...
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadStream - 18.3.1 Scheduler info:  3028> Read stream...
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadStream - 18.3.1 Scheduler info:  3028> Read stream len 461
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] EPiServer.DataAbstraction.ScheduledJob.Execute - 3.1.3  Scheduled job has already been executed 8de3f2b0-6e12-4716-a6f9-736f7e2f8944
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Write - 18.3.1 Scheduler info:  3028> Write string
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Write - 18.3.1 Scheduler info:  3028> Write ushort 65442
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Write - 18.3.1 Scheduler info:  3028> Write stream 23
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadString - 18.3.1 Scheduler info:  3028> Reading string...
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Parse - 18.3.1 Scheduler info:  Parse: IN: url = pipe://_LM_W3SVC_2_ROOT/Callback.rem
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Parse - 18.3.1 Scheduler info:  Parse: OUT: pipename = _LM_W3SVC_2_ROOT, objuri = Callback.rem
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadUShort - 18.3.1 Scheduler info:  3028> Reading ushort...
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadString - 18.3.1 Scheduler info:  3028> Reading string...
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadString - 18.3.1 Scheduler info:  3028> Reading string...
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadHeaders - 18.3.1 Scheduler info:  3028> Reading ushort...
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadStream - 18.3.1 Scheduler info:  3028> Read stream...
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadStream - 18.3.1 Scheduler info:  3028> Read stream len 187
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Write - 18.3.1 Scheduler info:  3028> Write string
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Write - 18.3.1 Scheduler info:  3028> Write ushort 65442
2011-05-23 13:20:04,761 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Write - 18.3.1 Scheduler info:  3028> Write stream 310

And here is when it doesn't occur:

2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadString - 18.3.1 Scheduler info:  3028> Reading string...
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Parse - 18.3.1 Scheduler info:  Parse: IN: url = pipe://_LM_W3SVC_2_ROOT/Callback.rem
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Parse - 18.3.1 Scheduler info:  Parse: OUT: pipename = _LM_W3SVC_2_ROOT, objuri = Callback.rem
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadUShort - 18.3.1 Scheduler info:  3028> Reading ushort...
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadString - 18.3.1 Scheduler info:  3028> Reading string...
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadString - 18.3.1 Scheduler info:  3028> Reading string...
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadHeaders - 18.3.1 Scheduler info:  3028> Reading ushort...
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadStream - 18.3.1 Scheduler info:  3028> Read stream...
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadStream - 18.3.1 Scheduler info:  3028> Read stream len 461
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] EPiServer.DataAbstraction.ScheduledJob.Execute - 3.1.3  Scheduled job has already been executed 8de3f2b0-6e12-4716-a6f9-736f7e2f8944
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Write - 18.3.1 Scheduler info:  3028> Write string
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Write - 18.3.1 Scheduler info:  3028> Write ushort 65442
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Write - 18.3.1 Scheduler info:  3028> Write stream 23
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadString - 18.3.1 Scheduler info:  3028> Reading string...
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Parse - 18.3.1 Scheduler info:  Parse: IN: url = pipe://_LM_W3SVC_2_ROOT/Callback.rem
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Parse - 18.3.1 Scheduler info:  Parse: OUT: pipename = _LM_W3SVC_2_ROOT, objuri = Callback.rem
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadUShort - 18.3.1 Scheduler info:  3028> Reading ushort...
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadString - 18.3.1 Scheduler info:  3028> Reading string...
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadString - 18.3.1 Scheduler info:  3028> Reading string...
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadHeaders - 18.3.1 Scheduler info:  3028> Reading ushort...
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadStream - 18.3.1 Scheduler info:  3028> Read stream...
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.ReadStream - 18.3.1 Scheduler info:  3028> Read stream len 187
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Write - 18.3.1 Scheduler info:  3028> Write string
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Write - 18.3.1 Scheduler info:  3028> Write ushort 65442
2011-05-23 13:22:14,765 INFO [PipeServerChannel.ServerMain] Microsoft.Samples.Runtime.Remoting.Channels.Pipe.PipeConnection.Write - 18.3.1 Scheduler info:  3028> Write stream 310
2011-05-23 13:22:17,890 DEBUG [9] EPiServer.Search.RequestQueueHandler.ProcessQueue - Start dequeue unprocessed items
2011-05-23 13:22:17,890 DEBUG [9] EPiServer.Search.RequestQueueHandler.ProcessQueue - End dequeue unprocessed items
2011-05-23 13:22:21,906 DEBUG [9] EPiServer.Web.UrlRewriteModuleBase.BeginRequestEventHandler - Starting request with Url http://xxx
2011-05-23 13:22:21,906 INFO [9] EPiServer.Core.PageProviderBase.GetPageInternal - pageLink=3, returning PageData object
2011-05-23 13:22:21,906 INFO [9] EPiServer.Core.PageProviderBase.GetPageInternal - pageLink=3, returning PageData object
[...]

As you can see it's when EpiServer tries to do this


2011-05-23 13:22:17,890 DEBUG [9] EPiServer.Search.RequestQueueHandler.ProcessQueue - Start dequeue unprocessed items

that it fails.

I'm really in need of help. I can't figure out what is going on.



#51048
May 23, 2011 13:42
Vote:
 

Hi Stefan!

Have you looked in the EventViewer to see if there's a more detailed error eventlog available?

If you can find it, please post the complete error including the stacktrace.

The log extracts you posted seem unrelated to the problem, theres only INFO and DEBUG events there (which are
all informational and no errors)

/johan

#51051
May 23, 2011 14:40
Vote:
 

Thanks for your reply, Johan.

There were no logs in the event viewer.

Right now I'm working with the idea that it's a dns problem. Our network technician found some strange stuff and hopefully this will solve the problem.

#51052
May 23, 2011 15:14
Vote:
 

Ok.

But a 500-error in the client browser really indicates that something went wrong (throwed an unhandled exception) within
the server and not some problem at the transportlayer.

Though, if there is serverside code that makes outgoing connections, then of course a network problem
might lead to the client seeing this 500-error, but it would still be a problem with the code not correctly dealing
with the underlying network problem...

/johan

#51060
May 23, 2011 17:23
This thread is locked and should be used for reference only. Please use the Episerver CMS 7 and earlier versions forum to open new discussions.
* You are NOT allowed to include any hyperlinks in the post because your account hasn't associated to your company. User profile should be updated.