INFO src.framework.common.api:api.py:97 {"timestamp": "2026-04-06T23:39:56.557190", "type": "request", "method": "POST", "url": "https://dm-fmcg-be-facade-fe.k8s-review.dailymail-tech.uz/api/v1/oauth/custom/token", "headers": {"x-aer-mock-hub": "true", "x-aer-mock-lp": "true", "x-aer-mock-ff": "true", "x-aer-mock-yandex-bond": "true", "x-aer-mock-egov-uz": "true", "x-aer-mock-tmg": "true", "x-aer-mesh": "dm-hub-service-package:master;dm-hub-service-package-external:master;dm-hub-service-sorting:master;dm-hub-service-asn:master;dm-hub-service-inbound:master;dm-hub-service-meta:master;dm-hub-service-configuration:master;dm-hub-service-packing:master;dm-hub-service-dispatch:master;dm-hub-service-label:master;dm-hub-service-storage:master;dm-hub-service-cargo:master;dm-hub-service-country:master;dm-hub-service-customs:master;dm-hub-service-document:master;dm-hub-service-email:master;dm-hub-service-file-storage:master;dm-hub-service-inspection:master;dm-hub-service-osl:master;dm-hub-service-partners:master;dm-hub-service-report:master;dm-hub-service-settings:master;dm-hub-service-short-link:master;dm-hub-service-templates:master;dm-hub-service-tracking:master;dm-hub-service-transliteration:master;dm-hub-service-processing:master;dm-hub-api-cargo-carrier:master;dm-hub-api-fe:master;dm-hub-api-ff:master;dm-hub-api-wms:master;dm-hub-api-aer:master;dm-hub-api-joom:master;dm-hub-api-gateway:master;dm-hub-api-partners:master;dm-hub-api-pochta-global:master;dm-hub-api-public:master;dm-hub-api-wildberries:master;dm-hub-api-internal:master;dm-hub-daemon-package-process:master;dm-hub-daemon-tracking:master;dm-hub-integration-ff:master;dm-hub-integration-aer:master;dm-hub-integration-cainiao:master;dm-hub-integration-cargo-carrier:master;dm-hub-integration-gtt:master;dm-hub-integration-kazpost:master;dm-hub-integration-kgpost:master;dm-hub-integration-partners:master;dm-hub-integration-post:master;dm-hub-integration-rupost:master;dm-hub-integration-telegram:master;dm-hub-integration-upu:master;dm-hub-integration-wms:master;dm-hub-workflow-asn:master;dm-hub-workflow-email:master;dm-hub-workflow-hub-external:master;dm-hub-workflow-hub-internal:master;dm-hub-workflow-integration:master;dm-hub-workflow-package:master;dm-hub-workflow-tracking:master;dm-hub-workflow-hub-internal:master;dm-hub-service-packing:master;dm-hub-service-cargo:master;dm-hub-workflow-hub-external:master"}, "json": {"login": "9000000001", "password": "1234", "grantType": "password"}, "timeout": 30} INFO src.framework.common.api:api.py:121 {"timestamp": "2026-04-06T23:39:56.591674", "type": "response", "method": "POST", "url": "https://dm-fmcg-be-facade-fe.k8s-review.dailymail-tech.uz/api/v1/oauth/custom/token", "status_code": 200, "trace_id": "23e458f3fd9c8032c337d5ad8fce6fb1", "headers": {}, "body": "{\"token\":\"0ae2e3d0-640e-443c-bc10-88a48455a831\",\"expired\":false,\"refreshToken\":\"411207b1-1c97-40f2-b1c2-418dac163f36\",\"expiresIn\":59874,\"expiration\":\"2026-04-07T16:17:51.590+00:00\"}", "elapsed_time": 0.03315, "reason": "OK"} INFO kafka.conn:conn.py:400 [IPv4 ('10.100.180.5', 9092)]>: connecting to redpanda.redpanda-operator.svc:9092 [('10.100.180.5', 9092) IPv4] INFO kafka.conn:conn.py:622 [IPv4 ('10.100.180.5', 9092)]>: Broker version identified as 2.6 INFO kafka.conn:conn.py:461 [IPv4 ('10.100.180.5', 9092)]>: Connection complete. INFO kafka.conn:conn.py:400 [IPv4 ('10.100.48.16', 9092)]>: connecting to redpanda-1.redpanda.redpanda-operator.svc.cluster.local.:9092 [('10.100.48.16', 9092) IPv4] INFO kafka.conn:conn.py:461 [IPv4 ('10.100.48.16', 9092)]>: Connection complete. INFO kafka.conn:conn.py:951 [IPv4 ('10.100.180.5', 9092)]>: Closing connection. INFO kafka.conn:conn.py:951 [IPv4 ('10.100.48.16', 9092)]>: Closing connection. INFO sqlalchemy.engine.Engine:base.py:2710 BEGIN (implicit; DBAPI should not BEGIN due to autocommit mode) INFO sqlalchemy.engine.Engine:base.py:1846 SELECT inbox.id, inbox.topic, inbox.payload, inbox.status, inbox.retry_count, inbox.max_retry_count, inbox.key, inbox.headers, inbox.created_at, inbox.next_retry_at, inbox.processing_started_at, inbox.error_message, inbox.exception_stack_trace, inbox.processing_node, inbox.processing_timeout_minutes FROM inbox WHERE inbox.topic = %(topic_1)s AND CAST(inbox.payload AS VARCHAR) LIKE %(param_1)s INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 45.51s ago] {'topic_1': , 'param_1': '%75518796593283328%'} INFO sqlalchemy.engine.Engine:base.py:1846 SELECT inbox.id, inbox.topic, inbox.payload, inbox.status, inbox.retry_count, inbox.max_retry_count, inbox.key, inbox.headers, inbox.created_at, inbox.next_retry_at, inbox.processing_started_at, inbox.error_message, inbox.exception_stack_trace, inbox.processing_node, inbox.processing_timeout_minutes FROM inbox WHERE inbox.topic = %(topic_1)s AND CAST(inbox.payload AS VARCHAR) LIKE %(param_1)s INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 50.51s ago] {'topic_1': , 'param_1': '%75518796593283328%'} INFO sqlalchemy.engine.Engine:base.py:1846 SELECT inbox.id, inbox.topic, inbox.payload, inbox.status, inbox.retry_count, inbox.max_retry_count, inbox.key, inbox.headers, inbox.created_at, inbox.next_retry_at, inbox.processing_started_at, inbox.error_message, inbox.exception_stack_trace, inbox.processing_node, inbox.processing_timeout_minutes FROM inbox WHERE inbox.topic = %(topic_1)s AND CAST(inbox.payload AS VARCHAR) LIKE %(param_1)s INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 55.51s ago] {'topic_1': , 'param_1': '%75518796593283328%'} INFO sqlalchemy.engine.Engine:base.py:1846 SELECT inbox.id, inbox.topic, inbox.payload, inbox.status, inbox.retry_count, inbox.max_retry_count, inbox.key, inbox.headers, inbox.created_at, inbox.next_retry_at, inbox.processing_started_at, inbox.error_message, inbox.exception_stack_trace, inbox.processing_node, inbox.processing_timeout_minutes FROM inbox WHERE inbox.topic = %(topic_1)s AND CAST(inbox.payload AS VARCHAR) LIKE %(param_1)s INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 60.52s ago] {'topic_1': , 'param_1': '%75518796593283328%'} INFO sqlalchemy.engine.Engine:base.py:1846 SELECT invoice.id, invoice.lk_invoice_id, invoice.original_invoice_number, invoice.need_mark, invoice.has_expiration_date, invoice.is_declared, invoice.invoice_status, invoice.date_create, invoice.vehicle_id, invoice.is_test, invoice.date_arrival, invoice.seller_id, invoice.warehouse_id, invoice.gtd_number, invoice.date_update, invoice.date_finished, invoice.partner_id FROM invoice WHERE invoice.original_invoice_number = %(original_invoice_number_1)s INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 45.5s ago] {'original_invoice_number_1': '75518796593283328'} INFO kafka.conn:conn.py:400 [IPv4 ('10.100.174.181', 9092)]>: connecting to redpanda.redpanda-operator.svc:9092 [('10.100.174.181', 9092) IPv4] INFO kafka.conn:conn.py:622 [IPv4 ('10.100.174.181', 9092)]>: Broker version identified as 2.6 INFO kafka.conn:conn.py:461 [IPv4 ('10.100.174.181', 9092)]>: Connection complete. INFO kafka.conn:conn.py:400 [IPv4 ('10.100.180.5', 9092)]>: connecting to redpanda-2.redpanda.redpanda-operator.svc.cluster.local.:9092 [('10.100.180.5', 9092) IPv4] INFO kafka.conn:conn.py:461 [IPv4 ('10.100.180.5', 9092)]>: Connection complete. INFO kafka.conn:conn.py:951 [IPv4 ('10.100.174.181', 9092)]>: Closing connection. INFO kafka.conn:conn.py:400 [IPv4 ('10.100.174.181', 9092)]>: connecting to redpanda-0.redpanda.redpanda-operator.svc.cluster.local.:9092 [('10.100.174.181', 9092) IPv4] INFO kafka.conn:conn.py:461 [IPv4 ('10.100.174.181', 9092)]>: Connection complete. INFO kafka.conn:conn.py:951 [IPv4 ('10.100.180.5', 9092)]>: Closing connection. INFO kafka.conn:conn.py:951 [IPv4 ('10.100.174.181', 9092)]>: Closing connection. INFO sqlalchemy.engine.Engine:base.py:1846 SELECT inbox.id, inbox.topic, inbox.payload, inbox.status, inbox.retry_count, inbox.max_retry_count, inbox.key, inbox.headers, inbox.created_at, inbox.next_retry_at, inbox.processing_started_at, inbox.error_message, inbox.exception_stack_trace, inbox.processing_node, inbox.processing_timeout_minutes FROM inbox WHERE inbox.topic = %(topic_1)s AND CAST(inbox.payload AS VARCHAR) LIKE %(param_1)s INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 60.64s ago] {'topic_1': , 'param_1': '%53890371560043%'} INFO sqlalchemy.engine.Engine:base.py:1846 SELECT inbox.id, inbox.topic, inbox.payload, inbox.status, inbox.retry_count, inbox.max_retry_count, inbox.key, inbox.headers, inbox.created_at, inbox.next_retry_at, inbox.processing_started_at, inbox.error_message, inbox.exception_stack_trace, inbox.processing_node, inbox.processing_timeout_minutes FROM inbox WHERE inbox.topic = %(topic_1)s AND CAST(inbox.payload AS VARCHAR) LIKE %(param_1)s INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 65.65s ago] {'topic_1': , 'param_1': '%53890371560043%'} INFO sqlalchemy.engine.Engine:base.py:1846 SELECT inbox.id, inbox.topic, inbox.payload, inbox.status, inbox.retry_count, inbox.max_retry_count, inbox.key, inbox.headers, inbox.created_at, inbox.next_retry_at, inbox.processing_started_at, inbox.error_message, inbox.exception_stack_trace, inbox.processing_node, inbox.processing_timeout_minutes FROM inbox WHERE inbox.topic = %(topic_1)s AND CAST(inbox.payload AS VARCHAR) LIKE %(param_1)s INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 70.66s ago] {'topic_1': , 'param_1': '%53890371560043%'} INFO sqlalchemy.engine.Engine:base.py:1846 SELECT inbox.id, inbox.topic, inbox.payload, inbox.status, inbox.retry_count, inbox.max_retry_count, inbox.key, inbox.headers, inbox.created_at, inbox.next_retry_at, inbox.processing_started_at, inbox.error_message, inbox.exception_stack_trace, inbox.processing_node, inbox.processing_timeout_minutes FROM inbox WHERE inbox.topic = %(topic_1)s AND CAST(inbox.payload AS VARCHAR) LIKE %(param_1)s INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 75.67s ago] {'topic_1': , 'param_1': '%53890371560043%'} INFO sqlalchemy.engine.Engine:base.py:1846 SELECT sku_lk.id, sku_lk.sku_id, sku_lk.name, sku_lk.has_mark, sku_lk.has_expiration_date, sku_lk.created_at, sku_lk.updated_at, sku_lk.external_sku_id, sku_lk.weight, sku_lk.height, sku_lk.length, sku_lk.width, sku_lk.image_url, sku_lk.seller_id, sku_lk.erp_id, barcode_info.id AS id_1, barcode_info.sku_lk_id, barcode_info.barcode, barcode_info.created_at AS created_at_1, barcode_info.updated_at AS updated_at_1, sku_bundle_lk.id AS id_2, sku_bundle_lk.sku_lk_id AS sku_lk_id_1, sku_bundle_lk.barcode AS barcode_1, sku_bundle_lk.item_amount, sku_bundle_lk.price, sku_bundle_lk.currency, sku_bundle_lk.weight AS weight_1, sku_bundle_lk.height AS height_1, sku_bundle_lk.length AS length_1, sku_bundle_lk.width AS width_1, sku_bundle_lk.created_at AS created_at_2, sku_bundle_lk.updated_at AS updated_at_2, sku_bundle_lk.number_bundle_on_level_pallet, sku_bundle_lk.external_sku_id AS external_sku_id_1, sku_bundle_lk.name AS name_1, sku_bundle_lk.erp_id AS erp_id_1 FROM sku_lk LEFT OUTER JOIN barcode_info ON barcode_info.sku_lk_id = sku_lk.id LEFT OUTER JOIN sku_bundle_lk ON sku_bundle_lk.sku_lk_id = sku_lk.id WHERE sku_lk.sku_id = %(sku_id_1)s INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 45.5s ago] {'sku_id_1': 75518811728550656} INFO sqlalchemy.engine.Engine:base.py:1846 SELECT barcode_info.id AS barcode_info_id, barcode_info.sku_lk_id AS barcode_info_sku_lk_id, barcode_info.barcode AS barcode_info_barcode, barcode_info.created_at AS barcode_info_created_at, barcode_info.updated_at AS barcode_info_updated_at FROM barcode_info WHERE %(param_1)s = barcode_info.sku_lk_id INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 45.49s ago] {'param_1': 3730} INFO kafka.conn:conn.py:400 [IPv4 ('10.100.48.16', 9092)]>: connecting to redpanda.redpanda-operator.svc:9092 [('10.100.48.16', 9092) IPv4] INFO kafka.conn:conn.py:622 [IPv4 ('10.100.48.16', 9092)]>: Broker version identified as 2.6 INFO kafka.conn:conn.py:461 [IPv4 ('10.100.48.16', 9092)]>: Connection complete. INFO kafka.conn:conn.py:400 [IPv4 ('10.100.48.16', 9092)]>: connecting to redpanda-1.redpanda.redpanda-operator.svc.cluster.local.:9092 [('10.100.48.16', 9092) IPv4] INFO kafka.conn:conn.py:461 [IPv4 ('10.100.48.16', 9092)]>: Connection complete. INFO kafka.conn:conn.py:951 [IPv4 ('10.100.48.16', 9092)]>: Closing connection. INFO kafka.conn:conn.py:400 [IPv4 ('10.100.174.181', 9092)]>: connecting to redpanda-0.redpanda.redpanda-operator.svc.cluster.local.:9092 [('10.100.174.181', 9092) IPv4] INFO kafka.conn:conn.py:461 [IPv4 ('10.100.174.181', 9092)]>: Connection complete. INFO kafka.conn:conn.py:951 [IPv4 ('10.100.48.16', 9092)]>: Closing connection. INFO kafka.conn:conn.py:951 [IPv4 ('10.100.174.181', 9092)]>: Closing connection. INFO sqlalchemy.engine.Engine:base.py:1846 SELECT inbox.id, inbox.topic, inbox.payload, inbox.status, inbox.retry_count, inbox.max_retry_count, inbox.key, inbox.headers, inbox.created_at, inbox.next_retry_at, inbox.processing_started_at, inbox.error_message, inbox.exception_stack_trace, inbox.processing_node, inbox.processing_timeout_minutes FROM inbox WHERE inbox.topic = %(topic_1)s AND CAST(inbox.payload AS VARCHAR) LIKE %(param_1)s INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 75.79s ago] {'topic_1': , 'param_1': '%52256600012167%'} INFO sqlalchemy.engine.Engine:base.py:1846 SELECT inbox.id, inbox.topic, inbox.payload, inbox.status, inbox.retry_count, inbox.max_retry_count, inbox.key, inbox.headers, inbox.created_at, inbox.next_retry_at, inbox.processing_started_at, inbox.error_message, inbox.exception_stack_trace, inbox.processing_node, inbox.processing_timeout_minutes FROM inbox WHERE inbox.topic = %(topic_1)s AND CAST(inbox.payload AS VARCHAR) LIKE %(param_1)s INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 80.8s ago] {'topic_1': , 'param_1': '%52256600012167%'} INFO sqlalchemy.engine.Engine:base.py:1846 SELECT inbox.id, inbox.topic, inbox.payload, inbox.status, inbox.retry_count, inbox.max_retry_count, inbox.key, inbox.headers, inbox.created_at, inbox.next_retry_at, inbox.processing_started_at, inbox.error_message, inbox.exception_stack_trace, inbox.processing_node, inbox.processing_timeout_minutes FROM inbox WHERE inbox.topic = %(topic_1)s AND CAST(inbox.payload AS VARCHAR) LIKE %(param_1)s INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 85.81s ago] {'topic_1': , 'param_1': '%52256600012167%'} INFO sqlalchemy.engine.Engine:base.py:1846 SELECT inbox.id, inbox.topic, inbox.payload, inbox.status, inbox.retry_count, inbox.max_retry_count, inbox.key, inbox.headers, inbox.created_at, inbox.next_retry_at, inbox.processing_started_at, inbox.error_message, inbox.exception_stack_trace, inbox.processing_node, inbox.processing_timeout_minutes FROM inbox WHERE inbox.topic = %(topic_1)s AND CAST(inbox.payload AS VARCHAR) LIKE %(param_1)s INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 90.83s ago] {'topic_1': , 'param_1': '%52256600012167%'} INFO sqlalchemy.engine.Engine:base.py:1846 SELECT sku_lk.id, sku_lk.sku_id, sku_lk.name, sku_lk.has_mark, sku_lk.has_expiration_date, sku_lk.created_at, sku_lk.updated_at, sku_lk.external_sku_id, sku_lk.weight, sku_lk.height, sku_lk.length, sku_lk.width, sku_lk.image_url, sku_lk.seller_id, sku_lk.erp_id, barcode_info.id AS id_1, barcode_info.sku_lk_id, barcode_info.barcode, barcode_info.created_at AS created_at_1, barcode_info.updated_at AS updated_at_1, sku_bundle_lk.id AS id_2, sku_bundle_lk.sku_lk_id AS sku_lk_id_1, sku_bundle_lk.barcode AS barcode_1, sku_bundle_lk.item_amount, sku_bundle_lk.price, sku_bundle_lk.currency, sku_bundle_lk.weight AS weight_1, sku_bundle_lk.height AS height_1, sku_bundle_lk.length AS length_1, sku_bundle_lk.width AS width_1, sku_bundle_lk.created_at AS created_at_2, sku_bundle_lk.updated_at AS updated_at_2, sku_bundle_lk.number_bundle_on_level_pallet, sku_bundle_lk.external_sku_id AS external_sku_id_1, sku_bundle_lk.name AS name_1, sku_bundle_lk.erp_id AS erp_id_1 FROM sku_lk LEFT OUTER JOIN barcode_info ON barcode_info.sku_lk_id = sku_lk.id LEFT OUTER JOIN sku_bundle_lk ON sku_bundle_lk.sku_lk_id = sku_lk.id WHERE sku_lk.sku_id = %(sku_id_1)s INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 60.66s ago] {'sku_id_1': 75518811728702720} INFO sqlalchemy.engine.Engine:base.py:1846 SELECT barcode_info.id AS barcode_info_id, barcode_info.sku_lk_id AS barcode_info_sku_lk_id, barcode_info.barcode AS barcode_info_barcode, barcode_info.created_at AS barcode_info_created_at, barcode_info.updated_at AS barcode_info_updated_at FROM barcode_info WHERE %(param_1)s = barcode_info.sku_lk_id INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 60.65s ago] {'param_1': 3731} INFO sqlalchemy.engine.Engine:base.py:1846 SELECT invoice.id, invoice.lk_invoice_id, invoice.original_invoice_number, invoice.need_mark, invoice.has_expiration_date, invoice.is_declared, invoice.invoice_status, invoice.date_create, invoice.vehicle_id, invoice.is_test, invoice.date_arrival, invoice.seller_id, invoice.warehouse_id, invoice.gtd_number, invoice.date_update, invoice.date_finished, invoice.partner_id FROM invoice WHERE invoice.original_invoice_number = %(original_invoice_number_1)s INFO sqlalchemy.engine.Engine:base.py:1846 [cached since 75.82s ago] {'original_invoice_number_1': '75518796593283328'} INFO sqlalchemy.engine.Engine:base.py:2713 ROLLBACK using DBAPI connection.rollback(); set skip_autocommit_rollback to prevent fully