[MXS-4231] Sometimes the date and time are missing in the query log Created: 2022-08-03  Updated: 2022-09-01  Resolved: 2022-09-01

Status: Closed
Project: MariaDB MaxScale
Component/s: qlafilter
Affects Version/s: 6.4.1, 22.08.0
Fix Version/s: 6.4.3, 22.08.1

Type: Bug Priority: Minor
Reporter: Nikita Borisenkov Assignee: markus makela
Resolution: Fixed Votes: 0
Labels: None


 Description   

This is a test setup. I send two requests per second, the requests are always the same. Sometimes the date and time are missing in the query_audit.unified log.

2022-08-01 23:59:47,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-01 23:59:47,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-01 23:59:48,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-01 23:59:49,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-01 23:59:50,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-01 23:59:51,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-01 23:59:52,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-01 23:59:53,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-01 23:59:54,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-01 23:59:54,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-01 23:59:55,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-01 23:59:56,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-01 23:59:56,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-01 23:59:57,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-01 23:59:58,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-01 23:59:59,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-02 00:00:00,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
2022-08-02 00:00:01,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1
,user@::ffff:192.168.68.88,SELECT * FROM db1 WHERE id=1

maxscale.cnf

[maxscale]
threads=auto
skip_name_resolve=true
admin_gui=false
admin_secure_gui=false
log_warning=true
log_notice=true
log_info=false
log_debug=false
 
[MyLogFilter]
type=filter
module=qlafilter
filebase=/var/log/maxscale/query_audit
log_type=unified
 
[node1]
type=server
address=10.77.1.1
port=3306
priority=1
rank=primary
 
[node2]
type=server
address=10.77.1.2
port=3306
priority=3
rank=secondary
 
[service]
type=service
router=readwritesplit
master_accept_reads=true
servers=node1,node2
user=mos_maxscale
password=ze6e1pio9ixh
filters=MyLogFilter
version_string=maxscale02
 
[monitor]
type=monitor
module=galeramon
user=mos_maxscale
password=ze6e1pio9ixh
servers=node1,node2
disable_master_failback=true
available_when_donor=true
use_priority=true
 
[galera-listener]
type=listener
service=service
port=3306
protocol=mariadbclient



 Comments   
Comment by markus makela [ 2022-08-30 ]

You need to use newline_replacement if you don't want literal newlines in the output.

Comment by markus makela [ 2022-08-30 ]

Whoops, my mistake: it's on by default in 6.4.

Comment by markus makela [ 2022-08-30 ]

I managed to reproduce this on 6.4.2.

Generated at Thu Feb 08 04:27:09 UTC 2024 using Jira 8.20.16#820016-sha1:9d11dbea5f4be3d4cc21f03a88dd11d8c8687422.