Enabling Redis instrumentation with Asp.Net web forms project

[Mr-Horse]

We have an old legacy asp.net webforms application that we've migrated from in proc session to redis. Now I'm trying to get the redis instrumentation to record redis calls in application insights. I've followed this guide to migrate from the application insights to opentelemetry. I've also been using these as a reference as well:

opentelemetry
opentelemetry-aspnet

It's sending the requests and I can see them in application insights. I can't seem to get the redis calls to show up. I see the redis sessions in the cache metrics and in the console of the redis instance. I can also see the logging if I call the loggerFactory.CreateLogger method. Do I need to implement a processor or something to get the redis calls to show up in the telemetry? Thanks!

Web.config

<system.webServer>
  <modules>
      <add
          name="TelemetryHttpModule"
          type="OpenTelemetry.Instrumentation.AspNet.TelemetryHttpModule,
              OpenTelemetry.Instrumentation.AspNet.TelemetryHttpModule"
          preCondition="integratedMode,managedHandler" />
  </modules>
</system.webServer>

Global.asax.vb:

Private tracerProvider As TracerProvider
Private meterProvider As MeterProvider
Friend Shared loggerFactory As ILoggerFactory

Protected Sub Application_Start(ByVal sender As [Object], ByVal e As EventArgs)
    'ConfigureAppInsights()
    ConfigureTelemetry()
End Sub

Private Sub ConfigureTelemetry()
    Dim appInsightsConnectionString = ConfigurationManager.AppSettings("AppInsightsConnectionString")
    Dim redisConnectionString = "redacted.redis.cache.windows.net:6380,password=blahblahblah,ssl=True,abortConnect=False"

    Using connection = ConnectionMultiplexer.Connect(redisConnectionString)
        Me.tracerProvider = Sdk.CreateTracerProviderBuilder() _
            .AddAspNetInstrumentation() _
            .AddOtlpExporter() _
            .AddHttpClientInstrumentation() _
            .AddSqlClientInstrumentation() _
            .AddRedisInstrumentation(connection, Sub(options) options.FlushInterval = TimeSpan.FromSeconds(1)) _
            .AddAzureMonitorTraceExporter(Sub(options) options.ConnectionString = appInsightsConnectionString) _
            .Build()
    End Using

    Me.meterProvider = Sdk.CreateMeterProviderBuilder() _
        .AddAspNetInstrumentation() _
        .AddOtlpExporter() _
        .AddHttpClientInstrumentation() _
        .AddAzureMonitorMetricExporter(Sub(options) options.ConnectionString = appInsightsConnectionString) _
        .Build()

    loggerFactory = Microsoft.Extensions.Logging.LoggerFactory.Create(
        Sub(builder) builder.AddOpenTelemetry(
            Sub(logging) logging.AddAzureMonitorLogExporter(
                Sub(options) options.ConnectionString = appInsightsConnectionString)))


    'loggerFactory.CreateLogger("Blah").Log(LogLevel.Critical, )
End Sub

Protected Sub Application_End(ByVal sender As [Object], ByVal e As EventArgs)
    Me.tracerProvider?.Dispose()
    Me.meterProvider?.Dispose()
    loggerFactory?.Dispose()
End Sub

[Mr-Horse]

Ok so I passed a streamwriter to the connection multiplexer and output to a file. Looks like it's just straight up dying at some point. Here is the output:

21:04:39.6900: Connecting (sync) on .NET Framework 4.8.9290.0 (StackExchange.Redis: v2.8.24.3255)
21:04:39.7550: redacted.redis.cache.windows.net:6380,connectTimeout=10000,password=*****,ssl=True,abortConnect=False
21:04:39.7645: redacted.redis.cache.windows.net:6380/Interactive: Connecting...
21:04:39.7865: redacted.redis.cache.windows.net:6380: BeginConnectAsync
21:04:39.8064: 1 unique nodes specified (with tiebreaker)
21:04:39.8114: redacted.redis.cache.windows.net:6380: OnConnectedAsync init (State=Connecting)
21:04:39.8124: Allowing 1 endpoint(s) 00:00:10 to respond...
21:04:39.8154: Awaiting 1 available task completion(s) for 10000ms, IOCP: (Busy=0,Free=1000,Min=22,Max=1000), WORKER: (Busy=2,Free=32765,Min=22,Max=32767)
21:04:39.8611: Configuring TLS
21:04:39.9509: TLS connection established successfully using protocol: Tls13
21:04:39.9529: redacted.redis.cache.windows.net:6380/Interactive: Connected 
21:04:39.9684: redacted.redis.cache.windows.net:6380: Server handshake
21:04:40.1071: redacted.redis.cache.windows.net:6380: Authenticating (password)
21:04:40.1371: redacted.redis.cache.windows.net:6380: Setting client name: REDACTEDPCNAME(SE.Redis-v2.8.24.3255)
21:04:40.1391: redacted.redis.cache.windows.net:6380: Setting client lib/ver
21:04:40.1550: redacted.redis.cache.windows.net:6380: Auto-configuring...
21:04:40.1605: redacted.redis.cache.windows.net:6380: Requesting tie-break (Key="__Booksleeve_TieBreak")...
21:04:40.1615: redacted.redis.cache.windows.net:6380/Interactive: Writing: GET __Booksleeve_TieBreak
21:04:40.1625: redacted.redis.cache.windows.net:6380: Sending critical tracer (handshake): ECHO
21:04:40.1625: redacted.redis.cache.windows.net:6380/Interactive: Writing: ECHO
21:04:40.1625: redacted.redis.cache.windows.net:6380: Flushing outbound buffer
21:04:40.1625: redacted.redis.cache.windows.net:6380: OnEstablishingAsync complete
21:04:40.1625: redacted.redis.cache.windows.net:6380: Starting read
21:04:40.2126: redacted.redis.cache.windows.net:6380: Auto-configured (CLIENT) connection-id: 2427674
21:04:40.2206: redacted.redis.cache.windows.net:6380: Auto-configured (INFO) role: primary
21:04:40.2216: redacted.redis.cache.windows.net:6380: Auto-configured (INFO) version: 6.0.14
21:04:40.2236: redacted.redis.cache.windows.net:6380: Auto-configured (INFO) server-type: Standalone
21:04:40.2236: Response from redacted.redis.cache.windows.net:6380/Interactive / GET __Booksleeve_TieBreak: (null)
21:04:40.2236: Response from redacted.redis.cache.windows.net:6380/Interactive / ECHO: BulkString: 16 bytes
21:04:40.2276: All 1 available tasks completed cleanly, IOCP: (Busy=2,Free=998,Min=22,Max=1000), WORKER: (Busy=2,Free=32765,Min=22,Max=32767)
21:04:40.2276: Endpoint summary:
21:04:40.2276:   redacted.redis.cache.windows.net:6380: Endpoint is (Interactive: ConnectedEstablished, Subscription: ConnectedEstablished)
21:04:40.2276: Task summary:
21:04:40.2276:   redacted.redis.cache.windows.net:6380: Returned with success as Standalone primary (Source: From command: ECHO)
21:04:40.2276: redacted.redis.cache.windows.net:6380: OnConnectedAsync completed (From command: ECHO)
21:04:40.2326: Election summary:
21:04:40.2326:   Election: redacted.redis.cache.windows.net:6380 had no tiebreaker set
21:04:40.2326:   Election: Single primary detected: redacted.redis.cache.windows.net:6380
21:04:40.2326: redacted.redis.cache.windows.net:6380: Clearing as RedundantPrimary
21:04:40.2336: Endpoint Summary:
21:04:40.2346:   redacted.redis.cache.windows.net:6380: Standalone v6.0.14, primary; keep-alive: 00:01:00; int: ConnectedEstablished; sub: ConnectedEstablished, 1 active
21:04:40.2356:   redacted.redis.cache.windows.net:6380: int ops=14, qu=0, qs=1, qc=0, wr=0, socks=1; sub ops=7, qu=0, qs=0, qc=0, wr=0, subs=1, socks=1
21:04:40.2366:   redacted.redis.cache.windows.net:6380: Circular op-count snapshot; int: 0+14=14 (1.40 ops/s; spans 10s); sub: 0+7=7 (0.70 ops/s; spans 10s)
21:04:40.2366: Syn

[Mr-Horse]

[Kielek]

I am not a vbexpert, but if I am reading following code correctly, you are creating connection, then registering it and disposing. I suppose that it will be no longer working.

    Using connection = ConnectionMultiplexer.Connect(redisConnectionString)
        Me.tracerProvider = Sdk.CreateTracerProviderBuilder() _
            .AddAspNetInstrumentation() _
            .AddOtlpExporter() _
            .AddHttpClientInstrumentation() _
            .AddSqlClientInstrumentation() _
            .AddRedisInstrumentation(connection, Sub(options) options.FlushInterval = TimeSpan.FromSeconds(1)) _
            .AddAzureMonitorTraceExporter(Sub(options) options.ConnectionString = appInsightsConnectionString) _
            .Build()
    End Using

BTW moving this discussion to contrib repository.

[Mr-Horse]

I made the connection a private variable in the Global.asax and removed the using. Same output in the log file for the connection multiplexer.

Public Class Global_asax
    Inherits HttpApplication
    
    Private tracerProvider As TracerProvider
    Private meterProvider As MeterProvider
    Friend Shared loggerFactory As ILoggerFactory
    Private connection As ConnectionMultiplexer
    
    
Private Sub ConfigureTelemetry()
    Dim appInsightsConnectionString = ConfigurationManager.AppSettings("AppInsightsConnectionString")
    Dim redisConnectionString = "redacted.redis.cache.windows.net:6380,password=redacted,ssl=True,abortConnect=False,ConnectTimeout=10000"

    Dim outputFile As New StreamWriter(Server.MapPath("~/log.txt"))

    Me.connection = ConnectionMultiplexer.Connect(redisConnectionString, outputFile)

    Me.tracerProvider = Sdk.CreateTracerProviderBuilder() _
        .AddAspNetInstrumentation() _
        .AddHttpClientInstrumentation() _
        .AddSqlClientInstrumentation() _
        .AddRedisInstrumentation(Me.connection, Sub(options) options.FlushInterval = TimeSpan.FromSeconds(1)) _
        .AddAzureMonitorTraceExporter(Sub(options) options.ConnectionString = appInsightsConnectionString) _
        .Build()

End Sub