Line usage in info/debugg logging - Mendix Forum

Line usage in info/debugg logging

5

Sometimes it is necessary to turn on as many debug and trace logs as possible to try to figure out what is happening. Turning on too much causes an information overload and performance issues.

At the moment there is a limit of 500 lines/s

2018-11-21T08:45:50.80+0000 [APP/PROC/WEB/0]	DEBUG - ConnectionBus_Retrieve: SQL: SELECT "system$user_language"."system$userid", 
2018-11-21T08:45:50.80+0000 [APP/PROC/WEB/0]	DEBUG - ConnectionBus_Retrieve: SQL: SELECT "system$user_timezone"."system$userid", 
2018-11-21T08:45:50.80+0000 [APP/PROC/WEB/0]	Rate-limiting to 500 loglines/second. Suppressed 3.
2018-11-21T08:45:50.80+0000 [APP/PROC/WEB/0]	DEBUG - ConnectionBus_Retrieve: SQL: SELECT "system$userroles"."system$userid", 
2018-11-21T08:45:50.80+0000 [APP/PROC/WEB/0]	Rate-limiting to 500 loglines/second. Suppressed 3.
2018-11-21T08:45:50.80+0000 [APP/PROC/WEB/0]	Rate-limiting to 500 loglines/second. Suppressed 3.
2018-11-21T08:45:50.80+0000 [APP/PROC/WEB/0]	DEBUG - ConnectionBus_Retrieve: RequestAnalyzer: incoming request InternalIdentifierGetRequest:  Object type: Some(Administration.Account)
2018-11-21T08:45:50.80+0000 [APP/PROC/WEB/0]	DEBUG - ConnectionBus_Retrieve: RequestAnalyzer: incoming request InternalIdentifierGetRequest:  Object type: Some(Administration.Account)
2018-11-21T08:45:50.81+0000 [APP/PROC/WEB/0]	Rate-limiting to 500 loglines/second. Suppressed 28.
2018-11-21T08:45:50.81+0000 [APP/PROC/WEB/0]	 WHERE "administration$account"."id" = ?

 

It would be nice if there where some conservative rules on how the log information is constructed. As an example, it seems a bit wasteful to use one line/attribute when listing the debug message for ConnectionBus_Retrive.

2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	DEBUG - ConnectionBus_Retrieve: SQL: SELECT "administration$account"."id", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"administration$account"."email", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"administration$account"."jobrole", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"administration$account"."first_name", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"administration$account"."usedfortest", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"administration$account"."accountstatus", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"administration$account"."skype", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"administration$account"."last_name", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"administration$account"."all_notifications", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"administration$account"."phone", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"administration$account"."fullname", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"administration$account"."resetrequired", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"administration$account"."islocaluser", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"administration$account"."scrolllocation", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"dj1system$user"."changeddate", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"dj1system$user"."active", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"dj1system$user"."createddate", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"dj1system$user"."lastlogin", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"dj1system$user"."webserviceuser", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"dj1system$user"."isanonymous", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"dj1system$user"."failedlogins", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"dj1system$user"."blocked", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"dj1system$user"."name", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"dj1system$user"."password", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"dj1system$user"."system$owner", 
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	"dj1system$user"."system$changedby"
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	 FROM "administration$account"
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	 INNER JOIN "system$user" "dj1system$user" ON "dj1system$user"."id" = "administration$account"."id"
2018-11-21T08:45:52.40+0000 [APP/PROC/WEB/0]	 WHERE "administration$account"."id" = ?

Changing this will of course break any script that any user have done to parse log files. But I think that the amount of new information in its place will be worth it.

The value for me if this is changed is an easier and nicer debug experience. Getting more information and the possibility to home in on problems faster.

asked
0 answers