telegraf-middleware-console-time

Debug your Telegram middlewares

When testing your bot it's nice to know what's coming in and how long it did take to process this request.

This library is not meant for usage in production. It's meant for debugging purposes.

In production the individual request wouldn't be as interesting as the general kind of request. For example not taking a look onto a specific '/start' command but onto all /start messages. This is kind of the opposite of what this library tries to achieve: Helping with understanding individual requests.

Install

npm install telegraf-middleware-console-time

Usage

Test your implementation

import {generateUpdateMiddleware} from 'telegraf-middleware-console-time';

const bot = new Bot(…);

// Other middlewares unrelated to your code
bot.use(…);

// Check your implementation for each telegram update (only when not in production)
if (process.env['NODE_ENV'] !== 'production') {
    bot.use(generateUpdateMiddleware());
}

// Your implementation
bot.command('start', …);

This will output something like this:

2020-03-31T14:32:36.974Z 490af message Edgar 6 /start: 926.247ms
2020-03-31T14:32:57.750Z 490ag message Edgar 6 /start: 914.764ms
2020-03-31T14:33:01.188Z 490ah message Edgar 5 /stop: 302.666ms
2020-03-31T14:46:11.385Z 490ai message Edgar 6 /start: 892.452ms

The 490af is the update_id.

The number before the commands is the total length of the content. This is helpful when considering max length for stuff like callback data.

The content itself is shortened in order to prevent log spamming.

Test a middleware

When you create your own middleware or assume slow timings of another middleware you can use these middlewares to create a timing profile

import {generateBeforeMiddleware, generateAfterMiddleware} from 'telegraf-middleware-console-time';

const bot = new Bot(…);

// Use BeforeMiddleware before loading the to be tested middleware
bot.use(generateBeforeMiddleware('foo'))

// Middleware to be tested
bot.use(…)

// Use AfterMiddleware after loading the to be tested middleware (with the same label)
bot.use(generateAfterMiddleware('foo'))

// Other middlewares / implementations (they will take the 'inner' amount of time when used)
bot.use(…)
bot.on(…, …)

This will output something like this:

490ai foo before: 304.185ms
490ai foo inner: 83.122ms
490ai foo after: 501.028ms
490ai foo total: 891.849ms

This indicates the checked middleware alone took 800ms and isnt as performant as maybe needed.