nixys / nxs-chat-srv

Nixys chat bot that integrates Telegram and Redmine
GNU General Public License v3.0
35 stars 10 forks source link

Вопрос #34

Closed Rammzai closed 2 years ago

Rammzai commented 2 years ago

[04.11.2021 13:01:50.545416] (167) ERROR: [cache-worker]: db ids cleanup rdmn userid by tlgrm username error, MySQL update error: Table 'redmine_default.ids' doesn't exist (tlgrm username: fdkian, rdmn userid: 8) [04.11.2021 13:01:50.545454] (167) ERROR: [cache-worker]: can't save rdmn ids into DB: rdmn userid cleanup error (tlgrm username: fdkian, rdmn userid: 8) [04.11.2021 13:01:50.545525] (167) WARNING: [cache-worker]: users cache update error: can't update 'ids' data на данный момент имеем такие ошибки при создании нового пользователя и присвоение ему телеграмм тега. а так же не понятен последний пункт в мануале . Telegram bot initializaton

Before the nxs-chat-srv has been starts you need to set Webhook in Telegram and populate the created MySQL DB.

To set Webhook run nxs-chat-srv with one of the follows options: In case the trusted SSL certificate is used:

nxs-chat-srv -i set_webhook

In case the self-signed certificate is used (read this manual before: https://core.telegram.org/bots/self-signed):

nxs-chat-srv -i set_webhook_self_signed_certificate

To populate the created MySQL DB run nxs-chat-srv with follow option:

nxs-chat-srv -i create_tables

Rammzai commented 2 years ago

теперь вроде все запустилось но Бот не работает mysql_1 | 2021-11-09 10:23:23+00:00 [Note] [Entrypoint]: Entrypoint script for MySQL Server 5.7.36-1debian10 started. mysql_1 | 2021-11-09 10:23:23+00:00 [Note] [Entrypoint]: Switching to dedicated user 'mysql' mysql_1 | 2021-11-09 10:23:23+00:00 [Note] [Entrypoint]: Entrypoint script for MySQL Server 5.7.36-1debian10 started. mysql_1 | 2021-11-09T10:23:23.611298Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details). mysql_1 | 2021-11-09T10:23:23.615563Z 0 [Note] mysqld (mysqld 5.7.36) starting as process 1 ... mysql_1 | 2021-11-09T10:23:23.620155Z 0 [Note] InnoDB: PUNCH HOLE support available mysql_1 | 2021-11-09T10:23:23.620183Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins mysql_1 | 2021-11-09T10:23:23.620187Z 0 [Note] InnoDB: Uses event mutexes mysql_1 | 2021-11-09T10:23:23.620189Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier mysql_1 | 2021-11-09T10:23:23.620192Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.11 mysql_1 | 2021-11-09T10:23:23.620198Z 0 [Note] InnoDB: Using Linux native AIO mysql_1 | 2021-11-09T10:23:23.620708Z 0 [Note] InnoDB: Number of pools: 1 redis_1 | 1:C 09 Nov 2021 10:23:24.225 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo mysql_1 | 2021-11-09T10:23:23.620964Z 0 [Note] InnoDB: Using CPU crc32 instructions mysql_1 | 2021-11-09T10:23:23.624854Z 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M redis_1 | 1:C 09 Nov 2021 10:23:24.225 # Redis version=6.2.6, bits=64, commit=00000000, modified=0, pid=1, just started mysql_1 | 2021-11-09T10:23:23.641315Z 0 [Note] InnoDB: Completed initialization of buffer pool redis_1 | 1:C 09 Nov 2021 10:23:24.225 # Configuration loaded mysql_1 | 2021-11-09T10:23:23.647041Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority(). mysql_1 | 2021-11-09T10:23:23.662769Z 0 [Note] InnoDB: Highest supported file format is Barracuda. mysql_1 | 2021-11-09T10:23:23.676378Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables mysql_1 | 2021-11-09T10:23:23.676448Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ... mysql_1 | 2021-11-09T10:23:23.718858Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB. redis_1 | 1:M 09 Nov 2021 10:23:24.225 monotonic clock: POSIX clock_gettime mysql_1 | 2021-11-09T10:23:23.720048Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active. mysql_1 | 2021-11-09T10:23:23.720069Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active. mysql_1 | 2021-11-09T10:23:23.720497Z 0 [Note] InnoDB: Waiting for purge to start redis_1 | 1:M 09 Nov 2021 10:23:24.226 Running mode=standalone, port=6379. mysql_1 | 2021-11-09T10:23:23.770737Z 0 [Note] InnoDB: 5.7.36 started; log sequence number 12669730 mysql_1 | 2021-11-09T10:23:23.771011Z 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool mysql_1 | 2021-11-09T10:23:23.771250Z 0 [Note] Plugin 'FEDERATED' is disabled. mysql_1 | 2021-11-09T10:23:23.775766Z 0 [Note] InnoDB: Buffer pool(s) load completed at 211109 10:23:23 mysql_1 | 2021-11-09T10:23:23.784805Z 0 [Note] Found ca.pem, server-cert.pem and server-key.pem in data directory. Trying to enable SSL support using them. mysql_1 | 2021-11-09T10:23:23.784840Z 0 [Note] Skipping generation of SSL certificates as certificate files are present in data directory. mysql_1 | 2021-11-09T10:23:23.784844Z 0 [Warning] A deprecated TLS version TLSv1 is enabled. Please use TLSv1.2 or higher. mysql_1 | 2021-11-09T10:23:23.784849Z 0 [Warning] A deprecated TLS version TLSv1.1 is enabled. Please use TLSv1.2 or higher. mysql_1 | 2021-11-09T10:23:23.785482Z 0 [Warning] CA certificate ca.pem is self signed. mysql_1 | 2021-11-09T10:23:23.785528Z 0 [Note] Skipping generation of RSA key pair as key files are present in data directory. mysql_1 | 2021-11-09T10:23:23.786124Z 0 [Note] Server hostname (bind-address): ''; port: 3306 mysql_1 | 2021-11-09T10:23:23.786194Z 0 [Note] IPv6 is available. redis_1 | 1:M 09 Nov 2021 10:23:24.226 # WARNING: The TCP backlog setting of 511 cannot be enforced because /proc/sys/net/core/somaxconn is set to the lower value of 128. mysql_1 | 2021-11-09T10:23:23.786215Z 0 [Note] - '::' resolves to '::'; redis_1 | 1:M 09 Nov 2021 10:23:24.226 # Server initialized redis_1 | 1:M 09 Nov 2021 10:23:24.226 # WARNING overcommit_memory is set to 0! Background save may fail under low memory condition. To fix this issue add 'vm.overcommit_memory = 1' to /etc/sysctl.conf and then reboot or run the command 'sysctl vm.overcommit_memory=1' for this to take effect. mysql_1 | 2021-11-09T10:23:23.786245Z 0 [Note] Server socket created on IP: '::'. redis_1 | 1:M 09 Nov 2021 10:23:24.226 Loading RDB produced by version 6.2.6 redis_1 | 1:M 09 Nov 2021 10:23:24.226 RDB age 9 seconds mysql_1 | 2021-11-09T10:23:23.787587Z 0 [Warning] Insecure configuration for --pid-file: Location '/var/run/mysqld' in the path is accessible to all OS users. Consider choosing a different directory. mysql_1 | 2021-11-09T10:23:23.806723Z 0 [Note] Event Scheduler: Loaded 0 events redis_1 | 1:M 09 Nov 2021 10:23:24.226 RDB memory usage when created 0.77 Mb mysql_1 | 2021-11-09T10:23:23.807031Z 0 [Note] mysqld: ready for connections. mysql_1 | Version: '5.7.36' socket: '/var/run/mysqld/mysqld.sock' port: 3306 MySQL Community Server (GPL) redis_1 | 1:M 09 Nov 2021 10:23:24.227 # Done loading RDB, keys loaded: 1, keys expired: 0. redis_1 | 1:M 09 Nov 2021 10:23:24.227 DB loaded from disk: 0.000 seconds redis_1 | 1:M 09 Nov 2021 10:23:24.227 Ready to accept connections nxs-chat-srv_1 | [09.11.2021 10:23:25.970701] (163) DEBUG: [/nxs-chat-srv]: tlgrm set webhook: success (telegram url: https://api.telegram.org/bot2099519733:AAHJMQuK1Yd3cJN2r9dhDhF9hk-aiNexjCY/setWebhook, url: https://api.telegram.org/bot2099519733:AAHJMQuK1Yd3cJN2r9dhDhF9hk-aiNexjCY/setWebhook?url=https://redmine.ogi.kz:8443/2099519733:AAHJMQuK1Yd3cJN2r9dhDhF9hk-aiNexjCY/tlgrm?token=id35kn7resb536bfc8zywdgaugk1ur69cysowr2kpiq5ngw41q, certificate: false) nxs-chat-srv_1 | [09.11.2021 10:23:25.972938] (163) INFO: [/nxs-chat-srv]: set webhook to tlgrm: success (result: true, error code: 200, description: "Webhook was set", using silf-signed certificate: false) nxs-chat-srv_1 | telegram set webhook success: Webhook was set nxs-chat-srv_1 | [09.11.2021 10:23:25.982622] (1) DEBUG: [/nxs-chat-srv]: pid file successfully created (pid file: /tmp/nxs-chat-srv.pid) nxs-chat-srv_1 | [09.11.2021 10:23:25.983121] (1) DEBUG: [/nxs-chat-srv]: started queue worker process (index: 0, pid: 165) nxs-chat-srv_1 | [09.11.2021 10:23:25.983473] (1) DEBUG: [/nxs-chat-srv]: started queue worker process (index: 1, pid: 166) nxs-chat-srv_1 | [09.11.2021 10:23:25.983868] (1) DEBUG: [/nxs-chat-srv]: started queue worker process (index: 2, pid: 167) nxs-chat-srv_1 | [09.11.2021 10:23:25.984323] (1) DEBUG: [/nxs-chat-srv]: started queue worker process (index: 3, pid: 168) nxs-chat-srv_1 | [09.11.2021 10:23:25.985074] (1) DEBUG: [/nxs-chat-srv]: started queue worker process (index: 4, pid: 169) nxs-chat-srv_1 | [09.11.2021 10:23:25.985484] (1) DEBUG: [/nxs-chat-srv]: started cache worker process (pid: 170) nxs-chat-srv_1 | [09.11.2021 10:23:25.985898] (1) DEBUG: [/nxs-chat-srv]: started rest api process (pid: 171) nxs-chat-srv_1 | [09.11.2021 10:23:28.907413] (166) DEBUG: [queue-worker]: rdmn current user get: success nxs-chat-srv_1 | [09.11.2021 10:23:28.919049] (165) DEBUG: [queue-worker]: rdmn current user get: success nxs-chat-srv_1 | [09.11.2021 10:23:28.931762] (169) DEBUG: [queue-worker]: rdmn current user get: success nxs-chat-srv_1 | [09.11.2021 10:23:28.954097] (170) DEBUG: [cache-worker]: rdmn projects get: success (offset: 0, limit: 100) nxs-chat-srv_1 | [09.11.2021 10:23:28.954380] (170) DEBUG: [cache-worker]: db cache put: success (cache type: projects) nxs-chat-srv_1 | [09.11.2021 10:23:28.954433] (170) DEBUG: [cache-worker]: projects cache successfully updated nxs-chat-srv_1 | [09.11.2021 10:23:29.012428] (170) DEBUG: [cache-worker]: rdmn users get: success (user id: 0, offset: 0, limit: 100) nxs-chat-srv_1 | [09.11.2021 10:23:29.012841] (170) DEBUG: [cache-worker]: db cache put: success (cache type: users) nxs-chat-srv_1 | [09.11.2021 10:23:29.012893] (170) DEBUG: [cache-worker]: users cache successfully updated nxs-chat-srv_1 | [09.11.2021 10:23:29.029461] (170) DEBUG: [cache-worker]: db presale get: success nxs-chat-srv_1 | [09.11.2021 10:23:29.029559] (170) DEBUG: [cache-worker]: db waitings get: success nxs-chat-srv_1 | [09.11.2021 10:23:29.029567] (170) DEBUG: [cache-worker]: users cache pull waitings: success nxs-chat-srv_1 | [09.11.2021 10:23:29.035510] (167) DEBUG: [queue-worker]: rdmn current user get: success nxs-chat-srv_1 | [09.11.2021 10:23:29.072452] (170) DEBUG: [cache-worker]: rdmn enums issue priorities get: success nxs-chat-srv_1 | [09.11.2021 10:23:29.072717] (170) DEBUG: [cache-worker]: db cache put: success (cache type: issue_priorities) nxs-chat-srv_1 | [09.11.2021 10:23:29.072807] (170) DEBUG: [cache-worker]: issue priorities cache successfully updated nxs-chat-srv_1 | [09.11.2021 10:23:29.140196] (168) DEBUG: [queue-worker]: rdmn current user get: success redis_1 | 1:M 09 Nov 2021 10:23:55.093 1 changes in 30 seconds. Saving... redis_1 | 1:M 09 Nov 2021 10:23:55.094 Background saving started by pid 19 redis_1 | 19:C 09 Nov 2021 10:23:55.101 DB saved on disk redis_1 | 19:C 09 Nov 2021 10:23:55.102 RDB: 0 MB of memory used by copy-on-write redis_1 | 1:M 09 Nov 2021 10:23:55.195 Background saving terminated with success nxs-chat-srv_1 | [09.11.2021 10:24:30.214805] (170) DEBUG: [cache-worker]: rdmn users get: success (user id: 0, offset: 0, limit: 100) nxs-chat-srv_1 | [09.11.2021 10:24:30.215702] (170) DEBUG: [cache-worker]: db cache put: success (cache type: users) nxs-chat-srv_1 | [09.11.2021 10:24:30.215892] (170) DEBUG: [cache-worker]: users cache successfully updated nxs-chat-srv_1 | [09.11.2021 10:24:30.221093] (170) DEBUG: [cache-worker]: db presale get: success nxs-chat-srv_1 | [09.11.2021 10:24:30.221168] (170) DEBUG: [cache-worker]: db waitings get: success nxs-chat-srv_1 | [09.11.2021 10:24:30.221177] (170) DEBUG: [cache-worker]: users cache pull waitings: success redis_1 | 1:M 09 Nov 2021 10:24:30.305 1 changes in 30 seconds. Saving... redis_1 | 1:M 09 Nov 2021 10:24:30.305 Background saving started by pid 20 redis_1 | 20:C 09 Nov 2021 10:24:30.310 DB saved on disk redis_1 | 20:C 09 Nov 2021 10:24:30.310 RDB: 0 MB of memory used by copy-on-write redis_1 | 1:M 09 Nov 2021 10:24:30.406 Background saving terminated with success nxs-chat-srv_1 | [09.11.2021 10:25:31.399545] (170) DEBUG: [cache-worker]: rdmn users get: success (user id: 0, offset: 0, limit: 100) nxs-chat-srv_1 | [09.11.2021 10:25:31.401938] (170) DEBUG: [cache-worker]: db cache put: success (cache type: users) nxs-chat-srv_1 | [09.11.2021 10:25:31.401984] (170) DEBUG: [cache-worker]: users cache successfully updated nxs-chat-srv_1 | [09.11.2021 10:25:31.407059] (170) DEBUG: [cache-worker]: db presale get: success nxs-chat-srv_1 | [09.11.2021 10:25:31.407479] (170) DEBUG: [cache-worker]: db waitings get: success nxs-chat-srv_1 | [09.11.2021 10:25:31.407501] (170) DEBUG: [cache-worker]: users cache pull waitings: success redis_1 | 1:M 09 Nov 2021 10:25:31.459 1 changes in 30 seconds. Saving... redis_1 | 1:M 09 Nov 2021 10:25:31.460 Background saving started by pid 21 redis_1 | 21:C 09 Nov 2021 10:25:31.465 DB saved on disk redis_1 | 21:C 09 Nov 2021 10:25:31.466 RDB: 0 MB of memory used by copy-on-write redis_1 | 1:M 09 Nov 2021 10:25:31.561 Background saving terminated with success nxs-chat-srv_1 | [09.11.2021 10:26:32.549198] (170) DEBUG: [cache-worker]: rdmn users get: success (user id: 0, offset: 0, limit: 100) nxs-chat-srv_1 | [09.11.2021 10:26:32.551236] (170) DEBUG: [cache-worker]: db cache put: success (cache type: users) nxs-chat-srv_1 | [09.11.2021 10:26:32.551312] (170) DEBUG: [cache-worker]: users cache successfully updated nxs-chat-srv_1 | [09.11.2021 10:26:32.556114] (170) DEBUG: [cache-worker]: db presale get: success nxs-chat-srv_1 | [09.11.2021 10:26:32.556220] (170) DEBUG: [cache-worker]: db waitings get: success nxs-chat-srv_1 | [09.11.2021 10:26:32.556229] (170) DEBUG: [cache-worker]: users cache pull waitings: success redis_1 | 1:M 09 Nov 2021 10:26:32.596 1 changes in 30 seconds. Saving... redis_1 | 1:M 09 Nov 2021 10:26:32.597 Background saving started by pid 22 redis_1 | 22:C 09 Nov 2021 10:26:32.601 DB saved on disk redis_1 | 22:C 09 Nov 2021 10:26:32.602 RDB: 0 MB of memory used by copy-on-write redis_1 | 1:M 09 Nov 2021 10:26:32.698 Background saving terminated with success nxs-chat-srv_1 | [09.11.2021 10:27:33.691131] (170) DEBUG: [cache-worker]: rdmn users get: success (user id: 0, offset: 0, limit: 100) nxs-chat-srv_1 | [09.11.2021 10:27:33.691748] (170) DEBUG: [cache-worker]: db cache put: success (cache type: users) nxs-chat-srv_1 | [09.11.2021 10:27:33.691816] (170) DEBUG: [cache-worker]: users cache successfully updated nxs-chat-srv_1 | [09.11.2021 10:27:33.698057] (170) DEBUG: [cache-worker]: db presale get: success nxs-chat-srv_1 | [09.11.2021 10:27:33.698292] (170) DEBUG: [cache-worker]: db waitings get: success nxs-chat-srv_1 | [09.11.2021 10:27:33.698324] (170) DEBUG: [cache-worker]: users cache pull waitings: success redis_1 | 1:M 09 Nov 2021 10:27:33.732 1 changes in 30 seconds. Saving... redis_1 | 1:M 09 Nov 2021 10:27:33.733 Background saving started by pid 23 redis_1 | 23:C 09 Nov 2021 10:27:33.738 DB saved on disk redis_1 | 23:C 09 Nov 2021 10:27:33.738 RDB: 0 MB of memory used by copy-on-write redis_1 | 1:M 09 Nov 2021 10:27:33.834 Background saving terminated with success nxs-chat-srv_1 | [09.11.2021 10:28:29.805919] (170) DEBUG: [cache-worker]: rdmn projects get: success (offset: 0, limit: 100) nxs-chat-srv_1 | [09.11.2021 10:28:29.806323] (170) DEBUG: [cache-worker]: db cache put: success (cache type: projects) nxs-chat-srv_1 | [09.11.2021 10:28:29.806365] (170) DEBUG: [cache-worker]: projects cache successfully updated redis_1 | 1:M 09 Nov 2021 10:28:29.822 1 changes in 30 seconds. Saving... redis_1 | 1:M 09 Nov 2021 10:28:29.823 Background saving started by pid 24 redis_1 | 24:C 09 Nov 2021 10:28:29.828 DB saved on disk redis_1 | 24:C 09 Nov 2021 10:28:29.829 RDB: 0 MB of memory used by copy-on-write redis_1 | 1:M 09 Nov 2021 10:28:29.924 Background saving terminated with success nxs-chat-srv_1 | [09.11.2021 10:28:30.834182] (170) DEBUG: [cache-worker]: rdmn enums issue priorities get: success nxs-chat-srv_1 | [09.11.2021 10:28:30.834458] (170) DEBUG: [cache-worker]: db cache put: success (cache type: issue_priorities) nxs-chat-srv_1 | [09.11.2021 10:28:30.834879] (170) DEBUG: [cache-worker]: issue priorities cache successfully updated nxs-chat-srv_1 | [09.11.2021 10:28:34.889138] (170) DEBUG: [cache-worker]: rdmn users get: success (user id: 0, offset: 0, limit: 100) nxs-chat-srv_1 | [09.11.2021 10:28:34.890011] (170) DEBUG: [cache-worker]: db cache put: success (cache type: users) nxs-chat-srv_1 | [09.11.2021 10:28:34.890057] (170) DEBUG: [cache-worker]: users cache successfully updated nxs-chat-srv_1 | [09.11.2021 10:28:34.895176] (170) DEBUG: [cache-worker]: db presale get: success nxs-chat-srv_1 | [09.11.2021 10:28:34.895249] (170) DEBUG: [cache-worker]: db waitings get: success nxs-chat-srv_1 | [09.11.2021 10:28:34.895255] (170) DEBUG: [cache-worker]: users cache pull waitings: success redis_1 | 1:M 09 Nov 2021 10:29:00.080 1 changes in 30 seconds. Saving... redis_1 | 1:M 09 Nov 2021 10:29:00.081 Background saving started by pid 25 redis_1 | 25:C 09 Nov 2021 10:29:00.084 DB saved on disk redis_1 | 25:C 09 Nov 2021 10:29:00.084 RDB: 0 MB of memory used by copy-on-write redis_1 | 1:M 09 Nov 2021 10:29:00.182 Background saving terminated with success nxs-chat-srv_1 | [09.11.2021 10:29:36.050883] (170) DEBUG: [cache-worker]: rdmn users get: success (user id: 0, offset: 0, limit: 100) nxs-chat-srv_1 | [09.11.2021 10:29:36.052889] (170) DEBUG: [cache-worker]: db cache put: success (cache type: users) nxs-chat-srv_1 | [09.11.2021 10:29:36.053391] (170) DEBUG: [cache-worker]: users cache successfully updated nxs-chat-srv_1 | [09.11.2021 10:29:36.058005] (170) DEBUG: [cache-worker]: db presale get: success nxs-chat-srv_1 | [09.11.2021 10:29:36.058132] (170) DEBUG: [cache-worker]: db waitings get: success nxs-chat-srv_1 | [09.11.2021 10:29:36.058149] (170) DEBUG: [cache-worker]: users cache pull waitings: success redis_1 | 1:M 09 Nov 2021 10:29:36.090 1 changes in 30 seconds. Saving... redis_1 | 1:M 09 Nov 2021 10:29:36.092 Background saving started by pid 26 redis_1 | 26:C 09 Nov 2021 10:29:36.097 DB saved on disk redis_1 | 26:C 09 Nov 2021 10:29:36.098 RDB: 0 MB of memory used by copy-on-write redis_1 | 1:M 09 Nov 2021 10:29:36.192 Background saving terminated with success