{"level":"warn","ts":"2026-04-08T01:16:06.084666Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T01:16:06.084781Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-08T01:16:06.084848Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T01:16:06.08486Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-04-08T01:16:06.084886Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-08T01:16:06.085727Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-04-08T01:16:06.085845Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-08T01:16:06.091314Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.149101ms"} {"level":"info","ts":"2026-04-08T01:16:06.097163Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-04-08T01:16:06.097266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T01:16:06.097382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-04-08T01:16:06.097401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T01:16:06.097412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-04-08T01:16:06.097478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-04-08T01:16:06.105112Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T01:16:06.106344Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T01:16:06.107235Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-08T01:16:06.108877Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T01:16:06.109334Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T01:16:06.109455Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T01:16:06.109564Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T01:16:06.109578Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T01:16:06.111705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-04-08T01:16:06.111998Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-04-08T01:16:06.114461Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-08T01:16:06.11471Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T01:16:06.114737Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T01:16:06.114846Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-04-08T01:16:06.114857Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-04-08T01:16:06.898126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T01:16:06.89823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T01:16:06.898278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-04-08T01:16:06.89831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-04-08T01:16:06.898329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-04-08T01:16:06.898364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-04-08T01:16:06.898386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-04-08T01:16:06.900348Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T01:16:06.900616Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T01:16:06.900747Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T01:16:06.900822Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T01:16:06.902232Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T01:16:06.902337Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T01:16:06.902998Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T01:16:06.903132Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T01:16:06.903181Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T01:16:06.903479Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T01:16:06.905471Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-04-08T01:16:08.33296Z","caller":"traceutil/trace.go:171","msg":"trace[302464216] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"114.703413ms","start":"2026-04-08T01:16:08.218218Z","end":"2026-04-08T01:16:08.332922Z","steps":["trace[302464216] 'read index received' (duration: 114.400878ms)","trace[302464216] 'applied index is now lower than readState.Index' (duration: 300.815µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:08.332982Z","caller":"traceutil/trace.go:171","msg":"trace[1703118194] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"116.877864ms","start":"2026-04-08T01:16:08.216052Z","end":"2026-04-08T01:16:08.33293Z","steps":["trace[1703118194] 'process raft request' (duration: 116.583139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:08.333314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.979638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T01:16:08.333386Z","caller":"traceutil/trace.go:171","msg":"trace[1816800406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:2; }","duration":"115.175872ms","start":"2026-04-08T01:16:08.218195Z","end":"2026-04-08T01:16:08.333371Z","steps":["trace[1816800406] 'agreement among raft nodes before linearized reading' (duration: 114.912477ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:08.473486Z","caller":"traceutil/trace.go:171","msg":"trace[407205279] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"140.371357ms","start":"2026-04-08T01:16:08.333068Z","end":"2026-04-08T01:16:08.473439Z","steps":["trace[407205279] 'read index received' (duration: 139.99474ms)","trace[407205279] 'applied index is now lower than readState.Index' (duration: 375.227µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:08.473614Z","caller":"traceutil/trace.go:171","msg":"trace[794066531] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"178.271101ms","start":"2026-04-08T01:16:08.295333Z","end":"2026-04-08T01:16:08.473604Z","steps":["trace[794066531] 'process raft request' (duration: 177.965495ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:08.473803Z","caller":"traceutil/trace.go:171","msg":"trace[141067415] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"180.158777ms","start":"2026-04-08T01:16:08.293625Z","end":"2026-04-08T01:16:08.473784Z","steps":["trace[141067415] 'process raft request' (duration: 179.419244ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:08.473788Z","caller":"traceutil/trace.go:171","msg":"trace[297628119] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"178.592528ms","start":"2026-04-08T01:16:08.295134Z","end":"2026-04-08T01:16:08.473727Z","steps":["trace[297628119] 'process raft request' (duration: 178.075909ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:08.474173Z","caller":"traceutil/trace.go:171","msg":"trace[1133669151] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"171.597975ms","start":"2026-04-08T01:16:08.302519Z","end":"2026-04-08T01:16:08.474117Z","steps":["trace[1133669151] 'process raft request' (duration: 170.851242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:08.474236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.884705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T01:16:08.474292Z","caller":"traceutil/trace.go:171","msg":"trace[1357919771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:10; }","duration":"255.963906ms","start":"2026-04-08T01:16:08.218312Z","end":"2026-04-08T01:16:08.474276Z","steps":["trace[1357919771] 'agreement among raft nodes before linearized reading' (duration: 255.798213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:08.474333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.484323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-08T01:16:08.474318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.681815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T01:16:08.4744Z","caller":"traceutil/trace.go:171","msg":"trace[1062521421] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"179.002274ms","start":"2026-04-08T01:16:08.295383Z","end":"2026-04-08T01:16:08.474386Z","steps":["trace[1062521421] 'process raft request' (duration: 177.941845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:08.474437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.475449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T01:16:08.474465Z","caller":"traceutil/trace.go:171","msg":"trace[1420293836] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:0; response_revision:10; }","duration":"183.542111ms","start":"2026-04-08T01:16:08.290914Z","end":"2026-04-08T01:16:08.474456Z","steps":["trace[1420293836] 'agreement among raft nodes before linearized reading' (duration: 183.50117ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:08.474392Z","caller":"traceutil/trace.go:171","msg":"trace[996988472] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"250.571334ms","start":"2026-04-08T01:16:08.223804Z","end":"2026-04-08T01:16:08.474375Z","steps":["trace[996988472] 'agreement among raft nodes before linearized reading' (duration: 250.448472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:08.474517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.720557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T01:16:08.474453Z","caller":"traceutil/trace.go:171","msg":"trace[1835784565] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"181.865738ms","start":"2026-04-08T01:16:08.292561Z","end":"2026-04-08T01:16:08.474427Z","steps":["trace[1835784565] 'agreement among raft nodes before linearized reading' (duration: 181.518972ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:08.474613Z","caller":"traceutil/trace.go:171","msg":"trace[1462228144] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"179.159887ms","start":"2026-04-08T01:16:08.295438Z","end":"2026-04-08T01:16:08.474598Z","steps":["trace[1462228144] 'process raft request' (duration: 177.905494ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:08.474623Z","caller":"traceutil/trace.go:171","msg":"trace[1104509451] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"129.111344ms","start":"2026-04-08T01:16:08.345487Z","end":"2026-04-08T01:16:08.474598Z","steps":["trace[1104509451] 'agreement among raft nodes before linearized reading' (duration: 128.701617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:08.474922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.60557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T01:16:08.474963Z","caller":"traceutil/trace.go:171","msg":"trace[566038371] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"106.677132ms","start":"2026-04-08T01:16:08.368276Z","end":"2026-04-08T01:16:08.474954Z","steps":["trace[566038371] 'agreement among raft nodes before linearized reading' (duration: 106.618681ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:08.474971Z","caller":"traceutil/trace.go:171","msg":"trace[723153094] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"159.388906ms","start":"2026-04-08T01:16:08.315567Z","end":"2026-04-08T01:16:08.474955Z","steps":["trace[723153094] 'process raft request' (duration: 157.835996ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:08.473502Z","caller":"traceutil/trace.go:171","msg":"trace[1476127249] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"178.183601ms","start":"2026-04-08T01:16:08.295255Z","end":"2026-04-08T01:16:08.473439Z","steps":["trace[1476127249] 'process raft request' (duration: 177.991257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:08.475199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.239568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T01:16:08.475246Z","caller":"traceutil/trace.go:171","msg":"trace[931580943] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"181.33333ms","start":"2026-04-08T01:16:08.293899Z","end":"2026-04-08T01:16:08.475232Z","steps":["trace[931580943] 'agreement among raft nodes before linearized reading' (duration: 181.217868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:08.478821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.447563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T01:16:08.478882Z","caller":"traceutil/trace.go:171","msg":"trace[337987641] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:10; }","duration":"258.550125ms","start":"2026-04-08T01:16:08.220319Z","end":"2026-04-08T01:16:08.478869Z","steps":["trace[337987641] 'agreement among raft nodes before linearized reading' (duration: 254.185183ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:42.261133Z","caller":"traceutil/trace.go:171","msg":"trace[1568003924] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"140.014924ms","start":"2026-04-08T01:16:42.121097Z","end":"2026-04-08T01:16:42.261112Z","steps":["trace[1568003924] 'process raft request' (duration: 139.859063ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:42.774661Z","caller":"traceutil/trace.go:171","msg":"trace[1147763305] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:696; }","duration":"123.736962ms","start":"2026-04-08T01:16:42.650901Z","end":"2026-04-08T01:16:42.774638Z","steps":["trace[1147763305] 'read index received' (duration: 123.51672ms)","trace[1147763305] 'applied index is now lower than readState.Index' (duration: 219.222µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:42.774784Z","caller":"traceutil/trace.go:171","msg":"trace[466687361] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"137.236991ms","start":"2026-04-08T01:16:42.637532Z","end":"2026-04-08T01:16:42.774769Z","steps":["trace[466687361] 'process raft request' (duration: 136.872017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:42.77489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.721227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-08T01:16:42.774901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.999956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-08T01:16:42.774965Z","caller":"traceutil/trace.go:171","msg":"trace[93562714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:674; }","duration":"120.834878ms","start":"2026-04-08T01:16:42.654113Z","end":"2026-04-08T01:16:42.774948Z","steps":["trace[93562714] 'agreement among raft nodes before linearized reading' (duration: 120.709626ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:42.774966Z","caller":"traceutil/trace.go:171","msg":"trace[333947557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:674; }","duration":"124.095167ms","start":"2026-04-08T01:16:42.650856Z","end":"2026-04-08T01:16:42.774952Z","steps":["trace[333947557] 'agreement among raft nodes before linearized reading' (duration: 123.968075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:42.774998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.050573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T01:16:42.775044Z","caller":"traceutil/trace.go:171","msg":"trace[522089458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:674; }","duration":"117.121075ms","start":"2026-04-08T01:16:42.657909Z","end":"2026-04-08T01:16:42.77503Z","steps":["trace[522089458] 'agreement among raft nodes before linearized reading' (duration: 116.962413ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:43.309214Z","caller":"traceutil/trace.go:171","msg":"trace[76238494] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"129.792649ms","start":"2026-04-08T01:16:43.179393Z","end":"2026-04-08T01:16:43.309186Z","steps":["trace[76238494] 'read index received' (duration: 129.622407ms)","trace[76238494] 'applied index is now lower than readState.Index' (duration: 169.292µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:43.309322Z","caller":"traceutil/trace.go:171","msg":"trace[139371866] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"452.111273ms","start":"2026-04-08T01:16:42.857179Z","end":"2026-04-08T01:16:43.309291Z","steps":["trace[139371866] 'process raft request' (duration: 451.83483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:43.309409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.999421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-08T01:16:43.30945Z","caller":"traceutil/trace.go:171","msg":"trace[2006385624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:678; }","duration":"130.098472ms","start":"2026-04-08T01:16:43.179342Z","end":"2026-04-08T01:16:43.30944Z","steps":["trace[2006385624] 'agreement among raft nodes before linearized reading' (duration: 129.946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:43.309915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:42.857167Z","time spent":"452.241415ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:17:29.161957Z","caller":"traceutil/trace.go:171","msg":"trace[1801433236] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"128.661597ms","start":"2026-04-08T01:17:29.033275Z","end":"2026-04-08T01:17:29.161936Z","steps":["trace[1801433236] 'process raft request' (duration: 122.366388ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:17:51.371397Z","caller":"traceutil/trace.go:171","msg":"trace[461203993] linearizableReadLoop","detail":"{readStateIndex:1498; appliedIndex:1497; }","duration":"118.586598ms","start":"2026-04-08T01:17:51.252775Z","end":"2026-04-08T01:17:51.371361Z","steps":["trace[461203993] 'read index received' (duration: 30.465306ms)","trace[461203993] 'applied index is now lower than readState.Index' (duration: 88.119662ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:17:51.371542Z","caller":"traceutil/trace.go:171","msg":"trace[298912101] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"120.043682ms","start":"2026-04-08T01:17:51.251466Z","end":"2026-04-08T01:17:51.37151Z","steps":["trace[298912101] 'process raft request' (duration: 31.788554ms)","trace[298912101] 'compare' (duration: 87.951028ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:17:51.371649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.846588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T01:17:51.371696Z","caller":"traceutil/trace.go:171","msg":"trace[825178910] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1453; }","duration":"118.949261ms","start":"2026-04-08T01:17:51.252734Z","end":"2026-04-08T01:17:51.371683Z","steps":["trace[825178910] 'agreement among raft nodes before linearized reading' (duration: 118.744885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:18:14.285907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.247706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:18:14.286674Z","caller":"traceutil/trace.go:171","msg":"trace[167775272] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"372.501196ms","start":"2026-04-08T01:18:13.914147Z","end":"2026-04-08T01:18:14.286648Z","steps":["trace[167775272] 'process raft request' (duration: 118.191515ms)","trace[167775272] 'compare' (duration: 253.033621ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:18:14.286799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:18:13.91411Z","time spent":"372.62164ms","remote":"127.0.0.1:39562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:18:14.286987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.882317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4f7b2654-4dac-4bc0-ba58-d9a3d6986ece\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-08T01:18:14.287112Z","caller":"traceutil/trace.go:171","msg":"trace[2062268718] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4f7b2654-4dac-4bc0-ba58-d9a3d6986ece; range_end:; response_count:1; response_revision:1862; }","duration":"194.073401ms","start":"2026-04-08T01:18:14.093022Z","end":"2026-04-08T01:18:14.287095Z","steps":["trace[2062268718] 'agreement among raft nodes before linearized reading' (duration: 193.798355ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:18:14.287029Z","caller":"traceutil/trace.go:171","msg":"trace[1132160117] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1932; }","duration":"193.463856ms","start":"2026-04-08T01:18:14.093101Z","end":"2026-04-08T01:18:14.286564Z","steps":["trace[1132160117] 'read index received' (duration: 46.671µs)","trace[1132160117] 'applied index is now lower than readState.Index' (duration: 193.415695ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:18:14.309398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.149685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-08T01:18:14.309475Z","caller":"traceutil/trace.go:171","msg":"trace[451395847] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1863; }","duration":"213.262107ms","start":"2026-04-08T01:18:14.096194Z","end":"2026-04-08T01:18:14.309456Z","steps":["trace[451395847] 'agreement among raft nodes before linearized reading' (duration: 213.019512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:18:14.593282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.304662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-08T01:18:14.593459Z","caller":"traceutil/trace.go:171","msg":"trace[1413021631] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1864; }","duration":"153.520107ms","start":"2026-04-08T01:18:14.439913Z","end":"2026-04-08T01:18:14.593433Z","steps":["trace[1413021631] 'range keys from in-memory index tree' (duration: 153.072216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:18:14.593283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.747569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T01:18:14.593794Z","caller":"traceutil/trace.go:171","msg":"trace[1233833898] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1864; }","duration":"145.312642ms","start":"2026-04-08T01:18:14.448462Z","end":"2026-04-08T01:18:14.593775Z","steps":["trace[1233833898] 'count revisions from in-memory index tree' (duration: 144.651756ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:18:14.633958Z","caller":"traceutil/trace.go:171","msg":"trace[684479059] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"121.66079ms","start":"2026-04-08T01:18:14.512273Z","end":"2026-04-08T01:18:14.633934Z","steps":["trace[684479059] 'process raft request' (duration: 121.511556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:18:30.769402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.961663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-kdw6h\" ","response":"range_response_count:1 size:6144"} {"level":"warn","ts":"2026-04-08T01:18:30.769444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.466285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:18:30.7695Z","caller":"traceutil/trace.go:171","msg":"trace[1573222233] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-kdw6h; range_end:; response_count:1; response_revision:1992; }","duration":"178.117346ms","start":"2026-04-08T01:18:30.591362Z","end":"2026-04-08T01:18:30.769479Z","steps":["trace[1573222233] 'range keys from in-memory index tree' (duration: 177.724558ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:18:30.769518Z","caller":"traceutil/trace.go:171","msg":"trace[554079014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1992; }","duration":"189.586408ms","start":"2026-04-08T01:18:30.579916Z","end":"2026-04-08T01:18:30.769502Z","steps":["trace[554079014] 'range keys from in-memory index tree' (duration: 189.339413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:18:30.76956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.668402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-08T01:18:30.76962Z","caller":"traceutil/trace.go:171","msg":"trace[414805350] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1992; }","duration":"298.782645ms","start":"2026-04-08T01:18:30.470822Z","end":"2026-04-08T01:18:30.769605Z","steps":["trace[414805350] 'range keys from in-memory index tree' (duration: 298.466758ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:18:53.837158Z","caller":"traceutil/trace.go:171","msg":"trace[2110914919] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"204.918779ms","start":"2026-04-08T01:18:53.63219Z","end":"2026-04-08T01:18:53.837109Z","steps":["trace[2110914919] 'process raft request' (duration: 149.32154ms)","trace[2110914919] 'compare' (duration: 55.443056ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:18:53.837295Z","caller":"traceutil/trace.go:171","msg":"trace[292210970] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"143.842491ms","start":"2026-04-08T01:18:53.69344Z","end":"2026-04-08T01:18:53.837283Z","steps":["trace[292210970] 'process raft request' (duration: 143.670098ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:18:53.837169Z","caller":"traceutil/trace.go:171","msg":"trace[1501273183] linearizableReadLoop","detail":"{readStateIndex:2209; appliedIndex:2208; }","duration":"152.406447ms","start":"2026-04-08T01:18:53.684744Z","end":"2026-04-08T01:18:53.83715Z","steps":["trace[1501273183] 'read index received' (duration: 96.786947ms)","trace[1501273183] 'applied index is now lower than readState.Index' (duration: 55.61876ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:18:53.837687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.942102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T01:18:53.837737Z","caller":"traceutil/trace.go:171","msg":"trace[1361654073] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2130; }","duration":"150.042144ms","start":"2026-04-08T01:18:53.687683Z","end":"2026-04-08T01:18:53.837725Z","steps":["trace[1361654073] 'agreement among raft nodes before linearized reading' (duration: 149.908452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:18:53.837844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.873115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T01:18:53.837934Z","caller":"traceutil/trace.go:171","msg":"trace[843177035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2130; }","duration":"153.219861ms","start":"2026-04-08T01:18:53.684682Z","end":"2026-04-08T01:18:53.837902Z","steps":["trace[843177035] 'agreement among raft nodes before linearized reading' (duration: 152.558399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:18:54.083556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.068183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-08T01:18:54.083684Z","caller":"traceutil/trace.go:171","msg":"trace[919591467] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2132; }","duration":"146.242407ms","start":"2026-04-08T01:18:53.937409Z","end":"2026-04-08T01:18:54.083652Z","steps":["trace[919591467] 'range keys from in-memory index tree' (duration: 145.769358ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:18:54.77265Z","caller":"traceutil/trace.go:171","msg":"trace[16302274] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"100.789129ms","start":"2026-04-08T01:18:54.671835Z","end":"2026-04-08T01:18:54.772625Z","steps":["trace[16302274] 'process raft request' (duration: 100.616276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:20:17.914081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.790677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T01:20:17.914157Z","caller":"traceutil/trace.go:171","msg":"trace[2068103318] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2691; }","duration":"144.927338ms","start":"2026-04-08T01:20:17.769212Z","end":"2026-04-08T01:20:17.914139Z","steps":["trace[2068103318] 'count revisions from in-memory index tree' (duration: 144.581804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:20:23.277709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.779993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-08T01:20:23.277799Z","caller":"traceutil/trace.go:171","msg":"trace[1519135157] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2720; }","duration":"138.992926ms","start":"2026-04-08T01:20:23.138786Z","end":"2026-04-08T01:20:23.277779Z","steps":["trace[1519135157] 'range keys from in-memory index tree' (duration: 138.60758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:20:23.278091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.122447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a43cfa246ba658\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-08T01:20:23.278211Z","caller":"traceutil/trace.go:171","msg":"trace[821811154] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a43cfa246ba658; range_end:; response_count:1; response_revision:2720; }","duration":"135.325889ms","start":"2026-04-08T01:20:23.142859Z","end":"2026-04-08T01:20:23.278185Z","steps":["trace[821811154] 'range keys from in-memory index tree' (duration: 134.922473ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:16.575552Z","caller":"traceutil/trace.go:171","msg":"trace[901135222] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"125.133314ms","start":"2026-04-08T01:24:16.450357Z","end":"2026-04-08T01:24:16.57549Z","steps":["trace[901135222] 'process raft request' (duration: 80.106656ms)","trace[901135222] 'compare' (duration: 44.475565ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:24:16.575857Z","caller":"traceutil/trace.go:171","msg":"trace[1889572131] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"122.144971ms","start":"2026-04-08T01:24:16.453695Z","end":"2026-04-08T01:24:16.57584Z","steps":["trace[1889572131] 'process raft request' (duration: 121.852674ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:16.576177Z","caller":"traceutil/trace.go:171","msg":"trace[1462500487] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"123.035113ms","start":"2026-04-08T01:24:16.45312Z","end":"2026-04-08T01:24:16.576155Z","steps":["trace[1462500487] 'process raft request' (duration: 122.192853ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:16.576333Z","caller":"traceutil/trace.go:171","msg":"trace[900900875] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"122.864799ms","start":"2026-04-08T01:24:16.453453Z","end":"2026-04-08T01:24:16.576318Z","steps":["trace[900900875] 'process raft request' (duration: 122.048139ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:16.576274Z","caller":"traceutil/trace.go:171","msg":"trace[1781232637] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"123.085254ms","start":"2026-04-08T01:24:16.453156Z","end":"2026-04-08T01:24:16.576241Z","steps":["trace[1781232637] 'process raft request' (duration: 122.283915ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:26:06.930005Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2952} {"level":"info","ts":"2026-04-08T01:26:07.053067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2952,"took":"121.072767ms","hash":3879075987,"current-db-size-bytes":54349824,"current-db-size":"54 MB","current-db-size-in-use-bytes":29413376,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-08T01:26:07.053141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3879075987,"revision":2952,"compact-revision":-1} {"level":"info","ts":"2026-04-08T01:31:06.934733Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5666} {"level":"info","ts":"2026-04-08T01:31:07.029009Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5666,"took":"92.759813ms","hash":2754783445,"current-db-size-bytes":54349824,"current-db-size":"54 MB","current-db-size-in-use-bytes":18300928,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-08T01:31:07.029068Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2754783445,"revision":5666,"compact-revision":2952}