{"level":"info","ts":"2026-07-21T06:40:14.987737226Z","msg":"OS distribution is supported","logger":"instance-manager","logging_pod":"keycloak-db-1","entry":{"version":"13 (trixie)","deprecatedFrom":"2028-08-09T00:00:00Z","supportedUntil":"2030-06-30T00:00:00Z"}} {"level":"info","ts":"2026-07-21T06:40:14.987926609Z","msg":"Starting CloudNativePG Instance Manager","logger":"instance-manager","logging_pod":"keycloak-db-1","version":"1.28.4","build":{"Version":"1.28.4","Commit":"f74344b4d","Date":"2026-06-29"},"skipNameValidation":false} {"level":"info","ts":"2026-07-21T06:40:15.340649942Z","msg":"starting tablespace manager","logger":"instance-manager","logging_pod":"keycloak-db-1"} {"level":"info","ts":"2026-07-21T06:40:15.340749144Z","msg":"starting external server manager","logger":"instance-manager","logging_pod":"keycloak-db-1"} {"level":"info","ts":"2026-07-21T06:40:15.340807815Z","msg":"starting controller-runtime manager","logger":"instance-manager","logging_pod":"keycloak-db-1"} {"level":"info","ts":"2026-07-21T06:40:15.341496579Z","msg":"Starting EventSource","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-subscription","controllerGroup":"postgresql.cnpg.io","controllerKind":"Subscription","source":"kind source: *v1.Cluster"} {"level":"info","ts":"2026-07-21T06:40:15.341485388Z","msg":"Starting EventSource","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-cluster","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","source":"kind source: *v1.Cluster"} {"level":"info","ts":"2026-07-21T06:40:15.34159965Z","msg":"Starting EventSource","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-database","controllerGroup":"postgresql.cnpg.io","controllerKind":"Database","source":"kind source: *v1.Cluster"} {"level":"info","ts":"2026-07-21T06:40:15.341756193Z","msg":"Starting EventSource","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-database","controllerGroup":"postgresql.cnpg.io","controllerKind":"Database","source":"kind source: *v1.Database"} {"level":"info","ts":"2026-07-21T06:40:15.341901046Z","msg":"Starting EventSource","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-subscription","controllerGroup":"postgresql.cnpg.io","controllerKind":"Subscription","source":"kind source: *v1.Subscription"} {"level":"info","ts":"2026-07-21T06:40:15.342052839Z","msg":"Starting EventSource","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-publication","controllerGroup":"postgresql.cnpg.io","controllerKind":"Publication","source":"kind source: *v1.Cluster"} {"level":"info","ts":"2026-07-21T06:40:15.342058649Z","logger":"roles_reconciler","msg":"starting up the runnable","logger":"instance-manager","logging_pod":"keycloak-db-1"} {"level":"info","ts":"2026-07-21T06:40:15.34210423Z","logger":"roles_reconciler","msg":"setting up RoleSynchronizer loop","logger":"instance-manager","logging_pod":"keycloak-db-1"} {"level":"info","ts":"2026-07-21T06:40:15.342158771Z","msg":"Starting webserver","logger":"instance-manager","logging_pod":"keycloak-db-1","address":"localhost:8010","hasTLS":false} {"level":"info","ts":"2026-07-21T06:40:15.342250933Z","msg":"Starting EventSource","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-publication","controllerGroup":"postgresql.cnpg.io","controllerKind":"Publication","source":"kind source: *v1.Publication"} {"level":"info","ts":"2026-07-21T06:40:15.342378035Z","msg":"Starting webserver","logger":"instance-manager","logging_pod":"keycloak-db-1","address":":8000","hasTLS":true} {"level":"info","ts":"2026-07-21T06:40:15.34261303Z","msg":"Starting webserver","logger":"instance-manager","logging_pod":"keycloak-db-1","address":":9187","hasTLS":false} {"level":"info","ts":"2026-07-21T06:40:15.342740123Z","msg":"Starting EventSource","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-tablespaces","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","source":"kind source: *v1.Cluster"} {"level":"info","ts":"2026-07-21T06:40:15.343086399Z","msg":"Starting EventSource","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-external-server","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","source":"kind source: *v1.Cluster"} {"level":"info","ts":"2026-07-21T06:40:15.346213719Z","msg":"Installed configuration file","logger":"instance-manager","logging_pod":"keycloak-db-1","pgdata":"/var/lib/postgresql/data/pgdata","filename":"pg_ident.conf"} {"level":"info","ts":"2026-07-21T06:40:15.54295714Z","msg":"Starting Controller","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-cluster","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster"} {"level":"info","ts":"2026-07-21T06:40:15.54295238Z","msg":"Starting Controller","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-publication","controllerGroup":"postgresql.cnpg.io","controllerKind":"Publication"} {"level":"info","ts":"2026-07-21T06:40:15.543038361Z","msg":"Starting Controller","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-database","controllerGroup":"postgresql.cnpg.io","controllerKind":"Database"} {"level":"info","ts":"2026-07-21T06:40:15.543059212Z","msg":"Starting workers","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-publication","controllerGroup":"postgresql.cnpg.io","controllerKind":"Publication","worker count":1} {"level":"info","ts":"2026-07-21T06:40:15.543068682Z","msg":"Starting workers","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-database","controllerGroup":"postgresql.cnpg.io","controllerKind":"Database","worker count":1} {"level":"info","ts":"2026-07-21T06:40:15.542993831Z","msg":"Starting workers","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-cluster","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","worker count":1} {"level":"info","ts":"2026-07-21T06:40:15.543126743Z","msg":"Starting Controller","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-subscription","controllerGroup":"postgresql.cnpg.io","controllerKind":"Subscription"} {"level":"info","ts":"2026-07-21T06:40:15.543132143Z","msg":"Starting workers","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-subscription","controllerGroup":"postgresql.cnpg.io","controllerKind":"Subscription","worker count":1} {"level":"info","ts":"2026-07-21T06:40:15.543214795Z","msg":"Starting Controller","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-tablespaces","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster"} {"level":"info","ts":"2026-07-21T06:40:15.543291556Z","msg":"Starting workers","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-tablespaces","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","worker count":1} {"level":"info","ts":"2026-07-21T06:40:15.54348754Z","msg":"Starting Controller","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-external-server","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster"} {"level":"info","ts":"2026-07-21T06:40:15.54350401Z","msg":"Starting workers","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-external-server","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","worker count":1} {"level":"info","ts":"2026-07-21T06:40:15.557149804Z","msg":"Refreshed configuration file","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-cluster","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","Cluster":{"name":"keycloak-db","namespace":"auth-system"},"namespace":"auth-system","name":"keycloak-db","reconcileID":"ee5ce16a-4009-4556-a28d-0517eb8cb41b","logging_pod":"keycloak-db-1","filename":"/controller/certificates/server.crt","secret":"keycloak-db-server"} {"level":"info","ts":"2026-07-21T06:40:15.560238363Z","msg":"Refreshed configuration file","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-cluster","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","Cluster":{"name":"keycloak-db","namespace":"auth-system"},"namespace":"auth-system","name":"keycloak-db","reconcileID":"ee5ce16a-4009-4556-a28d-0517eb8cb41b","logging_pod":"keycloak-db-1","filename":"/controller/certificates/server.key","secret":"keycloak-db-server"} {"level":"info","ts":"2026-07-21T06:40:15.565574976Z","msg":"Refreshed configuration file","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-cluster","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","Cluster":{"name":"keycloak-db","namespace":"auth-system"},"namespace":"auth-system","name":"keycloak-db","reconcileID":"ee5ce16a-4009-4556-a28d-0517eb8cb41b","logging_pod":"keycloak-db-1","filename":"/controller/certificates/streaming_replica.crt","secret":"keycloak-db-replication"} {"level":"info","ts":"2026-07-21T06:40:15.568222678Z","msg":"Refreshed configuration file","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-cluster","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","Cluster":{"name":"keycloak-db","namespace":"auth-system"},"namespace":"auth-system","name":"keycloak-db","reconcileID":"ee5ce16a-4009-4556-a28d-0517eb8cb41b","logging_pod":"keycloak-db-1","filename":"/controller/certificates/streaming_replica.key","secret":"keycloak-db-replication"} {"level":"info","ts":"2026-07-21T06:40:15.573417047Z","msg":"Refreshed configuration file","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-cluster","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","Cluster":{"name":"keycloak-db","namespace":"auth-system"},"namespace":"auth-system","name":"keycloak-db","reconcileID":"ee5ce16a-4009-4556-a28d-0517eb8cb41b","logging_pod":"keycloak-db-1","filename":"/controller/certificates/client-ca.crt","secret":"keycloak-db-ca"} {"level":"info","ts":"2026-07-21T06:40:15.578604818Z","msg":"Refreshed configuration file","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-cluster","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","Cluster":{"name":"keycloak-db","namespace":"auth-system"},"namespace":"auth-system","name":"keycloak-db","reconcileID":"ee5ce16a-4009-4556-a28d-0517eb8cb41b","logging_pod":"keycloak-db-1","filename":"/controller/certificates/server-ca.crt","secret":"keycloak-db-ca"} {"level":"info","ts":"2026-07-21T06:40:15.582152967Z","msg":"Installed configuration file","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-cluster","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","Cluster":{"name":"keycloak-db","namespace":"auth-system"},"namespace":"auth-system","name":"keycloak-db","reconcileID":"ee5ce16a-4009-4556-a28d-0517eb8cb41b","logging_pod":"keycloak-db-1","pgdata":"/var/lib/postgresql/data/pgdata","filename":"pg_hba.conf"} {"level":"info","ts":"2026-07-21T06:40:15.582246289Z","msg":"Detected running images","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-cluster","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","Cluster":{"name":"keycloak-db","namespace":"auth-system"},"namespace":"auth-system","name":"keycloak-db","reconcileID":"ee5ce16a-4009-4556-a28d-0517eb8cb41b","logging_pod":"keycloak-db-1","runningImages":["harbor.atmosphere.dev/ghcr.io/cloudnative-pg/postgresql:18.4-202606221003-minimal-trixie@sha256:538f1b327b8dc7621a94aac79c9c3e5c50dfa29115a6c291e19372a5b876f415"]} {"level":"info","ts":"2026-07-21T06:40:15.5880458Z","msg":"Installed configuration file","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-cluster","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","Cluster":{"name":"keycloak-db","namespace":"auth-system"},"namespace":"auth-system","name":"keycloak-db","reconcileID":"ee5ce16a-4009-4556-a28d-0517eb8cb41b","logging_pod":"keycloak-db-1","pgdata":"/var/lib/postgresql/data/pgdata","filename":"custom.conf"} {"level":"info","ts":"2026-07-21T06:40:15.588491029Z","msg":"Cluster status","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-cluster","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","Cluster":{"name":"keycloak-db","namespace":"auth-system"},"namespace":"auth-system","name":"keycloak-db","reconcileID":"ee5ce16a-4009-4556-a28d-0517eb8cb41b","logging_pod":"keycloak-db-1","currentPrimary":"","targetPrimary":"keycloak-db-1","isReplicaCluster":false} {"level":"info","ts":"2026-07-21T06:40:15.588513759Z","msg":"First primary instance bootstrap, marking myself as primary","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-cluster","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","Cluster":{"name":"keycloak-db","namespace":"auth-system"},"namespace":"auth-system","name":"keycloak-db","reconcileID":"ee5ce16a-4009-4556-a28d-0517eb8cb41b","logging_pod":"keycloak-db-1","currentPrimary":"","targetPrimary":"keycloak-db-1"} {"level":"info","ts":"2026-07-21T06:40:15.620971606Z","msg":"Extracting pg_controldata information","logger":"instance-manager","logging_pod":"keycloak-db-1","reason":"postmaster start up"} {"level":"info","ts":"2026-07-21T06:40:15.622543026Z","logger":"pg_controldata","msg":"pg_control version number: 1800\nCatalog version number: 202506291\nDatabase system identifier: 7664867384227536923\nDatabase cluster state: shut down\npg_control last modified: Tue Jul 21 06:40:08 2026\nLatest checkpoint location: 0/2000028\nLatest checkpoint's REDO location: 0/2000028\nLatest checkpoint's REDO WAL file: 000000010000000000000002\nLatest checkpoint's TimeLineID: 1\nLatest checkpoint's PrevTimeLineID: 1\nLatest checkpoint's full_page_writes: on\nLatest checkpoint's NextXID: 0:754\nLatest checkpoint's NextOID: 16386\nLatest checkpoint's NextMultiXactId: 1\nLatest checkpoint's NextMultiOffset: 0\nLatest checkpoint's oldestXID: 744\nLatest checkpoint's oldestXID's DB: 1\nLatest checkpoint's oldestActiveXID: 0\nLatest checkpoint's oldestMultiXid: 1\nLatest checkpoint's oldestMulti's DB: 1\nLatest checkpoint's oldestCommitTsXid:0\nLatest checkpoint's newestCommitTsXid:0\nTime of latest checkpoint: Tue Jul 21 06:40:07 2026\nFake LSN counter for unlogged rels: 0/3E8\nMinimum recovery ending location: 0/0\nMin recovery ending loc's timeline: 0\nBackup start location: 0/0\nBackup end location: 0/0\nEnd-of-backup record required: no\nwal_level setting: logical\nwal_log_hints setting: on\nmax_connections setting: 100\nmax_worker_processes setting: 60\nmax_wal_senders setting: 10\nmax_prepared_xacts setting: 0\nmax_locks_per_xact setting: 64\ntrack_commit_timestamp setting: off\nMaximum data alignment: 8\nDatabase block size: 8192\nBlocks per segment of large relation: 131072\nWAL block size: 8192\nBytes per WAL segment: 16777216\nMaximum length of identifiers: 64\nMaximum columns in an index: 32\nMaximum size of a TOAST chunk: 1996\nSize of a large-object chunk: 2048\nDate/time type storage: 64-bit integers\nFloat8 argument passing: by value\nData page checksum version: 1\nDefault char data signedness: signed\nMock authentication nonce: 9643b7a469275802ab3b919d83399c843fb3873cbab1da3f2ba97b3840e6f8e1\n","pipe":"stdout","logging_pod":"keycloak-db-1"} {"level":"info","ts":"2026-07-21T06:40:15.623950723Z","msg":"postmaster started","logger":"instance-manager","logging_pod":"keycloak-db-1","postMasterPID":31} {"level":"info","ts":"2026-07-21T06:40:15.62429543Z","msg":"DB not available, will retry","logger":"instance-manager","logging_pod":"keycloak-db-1","err":"failed to connect to `user=postgres database=postgres`: /controller/run/.s.PGSQL.5432 (/controller/run): dial error: dial unix /controller/run/.s.PGSQL.5432: connect: no such file or directory"} {"level":"info","ts":"2026-07-21T06:40:15.635298273Z","msg":"Instance is still down, will retry in 1 second","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-cluster","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","Cluster":{"name":"keycloak-db","namespace":"auth-system"},"namespace":"auth-system","name":"keycloak-db","reconcileID":"ee5ce16a-4009-4556-a28d-0517eb8cb41b","instance":"keycloak-db-1","cluster":"keycloak-db","namespace":"auth-system","logging_pod":"keycloak-db-1"} {"level":"info","ts":"2026-07-21T06:40:15.654147367Z","logger":"postgres","msg":"2026-07-21 06:40:15.654 UTC [31] LOG: redirecting log output to logging collector process","pipe":"stderr","logging_pod":"keycloak-db-1"} {"level":"info","ts":"2026-07-21T06:40:15.654184797Z","logger":"postgres","msg":"2026-07-21 06:40:15.654 UTC [31] HINT: Future log output will appear in directory \"/controller/log\".","pipe":"stderr","logging_pod":"keycloak-db-1"} {"level":"info","ts":"2026-07-21T06:40:15.655434782Z","logger":"postgres","msg":"2026-07-21 06:40:15.654 UTC [31] LOG: ending log output to stderr","source":"/controller/log/postgres","logging_pod":"keycloak-db-1"} {"level":"info","ts":"2026-07-21T06:40:15.655503073Z","logger":"postgres","msg":"2026-07-21 06:40:15.654 UTC [31] HINT: Future log output will go to log destination \"csvlog\".","source":"/controller/log/postgres","logging_pod":"keycloak-db-1"} {"level":"info","ts":"2026-07-21T06:40:15.654809829Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 06:40:15.654 UTC","process_id":"31","session_id":"6a5f144f.1f","session_line_num":"1","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"ending log output to stderr","hint":"Future log output will go to log destination \"csvlog\".","backend_type":"postmaster","query_id":"0"}} {"level":"info","ts":"2026-07-21T06:40:15.656289588Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 06:40:15.654 UTC","process_id":"31","session_id":"6a5f144f.1f","session_line_num":"2","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"starting PostgreSQL 18.4 (Debian 18.4-1.pgdg13+1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 14.2.0-19) 14.2.0, 64-bit","backend_type":"postmaster","query_id":"0"}} {"level":"info","ts":"2026-07-21T06:40:15.656452801Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 06:40:15.654 UTC","process_id":"31","session_id":"6a5f144f.1f","session_line_num":"3","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"listening on IPv4 address \"0.0.0.0\", port 5432","backend_type":"postmaster","query_id":"0"}} {"level":"info","ts":"2026-07-21T06:40:15.656470752Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 06:40:15.654 UTC","process_id":"31","session_id":"6a5f144f.1f","session_line_num":"4","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"listening on IPv6 address \"::\", port 5432","backend_type":"postmaster","query_id":"0"}} {"level":"info","ts":"2026-07-21T06:40:15.66003793Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 06:40:15.659 UTC","process_id":"31","session_id":"6a5f144f.1f","session_line_num":"5","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"listening on Unix socket \"/controller/run/.s.PGSQL.5432\"","backend_type":"postmaster","query_id":"0"}} {"level":"info","ts":"2026-07-21T06:40:15.668110286Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 06:40:15.667 UTC","process_id":"39","session_id":"6a5f144f.27","session_line_num":"1","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"database system was shut down at 2026-07-21 06:40:08 UTC","backend_type":"startup","query_id":"0"}} {"level":"info","ts":"2026-07-21T06:40:15.673874638Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 06:40:15.673 UTC","process_id":"31","session_id":"6a5f144f.1f","session_line_num":"6","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"database system is ready to accept connections","backend_type":"postmaster","query_id":"0"}} {"level":"info","ts":"2026-07-21T06:40:15.720328765Z","msg":"Triggered a managed role reconciliation","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-cluster","controllerGroup":"postgresql.cnpg.io","controllerKind":"Cluster","Cluster":{"name":"keycloak-db","namespace":"auth-system"},"namespace":"auth-system","name":"keycloak-db","reconcileID":"5b88bb15-5ee5-49d6-a8d5-fd3d68c4c802","logging_pod":"keycloak-db-1","pending-roles":["keycloak"]} {"level":"info","ts":"2026-07-21T06:40:31.26137423Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 06:40:31.261 UTC","process_id":"37","session_id":"6a5f144f.25","session_line_num":"1","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"checkpoint starting: force wait","backend_type":"checkpointer","query_id":"0"}} {"level":"info","ts":"2026-07-21T06:40:33.576998253Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 06:40:33.576 UTC","process_id":"37","session_id":"6a5f144f.25","session_line_num":"2","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"checkpoint complete: wrote 21 buffers (0.1%), wrote 3 SLRU buffers; 0 WAL file(s) added, 0 removed, 0 recycled; write=2.111 s, sync=0.091 s, total=2.316 s; sync files=21, longest=0.046 s, average=0.005 s; distance=16384 kB, estimate=16384 kB; lsn=0/3000080, redo lsn=0/3000028","backend_type":"checkpointer","query_id":"0"}} {"level":"info","ts":"2026-07-21T06:40:56.431147289Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 06:40:56.430 UTC","process_id":"37","session_id":"6a5f144f.25","session_line_num":"3","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"checkpoint starting: force wait","backend_type":"checkpointer","query_id":"0"}} {"level":"info","ts":"2026-07-21T06:40:56.527565821Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 06:40:56.527 UTC","process_id":"37","session_id":"6a5f144f.25","session_line_num":"4","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"checkpoint complete: wrote 0 buffers (0.0%), wrote 0 SLRU buffers; 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.097 s; sync files=0, longest=0.000 s, average=0.000 s; distance=32768 kB, estimate=32768 kB; lsn=0/5000080, redo lsn=0/5000028","backend_type":"checkpointer","query_id":"0"}} {"level":"info","ts":"2026-07-21T06:41:13.800355371Z","logger":"database_reconciler","msg":"Reconciling database","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-database","controllerGroup":"postgresql.cnpg.io","controllerKind":"Database","Database":{"name":"keycloak","namespace":"auth-system"},"namespace":"auth-system","name":"keycloak","reconcileID":"ef8f28c4-7845-438a-8971-e8ef69b44f4d","databaseName":"keycloak","logging_pod":"keycloak-db-1"} {"level":"info","ts":"2026-07-21T06:41:13.887477233Z","logger":"database_reconciler","msg":"Reconciliation loop of database exited","logger":"instance-manager","logging_pod":"keycloak-db-1","controller":"instance-database","controllerGroup":"postgresql.cnpg.io","controllerKind":"Database","Database":{"name":"keycloak","namespace":"auth-system"},"namespace":"auth-system","name":"keycloak","reconcileID":"ef8f28c4-7845-438a-8971-e8ef69b44f4d","databaseName":"keycloak","logging_pod":"keycloak-db-1"} {"level":"info","ts":"2026-07-21T06:41:44.117431248Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 06:41:44.117 UTC","user_name":"keycloak","database_name":"keycloak","process_id":"752","connection_from":"10.0.0.250:33444","session_id":"6a5f14a7.2f0","session_line_num":"1","command_tag":"PARSE","session_start_time":"2026-07-21 06:41:43 UTC","virtual_transaction_id":"72/25","transaction_id":"0","error_severity":"ERROR","sql_state_code":"42P01","message":"relation \"migration_model\" does not exist","query":"SELECT ID, VERSION FROM MIGRATION_MODEL ORDER BY UPDATE_TIME DESC","query_pos":"25","application_name":"PostgreSQL JDBC Driver","backend_type":"client backend","query_id":"0"}} {"level":"info","ts":"2026-07-21T06:41:45.477339742Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 06:41:45.477 UTC","user_name":"keycloak","database_name":"keycloak","process_id":"755","connection_from":"10.0.0.250:33448","session_id":"6a5f14a9.2f3","session_line_num":"1","command_tag":"PARSE","session_start_time":"2026-07-21 06:41:45 UTC","virtual_transaction_id":"74/19","transaction_id":"0","error_severity":"ERROR","sql_state_code":"42P01","message":"relation \"public.databasechangeloglock\" does not exist","query":"SELECT COUNT(*) FROM public.databasechangeloglock","query_pos":"22","application_name":"PostgreSQL JDBC Driver","backend_type":"client backend","query_id":"0"}} {"level":"info","ts":"2026-07-21T06:45:56.668217615Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 06:45:56.667 UTC","process_id":"37","session_id":"6a5f144f.25","session_line_num":"5","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"checkpoint starting: time","backend_type":"checkpointer","query_id":"0"}} {"level":"info","ts":"2026-07-21T06:48:26.099304914Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 06:48:26.099 UTC","process_id":"37","session_id":"6a5f144f.25","session_line_num":"6","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"checkpoint complete: wrote 1505 buffers (9.2%), wrote 2 SLRU buffers; 0 WAL file(s) added, 0 removed, 0 recycled; write=149.347 s, sync=0.051 s, total=149.432 s; sync files=876, longest=0.001 s, average=0.001 s; distance=26842 kB, estimate=32175 kB; lsn=0/7002368, redo lsn=0/6A36B00","backend_type":"checkpointer","query_id":"0"}} {"level":"info","ts":"2026-07-21T07:20:56.830718907Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 07:20:56.830 UTC","process_id":"37","session_id":"6a5f144f.25","session_line_num":"7","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"checkpoint starting: time","backend_type":"checkpointer","query_id":"0"}} {"level":"info","ts":"2026-07-21T07:20:57.318989515Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 07:20:57.318 UTC","process_id":"37","session_id":"6a5f144f.25","session_line_num":"8","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"checkpoint complete: wrote 3 buffers (0.0%), wrote 1 SLRU buffers; 0 WAL file(s) added, 0 removed, 0 recycled; write=0.377 s, sync=0.003 s, total=0.488 s; sync files=3, longest=0.003 s, average=0.001 s; distance=22317 kB, estimate=31189 kB; lsn=0/9000060, redo lsn=0/80020B0","backend_type":"checkpointer","query_id":"0"}} {"level":"info","ts":"2026-07-21T07:25:56.402301867Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 07:25:56.402 UTC","process_id":"37","session_id":"6a5f144f.25","session_line_num":"9","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"checkpoint starting: time","backend_type":"checkpointer","query_id":"0"}} {"level":"info","ts":"2026-07-21T07:25:56.92405308Z","logger":"postgres","msg":"record","logging_pod":"keycloak-db-1","record":{"log_time":"2026-07-21 07:25:56.923 UTC","process_id":"37","session_id":"6a5f144f.25","session_line_num":"10","session_start_time":"2026-07-21 06:40:15 UTC","transaction_id":"0","error_severity":"LOG","sql_state_code":"00000","message":"checkpoint complete: wrote 4 buffers (0.0%), wrote 1 SLRU buffers; 0 WAL file(s) added, 0 removed, 0 recycled; write=0.445 s, sync=0.002 s, total=0.522 s; sync files=4, longest=0.002 s, average=0.001 s; distance=16393 kB, estimate=29710 kB; lsn=0/A000060, redo lsn=0/90045D8","backend_type":"checkpointer","query_id":"0"}} {"level":"info","ts":"2026-07-21T07:27:03.74622549Z","msg":"readiness probe using cached cluster definition due to API server connectivity issue","apiServerReachable":false,"apiServerErr":"Get \"https://10.96.0.1:443/apis/postgresql.cnpg.io/v1/namespaces/auth-system/clusters/keycloak-db\": context deadline exceeded","logging_pod":"keycloak-db-1"} {"level":"info","ts":"2026-07-21T07:27:13.7455242Z","msg":"readiness probe using cached cluster definition due to API server connectivity issue","apiServerReachable":false,"apiServerErr":"Get \"https://10.96.0.1:443/apis/postgresql.cnpg.io/v1/namespaces/auth-system/clusters/keycloak-db\": context deadline exceeded","logging_pod":"keycloak-db-1"} {"level":"info","ts":"2026-07-21T07:28:03.747164163Z","msg":"readiness probe using cached cluster definition due to API server connectivity issue","apiServerReachable":false,"apiServerErr":"Get \"https://10.96.0.1:443/apis/postgresql.cnpg.io/v1/namespaces/auth-system/clusters/keycloak-db\": context deadline exceeded","logging_pod":"keycloak-db-1"}