Skip to content

Improve logging #14318

Description

@etj

Log settings are a bit messed up at the moment.
Also, output is quite bad formatted:

Sample current output
INFO 2026-06-10 09:14:31,479 basehttp 1807608 125832118920896 "GET / HTTP/1.0" 200 88853
DEBUG 2026-06-10 09:14:32,130 resource 1807608 125832118920896 Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x7271930f1280>.
INFO 2026-06-10 09:14:32,272 basehttp 1807608 125832118920896 "GET /api/v2/ HTTP/1.0" 200 867
WARNING 2026-06-10 09:14:32,302 log 1807608 125832118920896 Not Found: /api/o/v4/userinfo
WARNING 2026-06-10 09:14:32,302 basehttp 1807608 125832118920896 "GET /api/o/v4/userinfo HTTP/1.0" 404 43806
DEBUG 2026-06-10 09:14:32,347 resource 1807608 125832118920896 Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x727191b0c740>.
INFO 2026-06-10 09:14:32,366 basehttp 1807608 125832118920896 "GET /api/v2/reqrules HTTP/1.0" 200 12
INFO 2026-06-10 09:14:32,367 basehttp 1807608 125832101091008 "GET /client/extensions HTTP/1.0" 302 0
DEBUG 2026-06-10 09:14:32,373 resource 1807608 125832101091008 Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x727191b1ec00>.
INFO 2026-06-10 09:14:32,391 basehttp 1807608 125832101091008 "GET /it-it/client/extensions HTTP/1.0" 200 2
DEBUG 2026-06-10 09:14:32,994 resource 1807608 125832101091008 Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x727191b3bb80>.
DEBUG 2026-06-10 09:14:32,995 resource 1807608 125832118920896 Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x727191b56080>.
DEBUG 2026-06-10 09:14:33,309 serializers 1807608 125832101091008 AutoLinkField reading Meta from first parent - Parents: ['UserSerializer', 'DynamicRelationField', 'ResourceBaseSerializer', 'DynamicListSerializer'] root: DynamicListSerializer
DEBUG 2026-06-10 09:14:33,311 serializers 1807608 125832118920896 AutoLinkField reading Meta from first parent - Parents: ['UserSerializer', 'DynamicRelationField', 'ResourceBaseSerializer', 'DynamicListSerializer'] root: DynamicListSerializer
INFO 2026-06-10 09:14:33,478 basehttp 1807608 125832101091008 "GET /api/v2/resources?api_preset=catalog_list&filter%7Bfeatured%7D=true&filter%7Bmetadata_only%7D=false&page=1&page_size=4 HTTP/1.0" 200 2447
INFO 2026-06-10 09:14:34,038 basehttp 1807608 125832118920896 "GET /api/v2/resources?api_preset=catalog_list&filter%7Bmetadata_only%7D=false&page=1&page_size=12 HTTP/1.0" 200 11992
INFO 2026-06-10 09:14:38,491 basehttp 1807608 125832118920896 "GET /catalogue/ HTTP/1.0" 200 86340
DEBUG 2026-06-10 09:14:38,981 resource 1807608 125832118920896 Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x72719123d740>.
INFO 2026-06-10 09:14:38,983 basehttp 1807608 125832118920896 "GET /api/v2/ HTTP/1.0" 200 867
WARNING 2026-06-10 09:14:39,024 log 1807608 125832118920896 Not Found: /api/o/v4/userinfo
WARNING 2026-06-10 09:14:39,025 basehttp 1807608 125832118920896 "GET /api/o/v4/userinfo HTTP/1.0" 404 43806
DEBUG 2026-06-10 09:14:39,064 resource 1807608 125832118920896 Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x727191b065c0>.
INFO 2026-06-10 09:14:39,080 basehttp 1807608 125832118920896 "GET /api/v2/reqrules HTTP/1.0" 200 12
INFO 2026-06-10 09:14:39,080 basehttp 1807608 125832101091008 "GET /client/extensions HTTP/1.0" 302 0
DEBUG 2026-06-10 09:14:39,089 resource 1807608 125832101091008 Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x7271920a3680>.
INFO 2026-06-10 09:14:39,106 basehttp 1807608 125832101091008 "GET /it-it/client/extensions HTTP/1.0" 200 2
DEBUG 2026-06-10 09:14:39,853 resource 1807608 125832101091008 Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x727191bc9ec0>.
DEBUG 2026-06-10 09:14:40,019 serializers 1807608 125832101091008 AutoLinkField reading Meta from first parent - Parents: ['UserSerializer', 'DynamicRelationField', 'DatasetSerializer'] root: DatasetSerializer
DEBUG 2026-06-10 09:14:40,057 download_handler 1807608 125832101091008 You do not have download permissions for this dataset.
DEBUG 2026-06-10 09:14:40,215 serializers 1807608 125832101091008 AutoLinkField reading Meta from first parent - Parents: ['UserSerializer'] root: UserSerializer
DEBUG 2026-06-10 09:14:40,221 serializers 1807608 125832101091008 AutoLinkField reading Meta from first parent - Parents: ['UserSerializer'] root: UserSerializer
INFO 2026-06-10 09:14:40,223 basehttp 1807608 125832101091008 "GET /api/v2/datasets/2076?api_preset=viewer_common&api_preset=dataset_viewer HTTP/1.0" 200 11248
DEBUG 2026-06-10 09:14:40,710 resource 1807608 125832101091008 Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x727191f57400>.
Sample proposed output
2026-06-10T09:16:30,440 INFO    basehttp        1809089:7c963a5fe6c0 - "GET / HTTP/1.0" 200 88864
2026-06-10T09:16:30,998 DEBUG   resource        1809089:7c963a5fe6c0 - Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x7c9639ba7540>.
2026-06-10T09:16:31,002 INFO    basehttp        1809089:7c963a5fe6c0 - "GET /api/v2/ HTTP/1.0" 200 867
2026-06-10T09:16:31,050 WARNING log             1809089:7c963a5fe6c0 - Not Found: /api/o/v4/userinfo
2026-06-10T09:16:31,051 WARNING basehttp        1809089:7c963a5fe6c0 - "GET /api/o/v4/userinfo HTTP/1.0" 404 43806
2026-06-10T09:16:31,075 DEBUG   resource        1809089:7c963a5fe6c0 - Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x7c9639d08b40>.
2026-06-10T09:16:31,090 INFO    basehttp        1809089:7c96399fd6c0 - "GET /client/extensions HTTP/1.0" 302 0
2026-06-10T09:16:31,090 INFO    basehttp        1809089:7c963a5fe6c0 - "GET /api/v2/reqrules HTTP/1.0" 200 12
2026-06-10T09:16:31,096 DEBUG   resource        1809089:7c963a5fe6c0 - Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x7c9639a69d40>.
2026-06-10T09:16:31,112 INFO    basehttp        1809089:7c963a5fe6c0 - "GET /it-it/client/extensions HTTP/1.0" 200 2
2026-06-10T09:16:31,701 DEBUG   resource        1809089:7c963a5fe6c0 - Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x7c963a83fe40>.
2026-06-10T09:16:31,708 DEBUG   resource        1809089:7c96399fd6c0 - Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x7c963a86d540>.
2026-06-10T09:16:31,923 DEBUG   serializers     1809089:7c96399fd6c0 - AutoLinkField reading Meta from first parent - Parents: ['UserSerializer', 'DynamicRelationField', 'ResourceBaseSerializer', 'DynamicListSerializer'] root: DynamicListSerializer
2026-06-10T09:16:31,924 DEBUG   serializers     1809089:7c963a5fe6c0 - AutoLinkField reading Meta from first parent - Parents: ['UserSerializer', 'DynamicRelationField', 'ResourceBaseSerializer', 'DynamicListSerializer'] root: DynamicListSerializer
2026-06-10T09:16:32,076 INFO    basehttp        1809089:7c963a5fe6c0 - "GET /api/v2/resources?api_preset=catalog_list&filter%7Bfeatured%7D=true&filter%7Bmetadata_only%7D=false&page=1&page_size=4 HTTP/1.0" 200 2447
2026-06-10T09:16:32,600 INFO    basehttp        1809089:7c96399fd6c0 - "GET /api/v2/resources?api_preset=catalog_list&filter%7Bmetadata_only%7D=false&page=1&page_size=12 HTTP/1.0" 200 11992
2026-06-10T09:16:36,608 INFO    basehttp        1809089:7c96399fd6c0 - "GET /catalogue/ HTTP/1.0" 200 86340
2026-06-10T09:16:37,011 DEBUG   resource        1809089:7c96399fd6c0 - Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x7c9639190600>.
2026-06-10T09:16:37,017 INFO    basehttp        1809089:7c96399fd6c0 - "GET /api/v2/ HTTP/1.0" 200 867
2026-06-10T09:16:37,144 WARNING log             1809089:7c96399fd6c0 - Not Found: /api/o/v4/userinfo
2026-06-10T09:16:37,152 WARNING basehttp        1809089:7c96399fd6c0 - "GET /api/o/v4/userinfo HTTP/1.0" 404 43806
2026-06-10T09:16:37,217 DEBUG   resource        1809089:7c96399fd6c0 - Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x7c9639bc8180>.
2026-06-10T09:16:37,220 INFO    basehttp        1809089:7c96399fd6c0 - "GET /api/v2/reqrules HTTP/1.0" 200 12
2026-06-10T09:16:37,268 INFO    basehttp        1809089:7c963a5fe6c0 - "GET /client/extensions HTTP/1.0" 302 0
2026-06-10T09:16:37,273 DEBUG   resource        1809089:7c963a5fe6c0 - Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x7c9639a92c00>.
2026-06-10T09:16:37,297 INFO    basehttp        1809089:7c963a5fe6c0 - "GET /it-it/client/extensions HTTP/1.0" 200 2
2026-06-10T09:16:38,018 DEBUG   resource        1809089:7c963a5fe6c0 - Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x7c9639a43a80>.
2026-06-10T09:16:38,240 DEBUG   serializers     1809089:7c963a5fe6c0 - AutoLinkField reading Meta from first parent - Parents: ['UserSerializer', 'DynamicRelationField', 'DatasetSerializer'] root: DatasetSerializer
2026-06-10T09:16:38,463 DEBUG   download_handler 1809089:7c963a5fe6c0 - You do not have download permissions for this dataset.
2026-06-10T09:16:38,482 DEBUG   serializers     1809089:7c963a5fe6c0 - AutoLinkField reading Meta from first parent - Parents: ['UserSerializer'] root: UserSerializer
2026-06-10T09:16:38,490 DEBUG   serializers     1809089:7c963a5fe6c0 - AutoLinkField reading Meta from first parent - Parents: ['UserSerializer'] root: UserSerializer
2026-06-10T09:16:38,499 INFO    basehttp        1809089:7c963a5fe6c0 - "GET /api/v2/datasets/2076?api_preset=viewer_common&api_preset=dataset_viewer HTTP/1.0" 200 11248
2026-06-10T09:16:39,214 DEBUG   resource        1809089:7c963a5fe6c0 - Dispatching token_type JWT request to <oauthlib.openid.connect.core.tokens.JWTToken object at 0x7c96391b46c0>.
2026-06-10T09:16:39,398 INFO    basehttp        1809089:7c963a5fe6c0 - "GET /api/v2/datasets?page_size=1&filter%7Balternate.in%7D[]=geonode:pat_po_1bd518ea4c962fd24e4bc405243da208&exclude[]=*&include[]=pk&include[]=perms&include[]=alternate HTTP/1.0" 200 191

The proposal is to:

  • improve log format
  • add the missing recommended root logger
  • remove the unused mail_admins handler
  • replace the deprecated alias WARN to WARNING

Metadata

Metadata

Assignees

Fields

No fields configured for Enhancement.

Projects

No projects

Milestone

Relationships

None yet

Development

No branches or pull requests

Issue actions