HTTP Error 500.1013 #405

johnwp · 2014-12-11T18:46:03Z

I'm running into the following error after deploying my node app to a local instance of IIS 7.5.7600.16385 on 64-bit Windows 7. The Node.js sample apps run without any problems in the same app pool. I have 32-bit applications disallowed. The application runs as expected in debug mode. The helloworld sample app says I'm running IISNode 0.2.16 x64 and Node.js version 0.10.33 x64.

HTTP Error

HRESULT: 0x6d
HTTP status: 500
HTTP subStatus: 1013
HTTP reason: Internal Server Error

Web.config

<configuration>
  <system.webServer>

    <handlers>
      <add name="iisnode" path="app.js" verb="*" modules="iisnode" />
    </handlers>

  </system.webServer>
</configuration>

app.js

var debug = require('debug')('NodeApp');

var express = require('express');
var path = require('path');
var favicon = require('serve-favicon');
var logger = require('morgan');
var cookieParser = require('cookie-parser');
var bodyParser = require('body-parser');

var routes = require('./routes/routes');

var app = express();

// view engine setup
app.set('views', path.join(__dirname, 'views'));
app.set('view engine', 'jade');

// uncomment after placing your favicon in /public
//app.use(favicon(__dirname + '/public/favicon.ico'));
app.use(logger('dev'));
app.use(bodyParser.json());
app.use(bodyParser.urlencoded({ extended: false }));
app.use(cookieParser());
app.use(require('stylus').middleware(path.join(__dirname, 'public')));
app.use(express.static(path.join(__dirname, 'public')));

app.use('/', routes);

app.set('port', process.env.PORT || 3000);

var server = app.listen(app.get('port'), function () {
    debug('Express server listening on port ' + server.address().port);
});

module.exports = app;

Stdout

�[0mGET /app.js �[32m- �[0m- ms - -�[0m

ETW Trace log

<Events>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{9e814aad-3204-11d2-9a82-006008a86939}" />
        <EventID>0</EventID>
        <Version>2</Version>
        <Level>0</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:04.591938000Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="25872" ThreadID="18508" ProcessorID="0" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
    <EventData>
        <Data Name="BufferSize">    8192</Data>
        <Data Name="Version">83951878</Data>
        <Data Name="ProviderVersion">    7601</Data>
        <Data Name="NumberOfProcessors">       4</Data>
        <Data Name="EndTime">130627962108539623</Data>
        <Data Name="TimerResolution">  156001</Data>
        <Data Name="MaxFileSize">       0</Data>
        <Data Name="LogFileMode">0x0</Data>
        <Data Name="BuffersWritten">       4</Data>
        <Data Name="StartBuffers">       1</Data>
        <Data Name="PointerSize">       8</Data>
        <Data Name="EventsLost">       0</Data>
        <Data Name="CPUSpeed">    1696</Data>
        <Data Name="LoggerName">0x5</Data>
        <Data Name="LogFileName">0x6</Data>
        <Data Name="BootTime">130626758531255993</Data>
        <Data Name="PerfFreq">1656484</Data>
        <Data Name="StartTime">130627960845919380</Data>
        <Data Name="ReservedFlags">0x1</Data>
        <Data Name="BuffersLost">       0</Data>
        <Data Name="SessionNameString">iisnode</Data>
        <Data Name="LogFileNameString">C:\Users\John\AppData\Local\Temp\iisnode.etl</Data>
    </EventData>
    <RenderingInfo Culture="en-US">
        <Opcode>Header</Opcode>
        <Provider>MSNT_SystemTrace</Provider>
        <EventName xmlns="http://schemas.microsoft.com/win/2004/08/events/trace">EventTrace</EventName>
    </RenderingInfo>
    <ExtendedTracingInfo xmlns="http://schemas.microsoft.com/win/2004/08/events/trace">
        <EventGuid>{68fdd900-4a3e-11d1-84f4-0000f80464e3}</EventGuid>
    </ExtendedTracingInfo>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>4</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:06.672126500Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="25644" ProcessorID="1" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>iisnode initialized the application manager</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>4</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:06.672128300Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="25644" ProcessorID="1" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>iisnode received a new http request</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>4</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:06.675119600Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="25644" ProcessorID="0" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>iisnode initialized a new node.exe process</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>4</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:06.675375500Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="25644" ProcessorID="0" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>iisnode initialized a new node.js application</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:06.675397300Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="25644" ProcessorID="0" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode increases pending async operation count</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>4</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:06.675434100Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="25644" ProcessorID="0" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode scheduled a retry of a named pipe connection to the node.exe process </Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>4</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:06.675454000Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="25644" ProcessorID="0" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode dispatched new http request</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:06.675456400Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="25644" ProcessorID="0" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode decreases pending async operation count</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:06.675458200Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="25644" ProcessorID="0" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode leaves CNodeHttpModule::OnExecuteRequestHandler with RQ_NOTIFICATION_PENDING</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>4</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:06.925248700Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="22996" ProcessorID="0" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode scheduled a retry of a named pipe connection to the node.exe process </Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:07.175452200Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="22996" ProcessorID="0" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode created named pipe connection to the node.exe process</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:07.175482400Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="22996" ProcessorID="0" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode initiated sending http request headers to the node.exe process and completed synchronously</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:07.175483600Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="22996" ProcessorID="0" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode finished sending http request headers to the node.exe process</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:07.175487200Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="22996" ProcessorID="0" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode initiated reading http request body chunk and completed synchronously</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:07.175488400Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="22996" ProcessorID="0" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode detected the end of the http request body</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:07.175490800Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="22996" ProcessorID="0" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode starting to read http response</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:28:07.175494400Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="22996" ProcessorID="0" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode initiated reading http response chunk and will complete asynchronously</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:30:07.188935000Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="22996" ProcessorID="3" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode starting to process http response status line</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>2</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:30:07.188939200Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="22996" ProcessorID="3" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode failed to process http response status line</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:30:07.188940400Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="22996" ProcessorID="3" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode request processing failed for reasons unrecognized by iisnode</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:30:07.189486100Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="22996" ProcessorID="3" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode decreases pending async operation count</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:30:07.189487900Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="22996" ProcessorID="3" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode posts completion from SendEmtpyResponse</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:30:07.189865200Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="25644" ProcessorID="3" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode increases pending async operation count</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:30:07.189867100Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="25644" ProcessorID="3" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode enters CNodeHttpModule::OnAsyncCompletion callback</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:30:07.189867700Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="25644" ProcessorID="3" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode decreases pending async operation count</Data>
</Event>
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
    <System>
        <Provider Guid="{1040dfc4-61db-484a-9530-584b2735f7f7}" />
        <EventID>0</EventID>
        <Version>0</Version>
        <Level>5</Level>
        <Task>0</Task>
        <Opcode>0</Opcode>
        <Keywords>0x0</Keywords>
        <TimeCreated SystemTime="2014-12-11T12:30:07.189868900Z" />
        <Correlation ActivityID="{00000000-0000-0000-0000-000000000000}" />
        <Execution ProcessID="19876" ThreadID="25644" ProcessorID="3" KernelTime="0" UserTime="0" />
        <Channel />
        <Computer />
    </System>
        <Data>{4BC12ACB-77FA-4382-84C7-743800CBC57E}: iisnode leaves CNodeHttpModule::OnAsyncCompletion with RQ_NOTIFICATION_FINISH_REQUEST</Data>
</Event>
</Events>

The text was updated successfully, but these errors were encountered:

JustinNeumann · 2015-04-28T16:31:00Z

Hi John, any solution so far?

I am having the same messages. I used to fetch WordPress data prior to this yet unobserved bug

Here the error message

iisnode encountered an error when processing the request.

HRESULT: 0x6d
HTTP status: 500
HTTP subStatus: 1013
HTTP reason: Internal Server Error
You are receiving this HTTP 200 response because system.webServer/iisnode/@devErrorsEnabled configuration setting is 'true'.

In addition to the log of stdout and stderr of the node.exe process, consider using debugging and ETW traces to further diagnose the problem.

The node.exe process has not written any information to stderr or iisnode was unable to capture this information. Frequent reason is that the iisnode module is unable to create a log file to capture stdout and stderr output from node.exe. Please check that the identity of the IIS application pool running the node.js application has read and write access permissions to the directory on the server where the node.js application is located. Alternatively you can disable logging by setting system.webServer/iisnode/@loggingEnabled element of web.config to 'false'.

I solved this custom development issue by setting up the Azure SDK on my local pc, then using the 'command azure site log download' to fetch the error logs and continue with these's content's information.

rramachand21-zz · 2015-04-28T17:16:08Z

500.1013 usually means your node.exe is crashing due to some exception

alvarotrigo · 2015-11-18T13:15:04Z

+1
I have the same problem.

HRESULT: 0x6d
HTTP status: 500
HTTP subStatus: 1013
HTTP reason: Internal Server Error

You are receiving this HTTP 200 response because system.webServer/iisnode/@devErrorsEnabled configuration setting is 'true'.

In addition to the log of stdout and stderr of the node.exe process, consider using debugging and ETW traces to further diagnose the problem.

The node.exe process has not written any information to stderr or iisnode was unable to capture this information. Frequent reason is that the iisnode module is unable to create a log file to capture stdout and stderr output from node.exe. Please check that the identity of the IIS application pool running the node.js application has read and write access permissions to the directory on the server where the node.js application is located. Alternatively you can disable logging by setting system.webServer/iisnode/@loggingEnabled element of web.config to 'false'.

It doesn't specify to which folder we should change the persmissions. I did it on iisnode within my express app in (C:\Program Files\iisnode\www\express\bin), and just in case, over the whole express app folder (C:\Program Files\iisnode\www\express). Nothing changed.

I also tried it over logging folder of ISS (%SystemDrive%\inetpub\logs\LogFiles). No success either.

alvarotrigo · 2015-11-19T12:47:18Z

More info about the problem here: http://stackoverflow.com/questions/33781455/500-1013-error-when-using-iisnode-and-express

tjanczuk · 2015-11-19T13:49:44Z

Have you tried running iisnode sample hello world on that machine? This will help rule out iis/iisnode/node installation issues.

Beyond that, can you capture ETW logs as described at http://tomasz.janczuk.org/2011/09/using-event-tracing-for-windows-to.html.

alvarotrigo · 2015-11-19T14:34:21Z

Have you tried running iisnode sample hello world on that machine?

I did, I run all the examples included the Express one. But I still having the same problem.

I found out that changing app.get('/', or app.use('/', in my main file seems to make it work and print "respond with a resource aa".

I just don't understand why, and why it works perfectly with the get when using node server, and not when using iisnode.

alvarotrigo · 2015-11-19T16:56:10Z

@tjanczuk When using get instead of use the server responds with a The webpage cannot be found message. So I believe the ETW log is not very useful in that case. In any case, here you have the log of it. I was not able to get anything relevant from it.

alvarotrigo · 2015-11-19T17:20:41Z

I even tried with a simpler application extracted from the express documents (hello world), same problem. It works on node, it doesn't in iisnode.
Only works if I made the change I mentioned previously.

Now I'm getting the error: Cannot GET /node/parislight/myapp/ I'm using the same configuration (web.config) than in the express example provided with iisnode. Also, I changed the location of it and moved it from bin/www to hello.js as in the example.

alvarotrigo · 2015-11-19T17:34:45Z

Maybe related with this topic:#160 ?
I tried the boilerplate you recommend changing server.js for hello.js but still not having any effect.

dehildum · 2017-03-29T23:16:56Z

I am running into the same error. In my case, the error is intermittent, and seems to occur only when the request needs a significant amount of processing. I suspect that IIS is timing out, as the node function completes successfully AFTER IIS has returned a 500 error.

Is there a timeout setting that can be adjusted? I might need as long as five or ten minutes to deal with the request in some cases.

webuniverseio · 2017-06-21T01:43:02Z

From multiple forums you can increase timeout using:

    <system.web>
        <httpRuntime executionTimeout="3600" />
    </system.web>

But it didn't work, so the problem must be in something else :(

j-windsor · 2017-11-20T20:47:18Z

Any updates on this issue? From what I can tell, it is happening when there are concurrent requests being made and node.js closes the pipe for some reason, causing iisnode to immediately send an HTTP500.

irakli2692 · 2018-02-07T14:45:52Z

@johnwp did you fix this issue?
I have the same problem on iisnode 0.2.21 and Node.js v6.10.2

this error occures when I download file that takes more than 2 minute.

I have tried increasing timeout in iis advanced settings but didn't work.

Edwardfelix08 · 2020-03-03T10:29:03Z

Hi, any updates on this issue.

sheldonanto · 2020-04-28T07:08:12Z

I have the same error (HTTP Error 500.1013 - Internal Server Error) while sending more than 240 MB files in the Request body. we have used IIS node in Azure App service

pmansoorktr · 2021-01-19T21:25:04Z

I have the same issue , it has also been found that a detailed page is working , but not the absolute domain
example the domain first page
consoto.com is not working
but consoto.com/index.html -- working
but consoto.com/whatismessed -- working (this will show page not available) but the website will load

cjarv · 2021-10-20T22:45:48Z

Why is this closed when the issue still persists? I am running into the same issue.

bsor-dev · 2021-11-22T04:46:29Z

+1

sebastianNufi · 2022-03-15T15:17:08Z

Same issue here. In my case it's happening in a just HelloWorld simple app I'm trying to run

bsor-dev · 2022-03-15T15:35:35Z

I found out this error is not related to iis but in my nodejs. There is a default timeout in nodejs that you need to configure.

ZainAwan98 · 2024-11-01T05:45:15Z

i have encountered the same issue has anyone resolved this ?

johnwp changed the title ~~500.1013 Error~~ HTTP Error 500.1013 Dec 11, 2014

johnwp closed this as completed Nov 23, 2015

PatrickKing mentioned this issue Jan 17, 2017

IIS-Node hangs if a request is made from the server to itself under load #557

Open

bengreenier mentioned this issue Jul 28, 2017

long running signaling sessions timeout 3DStreamingToolkit/3DStreamingToolkit#45

Closed

Sebs030 mentioned this issue Jan 7, 2019

hey, you. Post stacktrace examples naugtur/blocked-at#5

Open

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

HTTP Error 500.1013 #405

HTTP Error 500.1013 #405

johnwp commented Dec 11, 2014

JustinNeumann commented Apr 28, 2015

rramachand21-zz commented Apr 28, 2015

alvarotrigo commented Nov 18, 2015

alvarotrigo commented Nov 19, 2015

tjanczuk commented Nov 19, 2015

alvarotrigo commented Nov 19, 2015

alvarotrigo commented Nov 19, 2015

alvarotrigo commented Nov 19, 2015

alvarotrigo commented Nov 19, 2015

dehildum commented Mar 29, 2017

webuniverseio commented Jun 21, 2017

j-windsor commented Nov 20, 2017

irakli2692 commented Feb 7, 2018

Edwardfelix08 commented Mar 3, 2020

sheldonanto commented Apr 28, 2020 •

edited

Loading

pmansoorktr commented Jan 19, 2021

cjarv commented Oct 20, 2021

bsor-dev commented Nov 22, 2021

sebastianNufi commented Mar 15, 2022

bsor-dev commented Mar 15, 2022

ZainAwan98 commented Nov 1, 2024

HTTP Error 500.1013 #405

HTTP Error 500.1013 #405

Comments

johnwp commented Dec 11, 2014

HTTP Error

Web.config

app.js

Stdout

ETW Trace log

JustinNeumann commented Apr 28, 2015

rramachand21-zz commented Apr 28, 2015

alvarotrigo commented Nov 18, 2015

alvarotrigo commented Nov 19, 2015

tjanczuk commented Nov 19, 2015

alvarotrigo commented Nov 19, 2015

alvarotrigo commented Nov 19, 2015

alvarotrigo commented Nov 19, 2015

alvarotrigo commented Nov 19, 2015

dehildum commented Mar 29, 2017

webuniverseio commented Jun 21, 2017

j-windsor commented Nov 20, 2017

irakli2692 commented Feb 7, 2018

Edwardfelix08 commented Mar 3, 2020

sheldonanto commented Apr 28, 2020 • edited Loading

pmansoorktr commented Jan 19, 2021

cjarv commented Oct 20, 2021

bsor-dev commented Nov 22, 2021

sebastianNufi commented Mar 15, 2022

bsor-dev commented Mar 15, 2022

ZainAwan98 commented Nov 1, 2024

sheldonanto commented Apr 28, 2020 •

edited

Loading