{"level":"warn","ts":"2026-09-17T19:39:07.274439Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-17T19:39:07.274567Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.181: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://162.253.55.181:2380","--initial-cluster=instance=https://162.253.55.181:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.181:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.181: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-09-17T19:39:07.274656Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-17T19:39:07.274666Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.181:2380"]} {"level":"info","ts":"2026-09-17T19:39:07.274693Z","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-09-17T19:39:07.275593Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.181:2379"]} {"level":"info","ts":"2026-09-17T19:39:07.275686Z","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":16,"max-cpu-available":16,"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://162.253.55.181:2380"],"listen-peer-urls":["https://162.253.55.181:2380"],"advertise-client-urls":["https://162.253.55.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.181: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-09-17T19:39:07.280664Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.436225ms"} {"level":"info","ts":"2026-09-17T19:39:07.287957Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"865c2c1f02504fb9","cluster-id":"c3b098aaa499ad51"} {"level":"info","ts":"2026-09-17T19:39:07.288139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 switched to configuration voters=()"} {"level":"info","ts":"2026-09-17T19:39:07.288217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 became follower at term 0"} {"level":"info","ts":"2026-09-17T19:39:07.28827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 865c2c1f02504fb9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-17T19:39:07.288287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 became follower at term 1"} {"level":"info","ts":"2026-09-17T19:39:07.288374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 switched to configuration voters=(9681661810634149817)"} {"level":"warn","ts":"2026-09-17T19:39:07.297052Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-17T19:39:07.298907Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-17T19:39:07.300559Z","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-09-17T19:39:07.303122Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"865c2c1f02504fb9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-17T19:39:07.303488Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"865c2c1f02504fb9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-17T19:39:07.303496Z","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-09-17T19:39:07.304014Z","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-09-17T19:39:07.304026Z","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-09-17T19:39:07.30634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 switched to configuration voters=(9681661810634149817)"} {"level":"info","ts":"2026-09-17T19:39:07.306812Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c3b098aaa499ad51","local-member-id":"865c2c1f02504fb9","added-peer-id":"865c2c1f02504fb9","added-peer-peer-urls":["https://162.253.55.181:2380"]} {"level":"info","ts":"2026-09-17T19:39:07.309369Z","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-09-17T19:39:07.30977Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"865c2c1f02504fb9","initial-advertise-peer-urls":["https://162.253.55.181:2380"],"listen-peer-urls":["https://162.253.55.181:2380"],"advertise-client-urls":["https://162.253.55.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-17T19:39:07.30974Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.181:2380"} {"level":"info","ts":"2026-09-17T19:39:07.309832Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-17T19:39:07.309887Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.181:2380"} {"level":"info","ts":"2026-09-17T19:39:08.089547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 is starting a new election at term 1"} {"level":"info","ts":"2026-09-17T19:39:08.089612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-17T19:39:08.089642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 received MsgPreVoteResp from 865c2c1f02504fb9 at term 1"} {"level":"info","ts":"2026-09-17T19:39:08.089658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 became candidate at term 2"} {"level":"info","ts":"2026-09-17T19:39:08.089665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 received MsgVoteResp from 865c2c1f02504fb9 at term 2"} {"level":"info","ts":"2026-09-17T19:39:08.089677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 became leader at term 2"} {"level":"info","ts":"2026-09-17T19:39:08.089686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 865c2c1f02504fb9 elected leader 865c2c1f02504fb9 at term 2"} {"level":"info","ts":"2026-09-17T19:39:08.091023Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"865c2c1f02504fb9","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.181:2379]}","request-path":"/0/members/865c2c1f02504fb9/attributes","cluster-id":"c3b098aaa499ad51","publish-timeout":"7s"} {"level":"info","ts":"2026-09-17T19:39:08.09106Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-17T19:39:08.091079Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-17T19:39:08.091216Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T19:39:08.091461Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-17T19:39:08.091528Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-17T19:39:08.092301Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c3b098aaa499ad51","local-member-id":"865c2c1f02504fb9","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T19:39:08.092382Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T19:39:08.092406Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T19:39:08.093184Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.181:2379"} {"level":"info","ts":"2026-09-17T19:39:08.095213Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-17T19:39:47.508943Z","caller":"traceutil/trace.go:171","msg":"trace[250035123] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"113.616002ms","start":"2026-09-17T19:39:47.395299Z","end":"2026-09-17T19:39:47.508915Z","steps":["trace[250035123] 'process raft request' (duration: 113.422506ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:39:54.047556Z","caller":"traceutil/trace.go:171","msg":"trace[156071254] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"141.710972ms","start":"2026-09-17T19:39:53.905776Z","end":"2026-09-17T19:39:54.047487Z","steps":["trace[156071254] 'read index received' (duration: 134.940297ms)","trace[156071254] 'applied index is now lower than readState.Index' (duration: 6.769515ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:39:54.04763Z","caller":"traceutil/trace.go:171","msg":"trace[1954972695] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"164.540291ms","start":"2026-09-17T19:39:53.883028Z","end":"2026-09-17T19:39:54.047568Z","steps":["trace[1954972695] 'process raft request' (duration: 161.922052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:39:54.048252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.31237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-17T19:39:54.048561Z","caller":"traceutil/trace.go:171","msg":"trace[753837133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"142.808444ms","start":"2026-09-17T19:39:53.90571Z","end":"2026-09-17T19:39:54.048519Z","steps":["trace[753837133] 'agreement among raft nodes before linearized reading' (duration: 141.978929ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:39:54.37894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.292748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:39:54.37908Z","caller":"traceutil/trace.go:171","msg":"trace[953975919] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"252.097606ms","start":"2026-09-17T19:39:54.126962Z","end":"2026-09-17T19:39:54.37906Z","steps":["trace[953975919] 'process raft request' (duration: 18.235851ms)","trace[953975919] 'compare' (duration: 233.140564ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:39:54.384713Z","caller":"traceutil/trace.go:171","msg":"trace[1369034160] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"231.655829ms","start":"2026-09-17T19:39:54.153033Z","end":"2026-09-17T19:39:54.384689Z","steps":["trace[1369034160] 'process raft request' (duration: 231.459623ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:39:54.38493Z","caller":"traceutil/trace.go:171","msg":"trace[2129372874] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"221.220493ms","start":"2026-09-17T19:39:54.163428Z","end":"2026-09-17T19:39:54.384648Z","steps":["trace[2129372874] 'read index received' (duration: 220.928625ms)","trace[2129372874] 'applied index is now lower than readState.Index' (duration: 290.788µs)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:39:54.38511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.656936ms","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-09-17T19:39:54.385145Z","caller":"traceutil/trace.go:171","msg":"trace[1109037289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"221.75958ms","start":"2026-09-17T19:39:54.163374Z","end":"2026-09-17T19:39:54.385133Z","steps":["trace[1109037289] 'agreement among raft nodes before linearized reading' (duration: 221.606835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:39:54.385716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.056396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w2tst\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-17T19:39:54.385759Z","caller":"traceutil/trace.go:171","msg":"trace[1202915015] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w2tst; range_end:; response_count:1; response_revision:598; }","duration":"116.101518ms","start":"2026-09-17T19:39:54.269647Z","end":"2026-09-17T19:39:54.385748Z","steps":["trace[1202915015] 'agreement among raft nodes before linearized reading' (duration: 115.988134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:39:54.386216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.326538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-17T19:39:54.386261Z","caller":"traceutil/trace.go:171","msg":"trace[2050304384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"133.398369ms","start":"2026-09-17T19:39:54.25285Z","end":"2026-09-17T19:39:54.386249Z","steps":["trace[2050304384] 'agreement among raft nodes before linearized reading' (duration: 132.74023ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:39:54.528771Z","caller":"traceutil/trace.go:171","msg":"trace[1134007291] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"138.973668ms","start":"2026-09-17T19:39:54.389755Z","end":"2026-09-17T19:39:54.528729Z","steps":["trace[1134007291] 'process raft request' (duration: 92.632698ms)","trace[1134007291] 'compare' (duration: 46.067302ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:39:54.529077Z","caller":"traceutil/trace.go:171","msg":"trace[1927727518] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"139.267397ms","start":"2026-09-17T19:39:54.389772Z","end":"2026-09-17T19:39:54.529039Z","steps":["trace[1927727518] 'process raft request' (duration: 138.893186ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:39:54.529247Z","caller":"traceutil/trace.go:171","msg":"trace[480092978] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"121.628645ms","start":"2026-09-17T19:39:54.407605Z","end":"2026-09-17T19:39:54.529233Z","steps":["trace[480092978] 'process raft request' (duration: 121.383568ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:39:54.529288Z","caller":"traceutil/trace.go:171","msg":"trace[1621488514] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:621; }","duration":"138.804554ms","start":"2026-09-17T19:39:54.390381Z","end":"2026-09-17T19:39:54.529186Z","steps":["trace[1621488514] 'read index received' (duration: 91.935898ms)","trace[1621488514] 'applied index is now lower than readState.Index' (duration: 46.867366ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:39:54.529743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.289269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:39:54.529917Z","caller":"traceutil/trace.go:171","msg":"trace[1135794961] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:601; }","duration":"139.548696ms","start":"2026-09-17T19:39:54.390338Z","end":"2026-09-17T19:39:54.529887Z","steps":["trace[1135794961] 'agreement among raft nodes before linearized reading' (duration: 138.989949ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:39:55.459549Z","caller":"traceutil/trace.go:171","msg":"trace[151907134] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"534.396596ms","start":"2026-09-17T19:39:54.925102Z","end":"2026-09-17T19:39:55.459499Z","steps":["trace[151907134] 'process raft request' (duration: 533.688044ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:39:55.460038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:39:54.925059Z","time spent":"534.608232ms","remote":"127.0.0.1:45656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:40:13.381634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.359634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-17T19:40:13.381754Z","caller":"traceutil/trace.go:171","msg":"trace[705741994] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:771; }","duration":"299.551461ms","start":"2026-09-17T19:40:13.08218Z","end":"2026-09-17T19:40:13.381731Z","steps":["trace[705741994] 'range keys from in-memory index tree' (duration: 299.109717ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:41:08.144732Z","caller":"traceutil/trace.go:171","msg":"trace[1719773817] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"267.833022ms","start":"2026-09-17T19:41:07.876865Z","end":"2026-09-17T19:41:08.144698Z","steps":["trace[1719773817] 'process raft request' (duration: 267.622245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:41:08.364956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.38239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:41:08.365141Z","caller":"traceutil/trace.go:171","msg":"trace[1047224767] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"215.312463ms","start":"2026-09-17T19:41:08.149765Z","end":"2026-09-17T19:41:08.365077Z","steps":["trace[1047224767] 'process raft request' (duration: 89.674776ms)","trace[1047224767] 'compare' (duration: 125.225854ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:41:35.370301Z","caller":"traceutil/trace.go:171","msg":"trace[449685832] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"223.276262ms","start":"2026-09-17T19:41:35.147002Z","end":"2026-09-17T19:41:35.370278Z","steps":["trace[449685832] 'process raft request' (duration: 223.049135ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:41:35.37029Z","caller":"traceutil/trace.go:171","msg":"trace[19910001] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"179.000741ms","start":"2026-09-17T19:41:35.19126Z","end":"2026-09-17T19:41:35.37026Z","steps":["trace[19910001] 'read index received' (duration: 178.906329ms)","trace[19910001] 'applied index is now lower than readState.Index' (duration: 93.162µs)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:41:35.370511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.234598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-17T19:41:35.370681Z","caller":"traceutil/trace.go:171","msg":"trace[1348525736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1867; }","duration":"179.467715ms","start":"2026-09-17T19:41:35.191196Z","end":"2026-09-17T19:41:35.370663Z","steps":["trace[1348525736] 'agreement among raft nodes before linearized reading' (duration: 179.156786ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:41:35.61992Z","caller":"traceutil/trace.go:171","msg":"trace[168609346] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"262.019236ms","start":"2026-09-17T19:41:35.357869Z","end":"2026-09-17T19:41:35.619888Z","steps":["trace[168609346] 'process raft request' (duration: 261.944024ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:41:35.620358Z","caller":"traceutil/trace.go:171","msg":"trace[721545966] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"269.468701ms","start":"2026-09-17T19:41:35.350822Z","end":"2026-09-17T19:41:35.62029Z","steps":["trace[721545966] 'process raft request' (duration: 262.197302ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:41:35.625878Z","caller":"traceutil/trace.go:171","msg":"trace[667911585] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"250.445629ms","start":"2026-09-17T19:41:35.375387Z","end":"2026-09-17T19:41:35.625833Z","steps":["trace[667911585] 'process raft request' (duration: 250.261803ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:41:36.121715Z","caller":"traceutil/trace.go:171","msg":"trace[923680618] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"225.045935ms","start":"2026-09-17T19:41:35.896641Z","end":"2026-09-17T19:41:36.121687Z","steps":["trace[923680618] 'process raft request' (duration: 224.889831ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:41:38.03282Z","caller":"traceutil/trace.go:171","msg":"trace[213131127] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"115.462772ms","start":"2026-09-17T19:41:37.917322Z","end":"2026-09-17T19:41:38.032785Z","steps":["trace[213131127] 'process raft request' (duration: 115.09442ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:41:41.013955Z","caller":"traceutil/trace.go:171","msg":"trace[60624360] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"151.608869ms","start":"2026-09-17T19:41:40.862322Z","end":"2026-09-17T19:41:41.013931Z","steps":["trace[60624360] 'process raft request' (duration: 60.784428ms)","trace[60624360] 'compare' (duration: 90.618935ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:41:41.014104Z","caller":"traceutil/trace.go:171","msg":"trace[615062741] linearizableReadLoop","detail":"{readStateIndex:1986; appliedIndex:1985; }","duration":"149.166225ms","start":"2026-09-17T19:41:40.864868Z","end":"2026-09-17T19:41:41.014034Z","steps":["trace[615062741] 'read index received' (duration: 58.098857ms)","trace[615062741] 'applied index is now lower than readState.Index' (duration: 91.064428ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:41:41.014551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.671659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-09-17T19:41:41.014598Z","caller":"traceutil/trace.go:171","msg":"trace[843449434] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1915; }","duration":"149.762772ms","start":"2026-09-17T19:41:40.864822Z","end":"2026-09-17T19:41:41.014585Z","steps":["trace[843449434] 'agreement among raft nodes before linearized reading' (duration: 149.378391ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:41:45.648667Z","caller":"traceutil/trace.go:171","msg":"trace[1624852934] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"115.535004ms","start":"2026-09-17T19:41:45.533097Z","end":"2026-09-17T19:41:45.648632Z","steps":["trace[1624852934] 'process raft request' (duration: 115.308027ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:41:50.528601Z","caller":"traceutil/trace.go:171","msg":"trace[1234928709] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"301.72714ms","start":"2026-09-17T19:41:50.226841Z","end":"2026-09-17T19:41:50.528568Z","steps":["trace[1234928709] 'process raft request' (duration: 301.505144ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:41:50.52881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:41:50.226809Z","time spent":"301.862524ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:42:22.140093Z","caller":"traceutil/trace.go:171","msg":"trace[1848995291] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2252; }","duration":"128.142943ms","start":"2026-09-17T19:42:22.011869Z","end":"2026-09-17T19:42:22.140012Z","steps":["trace[1848995291] 'read index received' (duration: 103.304925ms)","trace[1848995291] 'applied index is now lower than readState.Index' (duration: 24.836298ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:42:22.140152Z","caller":"traceutil/trace.go:171","msg":"trace[223269787] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"205.360631ms","start":"2026-09-17T19:42:21.934717Z","end":"2026-09-17T19:42:22.140077Z","steps":["trace[223269787] 'process raft request' (duration: 180.600325ms)","trace[223269787] 'compare' (duration: 24.393915ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:42:22.140359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.472704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-17T19:42:22.14046Z","caller":"traceutil/trace.go:171","msg":"trace[872902184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2171; }","duration":"128.644138ms","start":"2026-09-17T19:42:22.011793Z","end":"2026-09-17T19:42:22.140438Z","steps":["trace[872902184] 'agreement among raft nodes before linearized reading' (duration: 128.35157ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:42:22.443211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.738242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:42:22.443461Z","caller":"traceutil/trace.go:171","msg":"trace[2045283371] linearizableReadLoop","detail":"{readStateIndex:2254; appliedIndex:2253; }","duration":"264.133473ms","start":"2026-09-17T19:42:22.179292Z","end":"2026-09-17T19:42:22.443426Z","steps":["trace[2045283371] 'read index received' (duration: 61.066611ms)","trace[2045283371] 'applied index is now lower than readState.Index' (duration: 203.065562ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:42:22.443454Z","caller":"traceutil/trace.go:171","msg":"trace[711456414] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"294.964693ms","start":"2026-09-17T19:42:22.148463Z","end":"2026-09-17T19:42:22.443427Z","steps":["trace[711456414] 'process raft request' (duration: 91.913072ms)","trace[711456414] 'compare' (duration: 202.501855ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:42:22.443687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.377211ms","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-09-17T19:42:22.443801Z","caller":"traceutil/trace.go:171","msg":"trace[334287064] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2172; }","duration":"264.540105ms","start":"2026-09-17T19:42:22.179231Z","end":"2026-09-17T19:42:22.443771Z","steps":["trace[334287064] 'agreement among raft nodes before linearized reading' (duration: 264.277517ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:42:28.121832Z","caller":"traceutil/trace.go:171","msg":"trace[1646044229] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"174.841371ms","start":"2026-09-17T19:42:27.946951Z","end":"2026-09-17T19:42:28.121792Z","steps":["trace[1646044229] 'process raft request' (duration: 174.640905ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:42:28.134255Z","caller":"traceutil/trace.go:171","msg":"trace[386817459] linearizableReadLoop","detail":"{readStateIndex:2285; appliedIndex:2283; }","duration":"103.212482ms","start":"2026-09-17T19:42:28.031012Z","end":"2026-09-17T19:42:28.134225Z","steps":["trace[386817459] 'read index received' (duration: 90.55855ms)","trace[386817459] 'applied index is now lower than readState.Index' (duration: 12.652212ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:42:28.134557Z","caller":"traceutil/trace.go:171","msg":"trace[268620516] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"185.589545ms","start":"2026-09-17T19:42:27.94892Z","end":"2026-09-17T19:42:28.134509Z","steps":["trace[268620516] 'process raft request' (duration: 185.175642ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:42:28.134663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.573642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18d6332203895f9a\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-09-17T19:42:28.134733Z","caller":"traceutil/trace.go:171","msg":"trace[1813346586] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18d6332203895f9a; range_end:; response_count:1; response_revision:2202; }","duration":"103.760708ms","start":"2026-09-17T19:42:28.030945Z","end":"2026-09-17T19:42:28.134706Z","steps":["trace[1813346586] 'agreement among raft nodes before linearized reading' (duration: 103.394257ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:42:28.887482Z","caller":"traceutil/trace.go:171","msg":"trace[833585626] linearizableReadLoop","detail":"{readStateIndex:2288; appliedIndex:2287; }","duration":"366.605582ms","start":"2026-09-17T19:42:28.52085Z","end":"2026-09-17T19:42:28.887456Z","steps":["trace[833585626] 'read index received' (duration: 366.510818ms)","trace[833585626] 'applied index is now lower than readState.Index' (duration: 93.644µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:42:28.887642Z","caller":"traceutil/trace.go:171","msg":"trace[860239413] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"367.643984ms","start":"2026-09-17T19:42:28.519969Z","end":"2026-09-17T19:42:28.887613Z","steps":["trace[860239413] 'process raft request' (duration: 367.307943ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:42:28.887809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.947922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-09-17T19:42:28.887857Z","caller":"traceutil/trace.go:171","msg":"trace[1877113203] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2205; }","duration":"367.066315ms","start":"2026-09-17T19:42:28.520778Z","end":"2026-09-17T19:42:28.887844Z","steps":["trace[1877113203] 'agreement among raft nodes before linearized reading' (duration: 366.844109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:42:28.887888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:42:28.520751Z","time spent":"367.129508ms","remote":"127.0.0.1:45752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12407,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-17T19:42:28.887888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:42:28.519935Z","time spent":"367.785888ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:42:28.984507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.339647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:42:28.984573Z","caller":"traceutil/trace.go:171","msg":"trace[1796760318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2206; }","duration":"254.461761ms","start":"2026-09-17T19:42:28.730097Z","end":"2026-09-17T19:42:28.984559Z","steps":["trace[1796760318] 'agreement among raft nodes before linearized reading' (duration: 254.345317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:42:28.984549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.01791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-17T19:42:28.984646Z","caller":"traceutil/trace.go:171","msg":"trace[266777872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2206; }","duration":"200.144864ms","start":"2026-09-17T19:42:28.784478Z","end":"2026-09-17T19:42:28.984623Z","steps":["trace[266777872] 'agreement among raft nodes before linearized reading' (duration: 199.907557ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:42:28.984559Z","caller":"traceutil/trace.go:171","msg":"trace[1783858050] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"294.455386ms","start":"2026-09-17T19:42:28.690078Z","end":"2026-09-17T19:42:28.984534Z","steps":["trace[1783858050] 'process raft request' (duration: 294.116146ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:42:28.984833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.578465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-17T19:42:28.984862Z","caller":"traceutil/trace.go:171","msg":"trace[486359187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2206; }","duration":"117.670627ms","start":"2026-09-17T19:42:28.867183Z","end":"2026-09-17T19:42:28.984853Z","steps":["trace[486359187] 'agreement among raft nodes before linearized reading' (duration: 117.197113ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:42:34.255315Z","caller":"traceutil/trace.go:171","msg":"trace[1703894416] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"110.45254ms","start":"2026-09-17T19:42:34.14483Z","end":"2026-09-17T19:42:34.255282Z","steps":["trace[1703894416] 'process raft request' (duration: 109.945145ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:42:34.448228Z","caller":"traceutil/trace.go:171","msg":"trace[133810744] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"230.992513ms","start":"2026-09-17T19:42:34.21718Z","end":"2026-09-17T19:42:34.448173Z","steps":["trace[133810744] 'process raft request' (duration: 147.061723ms)","trace[133810744] 'compare' (duration: 83.190848ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:42:34.448287Z","caller":"traceutil/trace.go:171","msg":"trace[83862509] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"188.286035ms","start":"2026-09-17T19:42:34.259964Z","end":"2026-09-17T19:42:34.44825Z","steps":["trace[83862509] 'process raft request' (duration: 188.10025ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:43:34.816245Z","caller":"traceutil/trace.go:171","msg":"trace[1798437159] linearizableReadLoop","detail":"{readStateIndex:2832; appliedIndex:2831; }","duration":"238.257066ms","start":"2026-09-17T19:43:34.577953Z","end":"2026-09-17T19:43:34.81621Z","steps":["trace[1798437159] 'read index received' (duration: 238.094492ms)","trace[1798437159] 'applied index is now lower than readState.Index' (duration: 161.094µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:43:34.816274Z","caller":"traceutil/trace.go:171","msg":"trace[1446664384] transaction","detail":"{read_only:false; response_revision:2727; number_of_response:1; }","duration":"271.457163ms","start":"2026-09-17T19:43:34.544788Z","end":"2026-09-17T19:43:34.816246Z","steps":["trace[1446664384] 'process raft request' (duration: 271.241306ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:43:34.816476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.512695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-17T19:43:34.816523Z","caller":"traceutil/trace.go:171","msg":"trace[733710406] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2727; }","duration":"238.619427ms","start":"2026-09-17T19:43:34.577893Z","end":"2026-09-17T19:43:34.816513Z","steps":["trace[733710406] 'agreement among raft nodes before linearized reading' (duration: 238.431522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:43:34.850959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.866466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:43:34.851069Z","caller":"traceutil/trace.go:171","msg":"trace[1665065105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2728; }","duration":"121.02528ms","start":"2026-09-17T19:43:34.730024Z","end":"2026-09-17T19:43:34.85105Z","steps":["trace[1665065105] 'agreement among raft nodes before linearized reading' (duration: 120.869066ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:44:05.205939Z","caller":"traceutil/trace.go:171","msg":"trace[421646913] transaction","detail":"{read_only:false; response_revision:2909; number_of_response:1; }","duration":"230.495908ms","start":"2026-09-17T19:44:04.97541Z","end":"2026-09-17T19:44:05.205906Z","steps":["trace[421646913] 'process raft request' (duration: 230.322172ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:44:05.246518Z","caller":"traceutil/trace.go:171","msg":"trace[330630988] transaction","detail":"{read_only:false; response_revision:2910; number_of_response:1; }","duration":"266.286704ms","start":"2026-09-17T19:44:04.980199Z","end":"2026-09-17T19:44:05.246485Z","steps":["trace[330630988] 'process raft request' (duration: 261.766887ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:44:05.246734Z","caller":"traceutil/trace.go:171","msg":"trace[1430544602] linearizableReadLoop","detail":"{readStateIndex:3022; appliedIndex:3020; }","duration":"237.696065ms","start":"2026-09-17T19:44:05.009002Z","end":"2026-09-17T19:44:05.246698Z","steps":["trace[1430544602] 'read index received' (duration: 196.917896ms)","trace[1430544602] 'applied index is now lower than readState.Index' (duration: 40.775839ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:44:05.246913Z","caller":"traceutil/trace.go:171","msg":"trace[28360238] transaction","detail":"{read_only:false; response_revision:2911; number_of_response:1; }","duration":"251.583027ms","start":"2026-09-17T19:44:04.995314Z","end":"2026-09-17T19:44:05.246897Z","steps":["trace[28360238] 'process raft request' (duration: 251.108653ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:44:05.247039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.004365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-17T19:44:05.247095Z","caller":"traceutil/trace.go:171","msg":"trace[1673242303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2911; }","duration":"238.142839ms","start":"2026-09-17T19:44:05.008934Z","end":"2026-09-17T19:44:05.247077Z","steps":["trace[1673242303] 'agreement among raft nodes before linearized reading' (duration: 237.875411ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:44:11.533695Z","caller":"traceutil/trace.go:171","msg":"trace[2071900399] transaction","detail":"{read_only:false; response_revision:2945; number_of_response:1; }","duration":"658.203539ms","start":"2026-09-17T19:44:10.875461Z","end":"2026-09-17T19:44:11.533665Z","steps":["trace[2071900399] 'process raft request' (duration: 658.024913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:44:11.533882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:44:10.875436Z","time spent":"658.354044ms","remote":"127.0.0.1:45636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:44:11.53389Z","caller":"traceutil/trace.go:171","msg":"trace[2000647111] linearizableReadLoop","detail":"{readStateIndex:3057; appliedIndex:3057; }","duration":"285.062226ms","start":"2026-09-17T19:44:11.248793Z","end":"2026-09-17T19:44:11.533856Z","steps":["trace[2000647111] 'read index received' (duration: 285.050486ms)","trace[2000647111] 'applied index is now lower than readState.Index' (duration: 9.74µs)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:44:11.5342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.355675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-17T19:44:11.534284Z","caller":"traceutil/trace.go:171","msg":"trace[1236253258] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2945; }","duration":"285.510099ms","start":"2026-09-17T19:44:11.248751Z","end":"2026-09-17T19:44:11.534262Z","steps":["trace[1236253258] 'agreement among raft nodes before linearized reading' (duration: 285.21092ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:44:11.572378Z","caller":"traceutil/trace.go:171","msg":"trace[1803438979] transaction","detail":"{read_only:false; response_revision:2946; number_of_response:1; }","duration":"297.450202ms","start":"2026-09-17T19:44:11.274895Z","end":"2026-09-17T19:44:11.572346Z","steps":["trace[1803438979] 'process raft request' (duration: 277.688371ms)","trace[1803438979] 'compare' (duration: 19.592476ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:44:11.57267Z","caller":"traceutil/trace.go:171","msg":"trace[453965017] transaction","detail":"{read_only:false; response_revision:2947; number_of_response:1; }","duration":"297.632356ms","start":"2026-09-17T19:44:11.275009Z","end":"2026-09-17T19:44:11.572641Z","steps":["trace[453965017] 'process raft request' (duration: 297.300687ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:44:11.573151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.7796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-17T19:44:11.573251Z","caller":"traceutil/trace.go:171","msg":"trace[1036037643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2948; }","duration":"291.929945ms","start":"2026-09-17T19:44:11.281297Z","end":"2026-09-17T19:44:11.573227Z","steps":["trace[1036037643] 'agreement among raft nodes before linearized reading' (duration: 291.592424ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:47:25.11117Z","caller":"traceutil/trace.go:171","msg":"trace[1441009182] linearizableReadLoop","detail":"{readStateIndex:4324; appliedIndex:4323; }","duration":"165.422461ms","start":"2026-09-17T19:47:24.945714Z","end":"2026-09-17T19:47:25.111136Z","steps":["trace[1441009182] 'read index received' (duration: 163.764581ms)","trace[1441009182] 'applied index is now lower than readState.Index' (duration: 1.65593ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:47:25.111192Z","caller":"traceutil/trace.go:171","msg":"trace[1500695355] transaction","detail":"{read_only:false; response_revision:4165; number_of_response:1; }","duration":"314.574056ms","start":"2026-09-17T19:47:24.796569Z","end":"2026-09-17T19:47:25.111143Z","steps":["trace[1500695355] 'process raft request' (duration: 313.007779ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:47:25.111461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.72377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-09-17T19:47:25.111515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:47:24.796542Z","time spent":"314.852565ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:47:25.111565Z","caller":"traceutil/trace.go:171","msg":"trace[825959546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4165; }","duration":"165.903056ms","start":"2026-09-17T19:47:24.945634Z","end":"2026-09-17T19:47:25.111537Z","steps":["trace[825959546] 'agreement among raft nodes before linearized reading' (duration: 165.581245ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:47:30.579177Z","caller":"traceutil/trace.go:171","msg":"trace[1960674806] transaction","detail":"{read_only:false; response_revision:4190; number_of_response:1; }","duration":"171.545727ms","start":"2026-09-17T19:47:30.407576Z","end":"2026-09-17T19:47:30.579121Z","steps":["trace[1960674806] 'process raft request' (duration: 86.227993ms)","trace[1960674806] 'compare' (duration: 84.975853ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:47:30.761346Z","caller":"traceutil/trace.go:171","msg":"trace[527560434] transaction","detail":"{read_only:false; response_revision:4191; number_of_response:1; }","duration":"352.385827ms","start":"2026-09-17T19:47:30.408881Z","end":"2026-09-17T19:47:30.761267Z","steps":["trace[527560434] 'process raft request' (duration: 348.337814ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:47:30.761508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:47:30.408822Z","time spent":"352.600093ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:47:30.761619Z","caller":"traceutil/trace.go:171","msg":"trace[1171784743] transaction","detail":"{read_only:false; response_revision:4192; number_of_response:1; }","duration":"350.798979ms","start":"2026-09-17T19:47:30.410787Z","end":"2026-09-17T19:47:30.761586Z","steps":["trace[1171784743] 'process raft request' (duration: 350.422248ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:47:30.761707Z","caller":"traceutil/trace.go:171","msg":"trace[534645492] transaction","detail":"{read_only:false; response_revision:4193; number_of_response:1; }","duration":"167.092281ms","start":"2026-09-17T19:47:30.594578Z","end":"2026-09-17T19:47:30.761671Z","steps":["trace[534645492] 'process raft request' (duration: 166.873794ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:47:30.761851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:47:30.410775Z","time spent":"350.935153ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:49:07.713944Z","caller":"traceutil/trace.go:171","msg":"trace[1553469379] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"183.380666ms","start":"2026-09-17T19:49:07.530539Z","end":"2026-09-17T19:49:07.71392Z","steps":["trace[1553469379] 'process raft request' (duration: 108.056746ms)","trace[1553469379] 'compare' (duration: 75.197107ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:49:07.717499Z","caller":"traceutil/trace.go:171","msg":"trace[1911540837] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"185.969046ms","start":"2026-09-17T19:49:07.531491Z","end":"2026-09-17T19:49:07.71746Z","steps":["trace[1911540837] 'process raft request' (duration: 185.735969ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:49:07.717973Z","caller":"traceutil/trace.go:171","msg":"trace[1215543187] linearizableReadLoop","detail":"{readStateIndex:5477; appliedIndex:5475; }","duration":"186.399879ms","start":"2026-09-17T19:49:07.531562Z","end":"2026-09-17T19:49:07.717962Z","steps":["trace[1215543187] 'read index received' (duration: 107.050835ms)","trace[1215543187] 'applied index is now lower than readState.Index' (duration: 79.348124ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:49:07.71819Z","caller":"traceutil/trace.go:171","msg":"trace[1414768607] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"182.50522ms","start":"2026-09-17T19:49:07.535671Z","end":"2026-09-17T19:49:07.718176Z","steps":["trace[1414768607] 'process raft request' (duration: 181.725687ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:49:07.718275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.694937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-17T19:49:07.718325Z","caller":"traceutil/trace.go:171","msg":"trace[746065924] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5277; }","duration":"186.79169ms","start":"2026-09-17T19:49:07.531523Z","end":"2026-09-17T19:49:07.718314Z","steps":["trace[746065924] 'agreement among raft nodes before linearized reading' (duration: 186.621015ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:49:08.120599Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2934} {"level":"info","ts":"2026-09-17T19:49:08.2166Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2934,"took":"94.121272ms","hash":1267573428,"current-db-size-bytes":52359168,"current-db-size":"52 MB","current-db-size-in-use-bytes":28516352,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-17T19:49:08.216701Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1267573428,"revision":2934,"compact-revision":-1} {"level":"info","ts":"2026-09-17T19:49:08.819383Z","caller":"traceutil/trace.go:171","msg":"trace[1985351195] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5308; }","duration":"163.07848ms","start":"2026-09-17T19:49:08.65628Z","end":"2026-09-17T19:49:08.819359Z","steps":["trace[1985351195] 'process raft request' (duration: 162.978907ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:49:10.73763Z","caller":"traceutil/trace.go:171","msg":"trace[1905327596] linearizableReadLoop","detail":"{readStateIndex:5633; appliedIndex:5632; }","duration":"260.136381ms","start":"2026-09-17T19:49:10.477463Z","end":"2026-09-17T19:49:10.737599Z","steps":["trace[1905327596] 'read index received' (duration: 245.614729ms)","trace[1905327596] 'applied index is now lower than readState.Index' (duration: 14.520452ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:49:10.73775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.275846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:49:10.737715Z","caller":"traceutil/trace.go:171","msg":"trace[1173442707] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"319.083824ms","start":"2026-09-17T19:49:10.418591Z","end":"2026-09-17T19:49:10.737675Z","steps":["trace[1173442707] 'process raft request' (duration: 304.608094ms)","trace[1173442707] 'compare' (duration: 13.960054ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:49:10.737781Z","caller":"traceutil/trace.go:171","msg":"trace[1129407918] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:5426; }","duration":"260.386879ms","start":"2026-09-17T19:49:10.477381Z","end":"2026-09-17T19:49:10.737768Z","steps":["trace[1129407918] 'agreement among raft nodes before linearized reading' (duration: 260.306537ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:49:10.737946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:49:10.418578Z","time spent":"319.227378ms","remote":"127.0.0.1:45752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3706,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:49:13.187324Z","caller":"traceutil/trace.go:171","msg":"trace[903259841] transaction","detail":"{read_only:false; response_revision:5466; number_of_response:1; }","duration":"144.605558ms","start":"2026-09-17T19:49:13.042677Z","end":"2026-09-17T19:49:13.187283Z","steps":["trace[903259841] 'process raft request' (duration: 88.628495ms)","trace[903259841] 'compare' (duration: 55.502569ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:49:26.897479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.789688ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-17T19:49:26.89762Z","caller":"traceutil/trace.go:171","msg":"trace[1971378437] linearizableReadLoop","detail":"{readStateIndex:5925; appliedIndex:5924; }","duration":"324.60783ms","start":"2026-09-17T19:49:26.572994Z","end":"2026-09-17T19:49:26.897602Z","steps":["trace[1971378437] 'read index received' (duration: 99.634217ms)","trace[1971378437] 'applied index is now lower than readState.Index' (duration: 224.971813ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:49:26.89781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.804317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-gtwrw\" ","response":"range_response_count:1 size:4995"} {"level":"info","ts":"2026-09-17T19:49:26.89785Z","caller":"traceutil/trace.go:171","msg":"trace[398020233] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-gtwrw; range_end:; response_count:1; response_revision:5709; }","duration":"324.883649ms","start":"2026-09-17T19:49:26.572952Z","end":"2026-09-17T19:49:26.897836Z","steps":["trace[398020233] 'agreement among raft nodes before linearized reading' (duration: 324.725814ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:49:26.897882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:49:26.572932Z","time spent":"324.941982ms","remote":"127.0.0.1:45752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":5019,"request content":"key:\"/registry/pods/monitoring/goldpinger-gtwrw\" "} {"level":"warn","ts":"2026-09-17T19:49:26.897932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.886601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:49:26.89805Z","caller":"traceutil/trace.go:171","msg":"trace[60798655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5709; }","duration":"171.082578ms","start":"2026-09-17T19:49:26.726937Z","end":"2026-09-17T19:49:26.89802Z","steps":["trace[60798655] 'agreement among raft nodes before linearized reading' (duration: 170.821309ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:49:27.04979Z","caller":"traceutil/trace.go:171","msg":"trace[1109536723] transaction","detail":"{read_only:false; response_revision:5710; number_of_response:1; }","duration":"142.634943ms","start":"2026-09-17T19:49:26.907131Z","end":"2026-09-17T19:49:27.049766Z","steps":["trace[1109536723] 'process raft request' (duration: 142.055726ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:49:39.974577Z","caller":"traceutil/trace.go:171","msg":"trace[839835099] transaction","detail":"{read_only:false; response_revision:5972; number_of_response:1; }","duration":"142.493059ms","start":"2026-09-17T19:49:39.832056Z","end":"2026-09-17T19:49:39.974549Z","steps":["trace[839835099] 'process raft request' (duration: 142.328494ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:49:39.975096Z","caller":"traceutil/trace.go:171","msg":"trace[1863540264] linearizableReadLoop","detail":"{readStateIndex:6192; appliedIndex:6192; }","duration":"125.568344ms","start":"2026-09-17T19:49:39.849452Z","end":"2026-09-17T19:49:39.97502Z","steps":["trace[1863540264] 'read index received' (duration: 125.560524ms)","trace[1863540264] 'applied index is now lower than readState.Index' (duration: 6.19µs)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:49:39.97541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.931766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112148"} {"level":"info","ts":"2026-09-17T19:49:39.975475Z","caller":"traceutil/trace.go:171","msg":"trace[1011220748] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5972; }","duration":"126.07732ms","start":"2026-09-17T19:49:39.84938Z","end":"2026-09-17T19:49:39.975457Z","steps":["trace[1011220748] 'agreement among raft nodes before linearized reading' (duration: 125.743009ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:49:40.413643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.027884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:49:40.413756Z","caller":"traceutil/trace.go:171","msg":"trace[2110493650] linearizableReadLoop","detail":"{readStateIndex:6196; appliedIndex:6195; }","duration":"185.733893ms","start":"2026-09-17T19:49:40.228004Z","end":"2026-09-17T19:49:40.413738Z","steps":["trace[2110493650] 'read index received' (duration: 74.460452ms)","trace[2110493650] 'applied index is now lower than readState.Index' (duration: 111.271971ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:49:40.413841Z","caller":"traceutil/trace.go:171","msg":"trace[1826310489] transaction","detail":"{read_only:false; response_revision:5976; number_of_response:1; }","duration":"363.170683ms","start":"2026-09-17T19:49:40.050659Z","end":"2026-09-17T19:49:40.41383Z","steps":["trace[1826310489] 'process raft request' (duration: 251.868072ms)","trace[1826310489] 'compare' (duration: 110.897909ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:49:40.413928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:49:40.050636Z","time spent":"363.226925ms","remote":"127.0.0.1:42940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4711,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:49:40.414329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.543108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-17T19:49:40.414412Z","caller":"traceutil/trace.go:171","msg":"trace[1871041312] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5976; }","duration":"160.671401ms","start":"2026-09-17T19:49:40.253724Z","end":"2026-09-17T19:49:40.414395Z","steps":["trace[1871041312] 'agreement among raft nodes before linearized reading' (duration: 160.456735ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:49:40.414489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.493846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-17T19:49:40.414532Z","caller":"traceutil/trace.go:171","msg":"trace[941399585] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5976; }","duration":"186.592328ms","start":"2026-09-17T19:49:40.227928Z","end":"2026-09-17T19:49:40.41452Z","steps":["trace[941399585] 'agreement among raft nodes before linearized reading' (duration: 186.191256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:49:40.865507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.987221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:49:40.865597Z","caller":"traceutil/trace.go:171","msg":"trace[1750246407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5976; }","duration":"136.136325ms","start":"2026-09-17T19:49:40.729441Z","end":"2026-09-17T19:49:40.865577Z","steps":["trace[1750246407] 'range keys from in-memory index tree' (duration: 135.907188ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:49:40.865735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.100031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-13774a8e-45ad-467d-aab2-4526f0e737c7\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-17T19:49:40.865793Z","caller":"traceutil/trace.go:171","msg":"trace[1946302400] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-13774a8e-45ad-467d-aab2-4526f0e737c7; range_end:; response_count:1; response_revision:5976; }","duration":"100.186334ms","start":"2026-09-17T19:49:40.765589Z","end":"2026-09-17T19:49:40.865775Z","steps":["trace[1946302400] 'range keys from in-memory index tree' (duration: 99.870484ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:49:40.866074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.253673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T19:49:40.866098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.518947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:49:40.866135Z","caller":"traceutil/trace.go:171","msg":"trace[503530509] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5976; }","duration":"333.363047ms","start":"2026-09-17T19:49:40.532754Z","end":"2026-09-17T19:49:40.866117Z","steps":["trace[503530509] 'range keys from in-memory index tree' (duration: 333.160721ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:49:40.866144Z","caller":"traceutil/trace.go:171","msg":"trace[1157163203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5976; }","duration":"109.674003ms","start":"2026-09-17T19:49:40.756457Z","end":"2026-09-17T19:49:40.866131Z","steps":["trace[1157163203] 'range keys from in-memory index tree' (duration: 109.381923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:49:40.866177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:49:40.53273Z","time spent":"333.434479ms","remote":"127.0.0.1:46042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-09-17T19:54:08.134961Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5296} {"level":"info","ts":"2026-09-17T19:54:08.224328Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5296,"took":"87.682329ms","hash":3626959432,"current-db-size-bytes":52359168,"current-db-size":"52 MB","current-db-size-in-use-bytes":27942912,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-17T19:54:08.224412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3626959432,"revision":5296,"compact-revision":2934} {"level":"info","ts":"2026-09-17T19:55:33.024894Z","caller":"traceutil/trace.go:171","msg":"trace[592804193] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"146.405279ms","start":"2026-09-17T19:55:32.878463Z","end":"2026-09-17T19:55:33.024869Z","steps":["trace[592804193] 'process raft request' (duration: 146.317766ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:55:33.025287Z","caller":"traceutil/trace.go:171","msg":"trace[2110245329] transaction","detail":"{read_only:false; response_revision:8478; number_of_response:1; }","duration":"146.863983ms","start":"2026-09-17T19:55:32.878407Z","end":"2026-09-17T19:55:33.025271Z","steps":["trace[2110245329] 'process raft request' (duration: 126.826616ms)","trace[2110245329] 'compare' (duration: 19.401518ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:56:01.0982Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000239d.wal"} {"level":"info","ts":"2026-09-17T19:56:05.609767Z","caller":"traceutil/trace.go:171","msg":"trace[372133430] transaction","detail":"{read_only:false; response_revision:8825; number_of_response:1; }","duration":"500.84303ms","start":"2026-09-17T19:56:05.108896Z","end":"2026-09-17T19:56:05.609739Z","steps":["trace[372133430] 'process raft request' (duration: 500.706236ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:05.609931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:56:05.108869Z","time spent":"500.985454ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:56:05.937536Z","caller":"traceutil/trace.go:171","msg":"trace[1092079677] linearizableReadLoop","detail":"{readStateIndex:9141; appliedIndex:9139; }","duration":"778.125538ms","start":"2026-09-17T19:56:05.159361Z","end":"2026-09-17T19:56:05.937486Z","steps":["trace[1092079677] 'read index received' (duration: 450.229149ms)","trace[1092079677] 'applied index is now lower than readState.Index' (duration: 327.895399ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:56:05.937957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.571142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-17T19:56:05.938041Z","caller":"traceutil/trace.go:171","msg":"trace[1553058257] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:8827; }","duration":"778.702985ms","start":"2026-09-17T19:56:05.159314Z","end":"2026-09-17T19:56:05.938017Z","steps":["trace[1553058257] 'agreement among raft nodes before linearized reading' (duration: 778.409297ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:56:05.938026Z","caller":"traceutil/trace.go:171","msg":"trace[143202530] transaction","detail":"{read_only:false; response_revision:8826; number_of_response:1; }","duration":"789.302707ms","start":"2026-09-17T19:56:05.148684Z","end":"2026-09-17T19:56:05.937986Z","steps":["trace[143202530] 'process raft request' (duration: 788.340797ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:56:05.938032Z","caller":"traceutil/trace.go:171","msg":"trace[327595280] transaction","detail":"{read_only:false; response_revision:8827; number_of_response:1; }","duration":"754.784942ms","start":"2026-09-17T19:56:05.183208Z","end":"2026-09-17T19:56:05.937992Z","steps":["trace[327595280] 'process raft request' (duration: 754.233874ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:05.938099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.140337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:56:05.938158Z","caller":"traceutil/trace.go:171","msg":"trace[1432755795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8827; }","duration":"100.040397ms","start":"2026-09-17T19:56:05.838082Z","end":"2026-09-17T19:56:05.938122Z","steps":["trace[1432755795] 'agreement among raft nodes before linearized reading' (duration: 99.886272ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:56:05.938189Z","caller":"traceutil/trace.go:171","msg":"trace[1331443228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8827; }","duration":"208.294341ms","start":"2026-09-17T19:56:05.729876Z","end":"2026-09-17T19:56:05.93817Z","steps":["trace[1331443228] 'agreement among raft nodes before linearized reading' (duration: 208.113846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:05.938238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:56:05.148655Z","time spent":"789.479932ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:56:05.938217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.419234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-17T19:56:05.938093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:56:05.159291Z","time spent":"778.787758ms","remote":"127.0.0.1:45722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-09-17T19:56:05.93833Z","caller":"traceutil/trace.go:171","msg":"trace[1837693392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8827; }","duration":"124.586139ms","start":"2026-09-17T19:56:05.813716Z","end":"2026-09-17T19:56:05.938302Z","steps":["trace[1837693392] 'agreement among raft nodes before linearized reading' (duration: 124.2701ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:05.938285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:56:05.183187Z","time spent":"754.968158ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:56:05.938312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.350258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-17T19:56:05.938532Z","caller":"traceutil/trace.go:171","msg":"trace[1418392598] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8827; }","duration":"708.604335ms","start":"2026-09-17T19:56:05.229916Z","end":"2026-09-17T19:56:05.93852Z","steps":["trace[1418392598] 'agreement among raft nodes before linearized reading' (duration: 708.034389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:05.938568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:56:05.229898Z","time spent":"708.660987ms","remote":"127.0.0.1:45752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-17T19:56:16.112153Z","caller":"traceutil/trace.go:171","msg":"trace[2008357515] transaction","detail":"{read_only:false; response_revision:8882; number_of_response:1; }","duration":"121.217086ms","start":"2026-09-17T19:56:15.990887Z","end":"2026-09-17T19:56:16.112104Z","steps":["trace[2008357515] 'process raft request' (duration: 120.862966ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:56:16.123608Z","caller":"traceutil/trace.go:171","msg":"trace[533259716] linearizableReadLoop","detail":"{readStateIndex:9200; appliedIndex:9198; }","duration":"125.98239ms","start":"2026-09-17T19:56:15.997589Z","end":"2026-09-17T19:56:16.123571Z","steps":["trace[533259716] 'read index received' (duration: 114.0841ms)","trace[533259716] 'applied index is now lower than readState.Index' (duration: 11.8965ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:56:16.123782Z","caller":"traceutil/trace.go:171","msg":"trace[1700630764] transaction","detail":"{read_only:false; response_revision:8883; number_of_response:1; }","duration":"131.514428ms","start":"2026-09-17T19:56:15.992205Z","end":"2026-09-17T19:56:16.123719Z","steps":["trace[1700630764] 'process raft request' (duration: 131.166977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:16.123982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.359762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-17T19:56:16.124033Z","caller":"traceutil/trace.go:171","msg":"trace[1739394300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8883; }","duration":"126.438664ms","start":"2026-09-17T19:56:15.997578Z","end":"2026-09-17T19:56:16.124016Z","steps":["trace[1739394300] 'agreement among raft nodes before linearized reading' (duration: 126.196557ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:16.124138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.866047ms","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-09-17T19:56:16.12422Z","caller":"traceutil/trace.go:171","msg":"trace[1122284310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8883; }","duration":"119.992731ms","start":"2026-09-17T19:56:16.004208Z","end":"2026-09-17T19:56:16.1242Z","steps":["trace[1122284310] 'agreement among raft nodes before linearized reading' (duration: 119.683242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:16.124231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.279069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-17T19:56:16.124356Z","caller":"traceutil/trace.go:171","msg":"trace[884715874] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8883; }","duration":"120.485065ms","start":"2026-09-17T19:56:16.003824Z","end":"2026-09-17T19:56:16.124309Z","steps":["trace[884715874] 'agreement among raft nodes before linearized reading' (duration: 120.043432ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:56:16.664942Z","caller":"traceutil/trace.go:171","msg":"trace[1290044389] linearizableReadLoop","detail":"{readStateIndex:9204; appliedIndex:9203; }","duration":"196.904887ms","start":"2026-09-17T19:56:16.468013Z","end":"2026-09-17T19:56:16.664918Z","steps":["trace[1290044389] 'read index received' (duration: 196.750113ms)","trace[1290044389] 'applied index is now lower than readState.Index' (duration: 153.874µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:56:16.665072Z","caller":"traceutil/trace.go:171","msg":"trace[1401631083] transaction","detail":"{read_only:false; response_revision:8887; number_of_response:1; }","duration":"228.491842ms","start":"2026-09-17T19:56:16.436556Z","end":"2026-09-17T19:56:16.665047Z","steps":["trace[1401631083] 'process raft request' (duration: 228.203844ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:16.665142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.109483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:56:16.665186Z","caller":"traceutil/trace.go:171","msg":"trace[638456710] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:8887; }","duration":"197.231327ms","start":"2026-09-17T19:56:16.467942Z","end":"2026-09-17T19:56:16.665174Z","steps":["trace[638456710] 'agreement among raft nodes before linearized reading' (duration: 197.102803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:16.665619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.053803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-17T19:56:16.665853Z","caller":"traceutil/trace.go:171","msg":"trace[761674796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8887; }","duration":"124.378533ms","start":"2026-09-17T19:56:16.54144Z","end":"2026-09-17T19:56:16.665783Z","steps":["trace[761674796] 'agreement among raft nodes before linearized reading' (duration: 123.863107ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:56:16.819604Z","caller":"traceutil/trace.go:171","msg":"trace[2064384089] transaction","detail":"{read_only:false; response_revision:8888; number_of_response:1; }","duration":"147.08954ms","start":"2026-09-17T19:56:16.672474Z","end":"2026-09-17T19:56:16.819564Z","steps":["trace[2064384089] 'process raft request' (duration: 117.596648ms)","trace[2064384089] 'compare' (duration: 29.168121ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:56:17.036578Z","caller":"traceutil/trace.go:171","msg":"trace[2131225906] linearizableReadLoop","detail":"{readStateIndex:9206; appliedIndex:9205; }","duration":"124.708034ms","start":"2026-09-17T19:56:16.911845Z","end":"2026-09-17T19:56:17.036553Z","steps":["trace[2131225906] 'read index received' (duration: 106.789442ms)","trace[2131225906] 'applied index is now lower than readState.Index' (duration: 17.917562ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:56:17.036594Z","caller":"traceutil/trace.go:171","msg":"trace[1918011904] transaction","detail":"{read_only:false; response_revision:8889; number_of_response:1; }","duration":"165.862598ms","start":"2026-09-17T19:56:16.8707Z","end":"2026-09-17T19:56:17.036563Z","steps":["trace[1918011904] 'process raft request' (duration: 147.915095ms)","trace[1918011904] 'compare' (duration: 17.821908ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:56:17.036695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.838787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:56:17.036753Z","caller":"traceutil/trace.go:171","msg":"trace[914940190] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:8889; }","duration":"124.979641ms","start":"2026-09-17T19:56:16.911758Z","end":"2026-09-17T19:56:17.036738Z","steps":["trace[914940190] 'agreement among raft nodes before linearized reading' (duration: 124.869978ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:56:17.929574Z","caller":"traceutil/trace.go:171","msg":"trace[690489098] transaction","detail":"{read_only:false; response_revision:8890; number_of_response:1; }","duration":"254.516068ms","start":"2026-09-17T19:56:17.675031Z","end":"2026-09-17T19:56:17.929547Z","steps":["trace[690489098] 'process raft request' (duration: 254.325773ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:56:18.294688Z","caller":"traceutil/trace.go:171","msg":"trace[1151855294] transaction","detail":"{read_only:false; response_revision:8894; number_of_response:1; }","duration":"119.786853ms","start":"2026-09-17T19:56:18.17487Z","end":"2026-09-17T19:56:18.294656Z","steps":["trace[1151855294] 'process raft request' (duration: 119.581137ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:56:18.294953Z","caller":"traceutil/trace.go:171","msg":"trace[1448410163] transaction","detail":"{read_only:false; response_revision:8895; number_of_response:1; }","duration":"119.746262ms","start":"2026-09-17T19:56:18.17517Z","end":"2026-09-17T19:56:18.294916Z","steps":["trace[1448410163] 'process raft request' (duration: 119.443353ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:56:19.003113Z","caller":"traceutil/trace.go:171","msg":"trace[768715170] transaction","detail":"{read_only:false; response_revision:8897; number_of_response:1; }","duration":"174.452717ms","start":"2026-09-17T19:56:18.828623Z","end":"2026-09-17T19:56:19.003076Z","steps":["trace[768715170] 'process raft request' (duration: 174.266822ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:19.314828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.850392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:56:19.314986Z","caller":"traceutil/trace.go:171","msg":"trace[738921409] linearizableReadLoop","detail":"{readStateIndex:9216; appliedIndex:9215; }","duration":"245.2758ms","start":"2026-09-17T19:56:19.069681Z","end":"2026-09-17T19:56:19.314957Z","steps":["trace[738921409] 'read index received' (duration: 97.977225ms)","trace[738921409] 'applied index is now lower than readState.Index' (duration: 147.296445ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:56:19.315111Z","caller":"traceutil/trace.go:171","msg":"trace[2037181118] transaction","detail":"{read_only:false; response_revision:8898; number_of_response:1; }","duration":"272.976708ms","start":"2026-09-17T19:56:19.042079Z","end":"2026-09-17T19:56:19.315056Z","steps":["trace[2037181118] 'process raft request' (duration: 125.626351ms)","trace[2037181118] 'compare' (duration: 146.650755ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:56:19.31513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.434464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:56:19.31519Z","caller":"traceutil/trace.go:171","msg":"trace[1832040250] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:8898; }","duration":"245.541197ms","start":"2026-09-17T19:56:19.069629Z","end":"2026-09-17T19:56:19.31517Z","steps":["trace[1832040250] 'agreement among raft nodes before linearized reading' (duration: 245.417424ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:19.31526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.401506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:56:19.315308Z","caller":"traceutil/trace.go:171","msg":"trace[566645777] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8898; }","duration":"135.570551ms","start":"2026-09-17T19:56:19.179723Z","end":"2026-09-17T19:56:19.315294Z","steps":["trace[566645777] 'agreement among raft nodes before linearized reading' (duration: 135.468328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:19.315355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.022816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z6b9c\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-09-17T19:56:19.31545Z","caller":"traceutil/trace.go:171","msg":"trace[1007861394] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z6b9c; range_end:; response_count:1; response_revision:8898; }","duration":"180.200152ms","start":"2026-09-17T19:56:19.135227Z","end":"2026-09-17T19:56:19.315427Z","steps":["trace[1007861394] 'agreement among raft nodes before linearized reading' (duration: 179.895943ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:56:21.268527Z","caller":"traceutil/trace.go:171","msg":"trace[1056807429] transaction","detail":"{read_only:false; response_revision:8909; number_of_response:1; }","duration":"103.603564ms","start":"2026-09-17T19:56:21.164876Z","end":"2026-09-17T19:56:21.268479Z","steps":["trace[1056807429] 'process raft request' (duration: 103.360127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:21.733464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.760236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.181\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-17T19:56:21.733552Z","caller":"traceutil/trace.go:171","msg":"trace[1132916384] range","detail":"{range_begin:/registry/masterleases/162.253.55.181; range_end:; response_count:1; response_revision:8911; }","duration":"173.929981ms","start":"2026-09-17T19:56:21.559601Z","end":"2026-09-17T19:56:21.733531Z","steps":["trace[1132916384] 'range keys from in-memory index tree' (duration: 173.411387ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:45.994416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.018494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:56:45.994562Z","caller":"traceutil/trace.go:171","msg":"trace[426028471] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:9092; }","duration":"134.242941ms","start":"2026-09-17T19:56:45.860284Z","end":"2026-09-17T19:56:45.994527Z","steps":["trace[426028471] 'count revisions from in-memory index tree' (duration: 133.90188ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:45.994566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.402512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:20289"} {"level":"info","ts":"2026-09-17T19:56:45.994647Z","caller":"traceutil/trace.go:171","msg":"trace[1567693402] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:9092; }","duration":"155.578977ms","start":"2026-09-17T19:56:45.839048Z","end":"2026-09-17T19:56:45.994627Z","steps":["trace[1567693402] 'range keys from in-memory index tree' (duration: 155.178925ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:56:49.208041Z","caller":"traceutil/trace.go:171","msg":"trace[1384787734] linearizableReadLoop","detail":"{readStateIndex:9438; appliedIndex:9437; }","duration":"223.295056ms","start":"2026-09-17T19:56:48.984725Z","end":"2026-09-17T19:56:49.20802Z","steps":["trace[1384787734] 'read index received' (duration: 223.045198ms)","trace[1384787734] 'applied index is now lower than readState.Index' (duration: 249.058µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:56:49.208357Z","caller":"traceutil/trace.go:171","msg":"trace[1675679711] transaction","detail":"{read_only:false; response_revision:9111; number_of_response:1; }","duration":"314.668309ms","start":"2026-09-17T19:56:48.893675Z","end":"2026-09-17T19:56:49.208343Z","steps":["trace[1675679711] 'process raft request' (duration: 314.212316ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:49.208477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:56:48.893643Z","time spent":"314.752412ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:56:49.208726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.988756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:56:49.208807Z","caller":"traceutil/trace.go:171","msg":"trace[1611531896] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:9111; }","duration":"224.073458ms","start":"2026-09-17T19:56:48.984687Z","end":"2026-09-17T19:56:49.20876Z","steps":["trace[1611531896] 'agreement among raft nodes before linearized reading' (duration: 223.996956ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:56:49.527919Z","caller":"traceutil/trace.go:171","msg":"trace[71256933] transaction","detail":"{read_only:false; response_revision:9112; number_of_response:1; }","duration":"312.74529ms","start":"2026-09-17T19:56:49.215143Z","end":"2026-09-17T19:56:49.527888Z","steps":["trace[71256933] 'process raft request' (duration: 311.930806ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:49.528196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:56:49.215122Z","time spent":"312.882805ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:56:51.744151Z","caller":"traceutil/trace.go:171","msg":"trace[1526110735] linearizableReadLoop","detail":"{readStateIndex:9453; appliedIndex:9451; }","duration":"129.92147ms","start":"2026-09-17T19:56:51.614195Z","end":"2026-09-17T19:56:51.744116Z","steps":["trace[1526110735] 'read index received' (duration: 101.398377ms)","trace[1526110735] 'applied index is now lower than readState.Index' (duration: 28.521583ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:56:51.744291Z","caller":"traceutil/trace.go:171","msg":"trace[307343595] transaction","detail":"{read_only:false; response_revision:9125; number_of_response:1; }","duration":"172.165069ms","start":"2026-09-17T19:56:51.572108Z","end":"2026-09-17T19:56:51.744273Z","steps":["trace[307343595] 'process raft request' (duration: 171.787168ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:51.744525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.298092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z6b9c\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-09-17T19:56:51.74462Z","caller":"traceutil/trace.go:171","msg":"trace[496082498] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z6b9c; range_end:; response_count:1; response_revision:9125; }","duration":"130.441316ms","start":"2026-09-17T19:56:51.614155Z","end":"2026-09-17T19:56:51.744597Z","steps":["trace[496082498] 'agreement among raft nodes before linearized reading' (duration: 130.162038ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:51.782494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.755358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:56:51.782556Z","caller":"traceutil/trace.go:171","msg":"trace[765521261] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:9126; }","duration":"104.869672ms","start":"2026-09-17T19:56:51.677671Z","end":"2026-09-17T19:56:51.782541Z","steps":["trace[765521261] 'agreement among raft nodes before linearized reading' (duration: 104.744008ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:56:52.260454Z","caller":"traceutil/trace.go:171","msg":"trace[1332249323] transaction","detail":"{read_only:false; response_revision:9127; number_of_response:1; }","duration":"186.535402ms","start":"2026-09-17T19:56:52.073888Z","end":"2026-09-17T19:56:52.260423Z","steps":["trace[1332249323] 'process raft request' (duration: 186.322346ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:56:52.52799Z","caller":"traceutil/trace.go:171","msg":"trace[1350654735] linearizableReadLoop","detail":"{readStateIndex:9456; appliedIndex:9455; }","duration":"262.385628ms","start":"2026-09-17T19:56:52.265554Z","end":"2026-09-17T19:56:52.527939Z","steps":["trace[1350654735] 'read index received' (duration: 262.097049ms)","trace[1350654735] 'applied index is now lower than readState.Index' (duration: 286.999µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:56:52.528088Z","caller":"traceutil/trace.go:171","msg":"trace[1944656700] transaction","detail":"{read_only:false; response_revision:9128; number_of_response:1; }","duration":"318.066911ms","start":"2026-09-17T19:56:52.209959Z","end":"2026-09-17T19:56:52.528026Z","steps":["trace[1944656700] 'process raft request' (duration: 317.731521ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:52.528244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.656236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-17T19:56:52.528311Z","caller":"traceutil/trace.go:171","msg":"trace[330983325] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9128; }","duration":"262.759529ms","start":"2026-09-17T19:56:52.265534Z","end":"2026-09-17T19:56:52.528294Z","steps":["trace[330983325] 'agreement among raft nodes before linearized reading' (duration: 262.529182ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:56:52.528365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:56:52.209922Z","time spent":"318.287218ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:56:52.532215Z","caller":"traceutil/trace.go:171","msg":"trace[406443546] transaction","detail":"{read_only:false; response_revision:9129; number_of_response:1; }","duration":"220.767598ms","start":"2026-09-17T19:56:52.311419Z","end":"2026-09-17T19:56:52.532186Z","steps":["trace[406443546] 'process raft request' (duration: 220.557642ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:56:52.532406Z","caller":"traceutil/trace.go:171","msg":"trace[1953960487] transaction","detail":"{read_only:false; response_revision:9130; number_of_response:1; }","duration":"137.717046ms","start":"2026-09-17T19:56:52.39466Z","end":"2026-09-17T19:56:52.532377Z","steps":["trace[1953960487] 'process raft request' (duration: 137.477659ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:57:01.127872Z","caller":"traceutil/trace.go:171","msg":"trace[334993103] transaction","detail":"{read_only:false; response_revision:9177; number_of_response:1; }","duration":"309.635297ms","start":"2026-09-17T19:57:00.816133Z","end":"2026-09-17T19:57:01.125768Z","steps":["trace[334993103] 'process raft request' (duration: 308.932956ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:57:01.128199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:57:00.816092Z","time spent":"311.926506ms","remote":"127.0.0.1:34332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":415350,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:57:01.483676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.594546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:20289"} {"level":"info","ts":"2026-09-17T19:57:01.483837Z","caller":"traceutil/trace.go:171","msg":"trace[2122275323] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:9177; }","duration":"152.847114ms","start":"2026-09-17T19:57:01.330964Z","end":"2026-09-17T19:57:01.483811Z","steps":["trace[2122275323] 'range keys from in-memory index tree' (duration: 152.235285ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:57:01.48423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.98464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-17T19:57:01.484367Z","caller":"traceutil/trace.go:171","msg":"trace[1162909992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9177; }","duration":"202.187367ms","start":"2026-09-17T19:57:01.282141Z","end":"2026-09-17T19:57:01.484329Z","steps":["trace[1162909992] 'range keys from in-memory index tree' (duration: 201.63977ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:57:02.512173Z","caller":"traceutil/trace.go:171","msg":"trace[990139203] transaction","detail":"{read_only:false; response_revision:9182; number_of_response:1; }","duration":"197.827716ms","start":"2026-09-17T19:57:02.314303Z","end":"2026-09-17T19:57:02.512131Z","steps":["trace[990139203] 'process raft request' (duration: 197.531006ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:57:03.371662Z","caller":"traceutil/trace.go:171","msg":"trace[1350967415] transaction","detail":"{read_only:false; response_revision:9200; number_of_response:1; }","duration":"171.629383ms","start":"2026-09-17T19:57:03.199998Z","end":"2026-09-17T19:57:03.371627Z","steps":["trace[1350967415] 'process raft request' (duration: 171.422675ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:57:54.82267Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"865c2c1f02504fb9","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-17T19:57:54.829501Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-17T19:57:54.829683Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-17T19:58:19.064955Z","caller":"traceutil/trace.go:171","msg":"trace[1929485779] linearizableReadLoop","detail":"{readStateIndex:10319; appliedIndex:10318; }","duration":"105.96588ms","start":"2026-09-17T19:58:18.958955Z","end":"2026-09-17T19:58:19.06492Z","steps":["trace[1929485779] 'read index received' (duration: 105.666041ms)","trace[1929485779] 'applied index is now lower than readState.Index' (duration: 298.259µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:58:19.06503Z","caller":"traceutil/trace.go:171","msg":"trace[556037858] transaction","detail":"{read_only:false; response_revision:9967; number_of_response:1; }","duration":"131.909157ms","start":"2026-09-17T19:58:18.933077Z","end":"2026-09-17T19:58:19.064987Z","steps":["trace[556037858] 'process raft request' (duration: 131.588348ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:58:19.065222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.23246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:58:19.065355Z","caller":"traceutil/trace.go:171","msg":"trace[400820375] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:9967; }","duration":"106.433595ms","start":"2026-09-17T19:58:18.958894Z","end":"2026-09-17T19:58:19.065328Z","steps":["trace[400820375] 'agreement among raft nodes before linearized reading' (duration: 106.146626ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:58:33.225907Z","caller":"traceutil/trace.go:171","msg":"trace[1140470574] transaction","detail":"{read_only:false; response_revision:10048; number_of_response:1; }","duration":"104.187667ms","start":"2026-09-17T19:58:33.121695Z","end":"2026-09-17T19:58:33.225882Z","steps":["trace[1140470574] 'process raft request' (duration: 104.002722ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:58:33.411543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.082229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:58:33.411648Z","caller":"traceutil/trace.go:171","msg":"trace[942937154] linearizableReadLoop","detail":"{readStateIndex:10404; appliedIndex:10402; }","duration":"206.653452ms","start":"2026-09-17T19:58:33.204976Z","end":"2026-09-17T19:58:33.411629Z","steps":["trace[942937154] 'read index received' (duration: 20.724438ms)","trace[942937154] 'applied index is now lower than readState.Index' (duration: 185.927754ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:58:33.41184Z","caller":"traceutil/trace.go:171","msg":"trace[1443312151] transaction","detail":"{read_only:false; response_revision:10049; number_of_response:1; }","duration":"289.359019ms","start":"2026-09-17T19:58:33.122462Z","end":"2026-09-17T19:58:33.411821Z","steps":["trace[1443312151] 'process raft request' (duration: 134.915929ms)","trace[1443312151] 'compare' (duration: 153.990357ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:58:33.412039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.343738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-17T19:58:33.412107Z","caller":"traceutil/trace.go:171","msg":"trace[1067888058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10049; }","duration":"157.448291ms","start":"2026-09-17T19:58:33.254643Z","end":"2026-09-17T19:58:33.412092Z","steps":["trace[1067888058] 'agreement among raft nodes before linearized reading' (duration: 157.216604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:58:33.41226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.924865ms","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-09-17T19:58:33.412363Z","caller":"traceutil/trace.go:171","msg":"trace[1939180977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10049; }","duration":"157.0704ms","start":"2026-09-17T19:58:33.255262Z","end":"2026-09-17T19:58:33.412332Z","steps":["trace[1939180977] 'agreement among raft nodes before linearized reading' (duration: 156.795962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:58:33.41226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.25654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-17T19:58:33.412494Z","caller":"traceutil/trace.go:171","msg":"trace[359749946] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10049; }","duration":"207.521929ms","start":"2026-09-17T19:58:33.204942Z","end":"2026-09-17T19:58:33.412464Z","steps":["trace[359749946] 'agreement among raft nodes before linearized reading' (duration: 207.119906ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:58:33.638833Z","caller":"traceutil/trace.go:171","msg":"trace[309622385] transaction","detail":"{read_only:false; response_revision:10050; number_of_response:1; }","duration":"221.711598ms","start":"2026-09-17T19:58:33.417081Z","end":"2026-09-17T19:58:33.638793Z","steps":["trace[309622385] 'process raft request' (duration: 190.279416ms)","trace[309622385] 'compare' (duration: 31.276117ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:58:33.82316Z","caller":"traceutil/trace.go:171","msg":"trace[2093427139] linearizableReadLoop","detail":"{readStateIndex:10407; appliedIndex:10404; }","duration":"296.103693ms","start":"2026-09-17T19:58:33.527023Z","end":"2026-09-17T19:58:33.823127Z","steps":["trace[2093427139] 'read index received' (duration: 80.356185ms)","trace[2093427139] 'applied index is now lower than readState.Index' (duration: 215.746098ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:58:33.823204Z","caller":"traceutil/trace.go:171","msg":"trace[36598288] transaction","detail":"{read_only:false; response_revision:10051; number_of_response:1; }","duration":"406.008714ms","start":"2026-09-17T19:58:33.417137Z","end":"2026-09-17T19:58:33.823145Z","steps":["trace[36598288] 'process raft request' (duration: 363.570328ms)","trace[36598288] 'compare' (duration: 42.192188ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:58:33.823824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:58:33.417119Z","time spent":"406.483987ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:58:33.823391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.344131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-17T19:58:33.823985Z","caller":"traceutil/trace.go:171","msg":"trace[2007485025] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:10052; }","duration":"296.933878ms","start":"2026-09-17T19:58:33.526969Z","end":"2026-09-17T19:58:33.823903Z","steps":["trace[2007485025] 'agreement among raft nodes before linearized reading' (duration: 296.355381ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:58:33.823202Z","caller":"traceutil/trace.go:171","msg":"trace[554693083] transaction","detail":"{read_only:false; response_revision:10052; number_of_response:1; }","duration":"405.771956ms","start":"2026-09-17T19:58:33.417366Z","end":"2026-09-17T19:58:33.823138Z","steps":["trace[554693083] 'process raft request' (duration: 405.695754ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:58:33.824547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:58:33.41736Z","time spent":"406.971563ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:59:08.142387Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7909} {"level":"info","ts":"2026-09-17T19:59:08.249575Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7909,"took":"104.29553ms","hash":3224197032,"current-db-size-bytes":52359168,"current-db-size":"52 MB","current-db-size-in-use-bytes":25849856,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-17T19:59:08.249661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3224197032,"revision":7909,"compact-revision":5296} {"level":"info","ts":"2026-09-17T20:03:48.704925Z","caller":"traceutil/trace.go:171","msg":"trace[1037656015] transaction","detail":"{read_only:false; response_revision:12315; number_of_response:1; }","duration":"148.292626ms","start":"2026-09-17T20:03:48.556601Z","end":"2026-09-17T20:03:48.704893Z","steps":["trace[1037656015] 'process raft request' (duration: 148.034378ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:03:48.70714Z","caller":"traceutil/trace.go:171","msg":"trace[1788288934] transaction","detail":"{read_only:false; response_revision:12316; number_of_response:1; }","duration":"136.429488ms","start":"2026-09-17T20:03:48.570667Z","end":"2026-09-17T20:03:48.707097Z","steps":["trace[1788288934] 'process raft request' (duration: 136.206211ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:03:59.011518Z","caller":"traceutil/trace.go:171","msg":"trace[1157412774] transaction","detail":"{read_only:false; response_revision:12401; number_of_response:1; }","duration":"234.741569ms","start":"2026-09-17T20:03:58.776736Z","end":"2026-09-17T20:03:59.011477Z","steps":["trace[1157412774] 'process raft request' (duration: 154.928138ms)","trace[1157412774] 'compare' (duration: 79.645806ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:04:08.14977Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10287} {"level":"info","ts":"2026-09-17T20:04:08.242207Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10287,"took":"89.410282ms","hash":3804486261,"current-db-size-bytes":52359168,"current-db-size":"52 MB","current-db-size-in-use-bytes":25620480,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-17T20:04:08.242291Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3804486261,"revision":10287,"compact-revision":7909} {"level":"info","ts":"2026-09-17T20:04:19.21428Z","caller":"traceutil/trace.go:171","msg":"trace[919968717] transaction","detail":"{read_only:false; response_revision:12518; number_of_response:1; }","duration":"109.822081ms","start":"2026-09-17T20:04:19.104418Z","end":"2026-09-17T20:04:19.21424Z","steps":["trace[919968717] 'process raft request' (duration: 109.619804ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:04:19.523297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.296923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T20:04:19.523422Z","caller":"traceutil/trace.go:171","msg":"trace[414494300] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:12518; }","duration":"227.499009ms","start":"2026-09-17T20:04:19.295891Z","end":"2026-09-17T20:04:19.52339Z","steps":["trace[414494300] 'count revisions from in-memory index tree' (duration: 227.141748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:04:19.523618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.361068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z6b9c\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-09-17T20:04:19.524242Z","caller":"traceutil/trace.go:171","msg":"trace[1219937456] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z6b9c; range_end:; response_count:1; response_revision:12518; }","duration":"209.554826ms","start":"2026-09-17T20:04:19.314165Z","end":"2026-09-17T20:04:19.523719Z","steps":["trace[1219937456] 'range keys from in-memory index tree' (duration: 208.554125ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:04:20.723985Z","caller":"traceutil/trace.go:171","msg":"trace[1059981473] transaction","detail":"{read_only:false; response_revision:12525; number_of_response:1; }","duration":"214.922128ms","start":"2026-09-17T20:04:20.509013Z","end":"2026-09-17T20:04:20.723935Z","steps":["trace[1059981473] 'process raft request' (duration: 214.592609ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:09:08.16564Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12452} {"level":"info","ts":"2026-09-17T20:09:08.250601Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12452,"took":"82.171124ms","hash":962574566,"current-db-size-bytes":52359168,"current-db-size":"52 MB","current-db-size-in-use-bytes":27844608,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-17T20:09:08.250671Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":962574566,"revision":12452,"compact-revision":10287} {"level":"info","ts":"2026-09-17T20:10:29.060282Z","caller":"traceutil/trace.go:171","msg":"trace[73130818] transaction","detail":"{read_only:false; response_revision:15043; number_of_response:1; }","duration":"104.704198ms","start":"2026-09-17T20:10:28.955536Z","end":"2026-09-17T20:10:29.06024Z","steps":["trace[73130818] 'process raft request' (duration: 103.741838ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:10:54.137894Z","caller":"traceutil/trace.go:171","msg":"trace[2061257750] transaction","detail":"{read_only:false; response_revision:15169; number_of_response:1; }","duration":"122.665978ms","start":"2026-09-17T20:10:54.01519Z","end":"2026-09-17T20:10:54.137856Z","steps":["trace[2061257750] 'process raft request' (duration: 122.09125ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:11:12.373356Z","caller":"traceutil/trace.go:171","msg":"trace[2053638324] linearizableReadLoop","detail":"{readStateIndex:15810; appliedIndex:15809; }","duration":"141.937474ms","start":"2026-09-17T20:11:12.231375Z","end":"2026-09-17T20:11:12.373312Z","steps":["trace[2053638324] 'read index received' (duration: 140.629164ms)","trace[2053638324] 'applied index is now lower than readState.Index' (duration: 1.264369ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:11:12.373853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.439499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-09-17T20:11:12.373924Z","caller":"traceutil/trace.go:171","msg":"trace[1852710758] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:15275; }","duration":"142.565962ms","start":"2026-09-17T20:11:12.231322Z","end":"2026-09-17T20:11:12.373888Z","steps":["trace[1852710758] 'agreement among raft nodes before linearized reading' (duration: 142.294784ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:11:12.374108Z","caller":"traceutil/trace.go:171","msg":"trace[300520639] transaction","detail":"{read_only:false; response_revision:15275; number_of_response:1; }","duration":"172.77588ms","start":"2026-09-17T20:11:12.201257Z","end":"2026-09-17T20:11:12.374033Z","steps":["trace[300520639] 'process raft request' (duration: 170.845521ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:11:12.374224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.857377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-17T20:11:12.374367Z","caller":"traceutil/trace.go:171","msg":"trace[745324553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15275; }","duration":"133.044623ms","start":"2026-09-17T20:11:12.241292Z","end":"2026-09-17T20:11:12.374337Z","steps":["trace[745324553] 'agreement among raft nodes before linearized reading' (duration: 132.736934ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:11:13.198334Z","caller":"traceutil/trace.go:171","msg":"trace[974126527] transaction","detail":"{read_only:false; response_revision:15284; number_of_response:1; }","duration":"210.273679ms","start":"2026-09-17T20:11:12.988024Z","end":"2026-09-17T20:11:13.198298Z","steps":["trace[974126527] 'process raft request' (duration: 210.035622ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:11:13.480599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.526925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T20:11:13.480698Z","caller":"traceutil/trace.go:171","msg":"trace[2002259152] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:15284; }","duration":"103.716562ms","start":"2026-09-17T20:11:13.37696Z","end":"2026-09-17T20:11:13.480676Z","steps":["trace[2002259152] 'count revisions from in-memory index tree' (duration: 103.416362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:11:13.480799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.058472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-17T20:11:13.48093Z","caller":"traceutil/trace.go:171","msg":"trace[1093518525] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15284; }","duration":"153.265849ms","start":"2026-09-17T20:11:13.327628Z","end":"2026-09-17T20:11:13.480894Z","steps":["trace[1093518525] 'range keys from in-memory index tree' (duration: 152.732323ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:11:24.081661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.361912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T20:11:24.08179Z","caller":"traceutil/trace.go:171","msg":"trace[2134191408] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15458; }","duration":"130.559017ms","start":"2026-09-17T20:11:23.951201Z","end":"2026-09-17T20:11:24.08176Z","steps":["trace[2134191408] 'range keys from in-memory index tree' (duration: 130.252259ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:11:24.142024Z","caller":"traceutil/trace.go:171","msg":"trace[362782965] linearizableReadLoop","detail":"{readStateIndex:15997; appliedIndex:15996; }","duration":"119.773311ms","start":"2026-09-17T20:11:24.022219Z","end":"2026-09-17T20:11:24.141992Z","steps":["trace[362782965] 'read index received' (duration: 119.437451ms)","trace[362782965] 'applied index is now lower than readState.Index' (duration: 334.92µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:11:24.142155Z","caller":"traceutil/trace.go:171","msg":"trace[974290789] transaction","detail":"{read_only:false; response_revision:15459; number_of_response:1; }","duration":"142.096938ms","start":"2026-09-17T20:11:24.000009Z","end":"2026-09-17T20:11:24.142106Z","steps":["trace[974290789] 'process raft request' (duration: 141.762148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:11:24.142283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.013767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T20:11:24.142424Z","caller":"traceutil/trace.go:171","msg":"trace[128194099] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:15459; }","duration":"120.239064ms","start":"2026-09-17T20:11:24.022157Z","end":"2026-09-17T20:11:24.142396Z","steps":["trace[128194099] 'agreement among raft nodes before linearized reading' (duration: 119.978576ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:11:24.444558Z","caller":"traceutil/trace.go:171","msg":"trace[899292745] transaction","detail":"{read_only:false; response_revision:15460; number_of_response:1; }","duration":"218.578343ms","start":"2026-09-17T20:11:24.22595Z","end":"2026-09-17T20:11:24.444528Z","steps":["trace[899292745] 'process raft request' (duration: 211.172598ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:11:24.444549Z","caller":"traceutil/trace.go:171","msg":"trace[454855389] linearizableReadLoop","detail":"{readStateIndex:15998; appliedIndex:15997; }","duration":"213.365374ms","start":"2026-09-17T20:11:24.231159Z","end":"2026-09-17T20:11:24.444524Z","steps":["trace[454855389] 'read index received' (duration: 205.982609ms)","trace[454855389] 'applied index is now lower than readState.Index' (duration: 7.381905ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:11:24.4449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.734585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-xg7f4.18d634bbb364abfe\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-09-17T20:11:24.444933Z","caller":"traceutil/trace.go:171","msg":"trace[492332098] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-xg7f4.18d634bbb364abfe; range_end:; response_count:1; response_revision:15461; }","duration":"213.836967ms","start":"2026-09-17T20:11:24.231085Z","end":"2026-09-17T20:11:24.444922Z","steps":["trace[492332098] 'agreement among raft nodes before linearized reading' (duration: 213.567069ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:11:46.207197Z","caller":"traceutil/trace.go:171","msg":"trace[1872526117] transaction","detail":"{read_only:false; response_revision:15798; number_of_response:1; }","duration":"100.537686ms","start":"2026-09-17T20:11:46.106627Z","end":"2026-09-17T20:11:46.207165Z","steps":["trace[1872526117] 'process raft request' (duration: 99.884997ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:11:46.207313Z","caller":"traceutil/trace.go:171","msg":"trace[51207983] transaction","detail":"{read_only:false; response_revision:15799; number_of_response:1; }","duration":"100.506935ms","start":"2026-09-17T20:11:46.106791Z","end":"2026-09-17T20:11:46.207298Z","steps":["trace[51207983] 'process raft request' (duration: 100.0161ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:11:46.207519Z","caller":"traceutil/trace.go:171","msg":"trace[592006378] transaction","detail":"{read_only:false; response_revision:15800; number_of_response:1; }","duration":"100.532225ms","start":"2026-09-17T20:11:46.106891Z","end":"2026-09-17T20:11:46.207423Z","steps":["trace[592006378] 'process raft request' (duration: 100.011719ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:13:08.145965Z","caller":"traceutil/trace.go:171","msg":"trace[1966715152] transaction","detail":"{read_only:false; response_revision:16565; number_of_response:1; }","duration":"109.803997ms","start":"2026-09-17T20:13:08.036136Z","end":"2026-09-17T20:13:08.14594Z","steps":["trace[1966715152] 'process raft request' (duration: 109.645351ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:13:08.332193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.424709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-17T20:13:08.332728Z","caller":"traceutil/trace.go:171","msg":"trace[1727207513] linearizableReadLoop","detail":"{readStateIndex:17136; appliedIndex:17135; }","duration":"142.618844ms","start":"2026-09-17T20:13:08.190035Z","end":"2026-09-17T20:13:08.332654Z","steps":["trace[1727207513] 'read index received' (duration: 22.55µs)","trace[1727207513] 'applied index is now lower than readState.Index' (duration: 142.593814ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:13:08.332835Z","caller":"traceutil/trace.go:171","msg":"trace[1047384076] transaction","detail":"{read_only:false; response_revision:16566; number_of_response:1; }","duration":"218.249432ms","start":"2026-09-17T20:13:08.114552Z","end":"2026-09-17T20:13:08.332801Z","steps":["trace[1047384076] 'process raft request' (duration: 52.130103ms)","trace[1047384076] 'compare' (duration: 164.695076ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:13:08.333089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.043247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-17T20:13:08.333325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.438334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-17T20:13:08.33337Z","caller":"traceutil/trace.go:171","msg":"trace[2144057] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16566; }","duration":"104.526116ms","start":"2026-09-17T20:13:08.228832Z","end":"2026-09-17T20:13:08.333358Z","steps":["trace[2144057] 'agreement among raft nodes before linearized reading' (duration: 104.375041ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:13:08.333207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.129758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5450"} {"level":"info","ts":"2026-09-17T20:13:08.33352Z","caller":"traceutil/trace.go:171","msg":"trace[2098818555] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16566; }","duration":"140.48547ms","start":"2026-09-17T20:13:08.192993Z","end":"2026-09-17T20:13:08.333479Z","steps":["trace[2098818555] 'agreement among raft nodes before linearized reading' (duration: 140.074587ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:13:08.334468Z","caller":"traceutil/trace.go:171","msg":"trace[864018679] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16566; }","duration":"143.350276ms","start":"2026-09-17T20:13:08.189999Z","end":"2026-09-17T20:13:08.33335Z","steps":["trace[864018679] 'agreement among raft nodes before linearized reading' (duration: 142.980995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:13:08.333188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.428785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-17T20:13:08.333246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.880371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:13:08.3347Z","caller":"traceutil/trace.go:171","msg":"trace[842032134] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16566; }","duration":"141.334775ms","start":"2026-09-17T20:13:08.193349Z","end":"2026-09-17T20:13:08.334684Z","steps":["trace[842032134] 'agreement among raft nodes before linearized reading' (duration: 139.880911ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:13:08.334622Z","caller":"traceutil/trace.go:171","msg":"trace[522452207] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16566; }","duration":"137.913041ms","start":"2026-09-17T20:13:08.196695Z","end":"2026-09-17T20:13:08.334608Z","steps":["trace[522452207] 'agreement among raft nodes before linearized reading' (duration: 136.454597ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:13:08.68782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.208898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-glxbs\" ","response":"range_response_count:1 size:22015"} {"level":"info","ts":"2026-09-17T20:13:08.687918Z","caller":"traceutil/trace.go:171","msg":"trace[1226954768] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-glxbs; range_end:; response_count:1; response_revision:16567; }","duration":"234.379343ms","start":"2026-09-17T20:13:08.453515Z","end":"2026-09-17T20:13:08.687895Z","steps":["trace[1226954768] 'range keys from in-memory index tree' (duration: 233.95167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:13:08.688032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.382975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"warn","ts":"2026-09-17T20:13:08.688168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.635281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:13:08.688207Z","caller":"traceutil/trace.go:171","msg":"trace[152081460] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16567; }","duration":"108.719004ms","start":"2026-09-17T20:13:08.579478Z","end":"2026-09-17T20:13:08.688197Z","steps":["trace[152081460] 'range keys from in-memory index tree' (duration: 108.525748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:13:08.688344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.726096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-glxbs\" ","response":"range_response_count:1 size:22015"} {"level":"info","ts":"2026-09-17T20:13:08.6884Z","caller":"traceutil/trace.go:171","msg":"trace[534249085] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-glxbs; range_end:; response_count:1; response_revision:16567; }","duration":"217.817419ms","start":"2026-09-17T20:13:08.470567Z","end":"2026-09-17T20:13:08.688385Z","steps":["trace[534249085] 'range keys from in-memory index tree' (duration: 217.52809ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:13:08.688452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.372983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5450"} {"level":"info","ts":"2026-09-17T20:13:08.688491Z","caller":"traceutil/trace.go:171","msg":"trace[1897360807] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16567; }","duration":"106.431894ms","start":"2026-09-17T20:13:08.582049Z","end":"2026-09-17T20:13:08.688481Z","steps":["trace[1897360807] 'range keys from in-memory index tree' (duration: 106.261749ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:13:08.688161Z","caller":"traceutil/trace.go:171","msg":"trace[884315473] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16567; }","duration":"108.566899ms","start":"2026-09-17T20:13:08.579562Z","end":"2026-09-17T20:13:08.688129Z","steps":["trace[884315473] 'range keys from in-memory index tree' (duration: 108.163498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:13:08.689138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.171106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:13:08.689197Z","caller":"traceutil/trace.go:171","msg":"trace[308551054] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:16567; }","duration":"129.28317ms","start":"2026-09-17T20:13:08.559898Z","end":"2026-09-17T20:13:08.689182Z","steps":["trace[308551054] 'count revisions from in-memory index tree' (duration: 129.053523ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:13:09.008893Z","caller":"traceutil/trace.go:171","msg":"trace[1946695768] transaction","detail":"{read_only:false; response_revision:16568; number_of_response:1; }","duration":"150.873745ms","start":"2026-09-17T20:13:08.857988Z","end":"2026-09-17T20:13:09.008861Z","steps":["trace[1946695768] 'process raft request' (duration: 150.611187ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:13:09.032152Z","caller":"traceutil/trace.go:171","msg":"trace[2025487120] linearizableReadLoop","detail":"{readStateIndex:17139; appliedIndex:17137; }","duration":"144.908224ms","start":"2026-09-17T20:13:08.887212Z","end":"2026-09-17T20:13:09.03212Z","steps":["trace[2025487120] 'read index received' (duration: 121.516063ms)","trace[2025487120] 'applied index is now lower than readState.Index' (duration: 23.390891ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:13:09.032204Z","caller":"traceutil/trace.go:171","msg":"trace[1404835286] transaction","detail":"{read_only:false; response_revision:16569; number_of_response:1; }","duration":"145.630376ms","start":"2026-09-17T20:13:08.886539Z","end":"2026-09-17T20:13:09.032169Z","steps":["trace[1404835286] 'process raft request' (duration: 145.402298ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:13:09.032683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.4474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-09-17T20:13:09.032813Z","caller":"traceutil/trace.go:171","msg":"trace[1964952457] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16569; }","duration":"145.625216ms","start":"2026-09-17T20:13:08.887157Z","end":"2026-09-17T20:13:09.032782Z","steps":["trace[1964952457] 'agreement among raft nodes before linearized reading' (duration: 145.121091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:13:09.312967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.042643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-17T20:13:09.313081Z","caller":"traceutil/trace.go:171","msg":"trace[1754841244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16569; }","duration":"210.247559ms","start":"2026-09-17T20:13:09.102805Z","end":"2026-09-17T20:13:09.313053Z","steps":["trace[1754841244] 'range keys from in-memory index tree' (duration: 209.796525ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:13:09.313672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.06451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-17T20:13:09.313723Z","caller":"traceutil/trace.go:171","msg":"trace[1657751227] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16569; }","duration":"173.117692ms","start":"2026-09-17T20:13:09.140591Z","end":"2026-09-17T20:13:09.313708Z","steps":["trace[1657751227] 'range keys from in-memory index tree' (duration: 172.912615ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:13:09.31477Z","caller":"traceutil/trace.go:171","msg":"trace[826539834] linearizableReadLoop","detail":"{readStateIndex:17140; appliedIndex:17139; }","duration":"114.593352ms","start":"2026-09-17T20:13:09.200158Z","end":"2026-09-17T20:13:09.314752Z","steps":["trace[826539834] 'read index received' (duration: 103.695921ms)","trace[826539834] 'applied index is now lower than readState.Index' (duration: 10.895861ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:13:09.314933Z","caller":"traceutil/trace.go:171","msg":"trace[1993301143] transaction","detail":"{read_only:false; response_revision:16570; number_of_response:1; }","duration":"125.901266ms","start":"2026-09-17T20:13:09.188986Z","end":"2026-09-17T20:13:09.314887Z","steps":["trace[1993301143] 'process raft request' (duration: 114.846591ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:13:09.315153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.932783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-17T20:13:09.315222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.102485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5450"} {"level":"info","ts":"2026-09-17T20:13:09.315277Z","caller":"traceutil/trace.go:171","msg":"trace[734350517] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16570; }","duration":"115.115398ms","start":"2026-09-17T20:13:09.200123Z","end":"2026-09-17T20:13:09.315238Z","steps":["trace[734350517] 'agreement among raft nodes before linearized reading' (duration: 114.765707ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:13:09.31536Z","caller":"traceutil/trace.go:171","msg":"trace[1708867280] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16570; }","duration":"107.23547ms","start":"2026-09-17T20:13:09.208092Z","end":"2026-09-17T20:13:09.315327Z","steps":["trace[1708867280] 'agreement among raft nodes before linearized reading' (duration: 106.858358ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:13:09.315196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.781258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:13:09.315494Z","caller":"traceutil/trace.go:171","msg":"trace[793942039] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16570; }","duration":"115.134399ms","start":"2026-09-17T20:13:09.200323Z","end":"2026-09-17T20:13:09.315457Z","steps":["trace[793942039] 'agreement among raft nodes before linearized reading' (duration: 114.667575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:13:09.315519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.86992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-17T20:13:09.315632Z","caller":"traceutil/trace.go:171","msg":"trace[2048890067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16570; }","duration":"109.984793ms","start":"2026-09-17T20:13:09.205623Z","end":"2026-09-17T20:13:09.315608Z","steps":["trace[2048890067] 'agreement among raft nodes before linearized reading' (duration: 109.677694ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:13:09.315669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.325724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:13:09.315899Z","caller":"traceutil/trace.go:171","msg":"trace[533296886] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16570; }","duration":"113.414337ms","start":"2026-09-17T20:13:09.202306Z","end":"2026-09-17T20:13:09.31572Z","steps":["trace[533296886] 'agreement among raft nodes before linearized reading' (duration: 113.023116ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:14:08.173314Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14518} {"level":"info","ts":"2026-09-17T20:14:08.272546Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14518,"took":"95.70721ms","hash":1039241558,"current-db-size-bytes":52359168,"current-db-size":"52 MB","current-db-size-in-use-bytes":30973952,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-17T20:14:08.272888Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1039241558,"revision":14518,"compact-revision":12452} {"level":"info","ts":"2026-09-17T20:14:42.150774Z","caller":"traceutil/trace.go:171","msg":"trace[1784261058] transaction","detail":"{read_only:false; response_revision:17150; number_of_response:1; }","duration":"133.722024ms","start":"2026-09-17T20:14:42.017029Z","end":"2026-09-17T20:14:42.150751Z","steps":["trace[1784261058] 'process raft request' (duration: 89.027856ms)","trace[1784261058] 'compare' (duration: 44.261725ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:14:42.150958Z","caller":"traceutil/trace.go:171","msg":"trace[1292201740] transaction","detail":"{read_only:false; response_revision:17151; number_of_response:1; }","duration":"132.113574ms","start":"2026-09-17T20:14:42.018836Z","end":"2026-09-17T20:14:42.15095Z","steps":["trace[1292201740] 'process raft request' (duration: 131.613189ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:14:42.151054Z","caller":"traceutil/trace.go:171","msg":"trace[1906121526] transaction","detail":"{read_only:false; response_revision:17152; number_of_response:1; }","duration":"131.715162ms","start":"2026-09-17T20:14:42.019333Z","end":"2026-09-17T20:14:42.151048Z","steps":["trace[1906121526] 'process raft request' (duration: 131.151526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:14:42.188807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.739843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:683"} {"level":"info","ts":"2026-09-17T20:14:42.188891Z","caller":"traceutil/trace.go:171","msg":"trace[2131071758] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:17153; }","duration":"130.872288ms","start":"2026-09-17T20:14:42.057997Z","end":"2026-09-17T20:14:42.18887Z","steps":["trace[2131071758] 'agreement among raft nodes before linearized reading' (duration: 130.62379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:14:46.448959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.535547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-6b9vh\" ","response":"range_response_count:1 size:9710"} {"level":"info","ts":"2026-09-17T20:14:46.449178Z","caller":"traceutil/trace.go:171","msg":"trace[2047919698] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-6b9vh; range_end:; response_count:1; response_revision:17190; }","duration":"102.822045ms","start":"2026-09-17T20:14:46.346315Z","end":"2026-09-17T20:14:46.449137Z","steps":["trace[2047919698] 'range keys from in-memory index tree' (duration: 102.288108ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:15:12.528816Z","caller":"traceutil/trace.go:171","msg":"trace[192747758] transaction","detail":"{read_only:false; response_revision:17326; number_of_response:1; }","duration":"163.998754ms","start":"2026-09-17T20:15:12.364778Z","end":"2026-09-17T20:15:12.528777Z","steps":["trace[192747758] 'process raft request' (duration: 163.761557ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:15:12.544819Z","caller":"traceutil/trace.go:171","msg":"trace[1578604743] linearizableReadLoop","detail":"{readStateIndex:17925; appliedIndex:17923; }","duration":"109.847248ms","start":"2026-09-17T20:15:12.434947Z","end":"2026-09-17T20:15:12.544794Z","steps":["trace[1578604743] 'read index received' (duration: 93.585065ms)","trace[1578604743] 'applied index is now lower than readState.Index' (duration: 16.260133ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:15:12.544872Z","caller":"traceutil/trace.go:171","msg":"trace[1864107304] transaction","detail":"{read_only:false; response_revision:17327; number_of_response:1; }","duration":"159.366043ms","start":"2026-09-17T20:15:12.385479Z","end":"2026-09-17T20:15:12.544845Z","steps":["trace[1864107304] 'process raft request' (duration: 159.145726ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:15:12.545044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.085516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-09-17T20:15:12.545075Z","caller":"traceutil/trace.go:171","msg":"trace[1657863248] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:17327; }","duration":"110.192589ms","start":"2026-09-17T20:15:12.434873Z","end":"2026-09-17T20:15:12.545066Z","steps":["trace[1657863248] 'agreement among raft nodes before linearized reading' (duration: 110.045215ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:15:12.662869Z","caller":"traceutil/trace.go:171","msg":"trace[380579908] transaction","detail":"{read_only:false; response_revision:17328; number_of_response:1; }","duration":"113.165209ms","start":"2026-09-17T20:15:12.549664Z","end":"2026-09-17T20:15:12.66283Z","steps":["trace[380579908] 'process raft request' (duration: 95.156981ms)","trace[380579908] 'compare' (duration: 17.765181ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:15:12.667362Z","caller":"traceutil/trace.go:171","msg":"trace[711506722] transaction","detail":"{read_only:false; response_revision:17329; number_of_response:1; }","duration":"117.46558ms","start":"2026-09-17T20:15:12.549846Z","end":"2026-09-17T20:15:12.667312Z","steps":["trace[711506722] 'process raft request' (duration: 117.11908ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:15:13.182877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.394165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-6b9vh\" ","response":"range_response_count:1 size:9710"} {"level":"info","ts":"2026-09-17T20:15:13.183007Z","caller":"traceutil/trace.go:171","msg":"trace[173052262] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-6b9vh; range_end:; response_count:1; response_revision:17331; }","duration":"211.587531ms","start":"2026-09-17T20:15:12.971388Z","end":"2026-09-17T20:15:13.182976Z","steps":["trace[173052262] 'range keys from in-memory index tree' (duration: 211.147668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:15:13.183044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.744842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-btw92\" ","response":"range_response_count:1 size:20559"} {"level":"info","ts":"2026-09-17T20:15:13.183132Z","caller":"traceutil/trace.go:171","msg":"trace[445275240] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-btw92; range_end:; response_count:1; response_revision:17331; }","duration":"202.850535ms","start":"2026-09-17T20:15:12.980261Z","end":"2026-09-17T20:15:13.183112Z","steps":["trace[445275240] 'range keys from in-memory index tree' (duration: 202.504333ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:15:13.183385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.49823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-17T20:15:13.183487Z","caller":"traceutil/trace.go:171","msg":"trace[1858292738] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:17331; }","duration":"242.681886ms","start":"2026-09-17T20:15:12.940782Z","end":"2026-09-17T20:15:13.183464Z","steps":["trace[1858292738] 'count revisions from in-memory index tree' (duration: 242.366917ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:41.500292Z","caller":"traceutil/trace.go:171","msg":"trace[1910989372] linearizableReadLoop","detail":"{readStateIndex:19608; appliedIndex:19607; }","duration":"165.391906ms","start":"2026-09-17T20:18:41.334858Z","end":"2026-09-17T20:18:41.50025Z","steps":["trace[1910989372] 'read index received' (duration: 165.157589ms)","trace[1910989372] 'applied index is now lower than readState.Index' (duration: 233.037µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:18:41.500369Z","caller":"traceutil/trace.go:171","msg":"trace[1238275346] transaction","detail":"{read_only:false; response_revision:18959; number_of_response:1; }","duration":"201.733391ms","start":"2026-09-17T20:18:41.298582Z","end":"2026-09-17T20:18:41.500315Z","steps":["trace[1238275346] 'process raft request' (duration: 201.453032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:41.500785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.876582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7309"} {"level":"info","ts":"2026-09-17T20:18:41.500909Z","caller":"traceutil/trace.go:171","msg":"trace[464908573] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:18959; }","duration":"166.088958ms","start":"2026-09-17T20:18:41.334794Z","end":"2026-09-17T20:18:41.500883Z","steps":["trace[464908573] 'agreement among raft nodes before linearized reading' (duration: 165.631774ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:41.501324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.771826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-09-17T20:18:41.501393Z","caller":"traceutil/trace.go:171","msg":"trace[14713850] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18959; }","duration":"164.894001ms","start":"2026-09-17T20:18:41.336482Z","end":"2026-09-17T20:18:41.501376Z","steps":["trace[14713850] 'agreement among raft nodes before linearized reading' (duration: 163.983554ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:41.951224Z","caller":"traceutil/trace.go:171","msg":"trace[414135660] transaction","detail":"{read_only:false; response_revision:18960; number_of_response:1; }","duration":"120.922395ms","start":"2026-09-17T20:18:41.830252Z","end":"2026-09-17T20:18:41.951174Z","steps":["trace[414135660] 'process raft request' (duration: 111.54582ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:41.951367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.322112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.181\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-17T20:18:41.951486Z","caller":"traceutil/trace.go:171","msg":"trace[324786833] range","detail":"{range_begin:/registry/masterleases/162.253.55.181; range_end:; response_count:1; response_revision:18959; }","duration":"313.125796ms","start":"2026-09-17T20:18:41.63832Z","end":"2026-09-17T20:18:41.951446Z","steps":["trace[324786833] 'range keys from in-memory index tree' (duration: 312.028814ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:41.951543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:18:41.638293Z","time spent":"313.2353ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":164,"request content":"key:\"/registry/masterleases/162.253.55.181\" "} {"level":"info","ts":"2026-09-17T20:18:48.566644Z","caller":"traceutil/trace.go:171","msg":"trace[86941840] linearizableReadLoop","detail":"{readStateIndex:19649; appliedIndex:19648; }","duration":"193.926623ms","start":"2026-09-17T20:18:48.372686Z","end":"2026-09-17T20:18:48.566613Z","steps":["trace[86941840] 'read index received' (duration: 193.599473ms)","trace[86941840] 'applied index is now lower than readState.Index' (duration: 326.06µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:18:48.56678Z","caller":"traceutil/trace.go:171","msg":"trace[721013107] transaction","detail":"{read_only:false; response_revision:18998; number_of_response:1; }","duration":"238.919071ms","start":"2026-09-17T20:18:48.327789Z","end":"2026-09-17T20:18:48.566708Z","steps":["trace[721013107] 'process raft request' (duration: 238.615522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:48.567096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.296777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-17T20:18:48.567205Z","caller":"traceutil/trace.go:171","msg":"trace[1681399076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18998; }","duration":"223.481022ms","start":"2026-09-17T20:18:48.343704Z","end":"2026-09-17T20:18:48.567185Z","steps":["trace[1681399076] 'agreement among raft nodes before linearized reading' (duration: 223.160142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:48.56764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.487534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-17T20:18:48.567673Z","caller":"traceutil/trace.go:171","msg":"trace[1110542712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18998; }","duration":"177.545066ms","start":"2026-09-17T20:18:48.390119Z","end":"2026-09-17T20:18:48.567665Z","steps":["trace[1110542712] 'agreement among raft nodes before linearized reading' (duration: 177.446713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:48.567847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.788909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-17T20:18:48.567935Z","caller":"traceutil/trace.go:171","msg":"trace[164789402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18998; }","duration":"142.078478ms","start":"2026-09-17T20:18:48.425841Z","end":"2026-09-17T20:18:48.56792Z","steps":["trace[164789402] 'agreement among raft nodes before linearized reading' (duration: 141.699267ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:48.567864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.079569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-glxbs\" ","response":"range_response_count:1 size:22015"} {"level":"info","ts":"2026-09-17T20:18:48.568109Z","caller":"traceutil/trace.go:171","msg":"trace[2077634183] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-glxbs; range_end:; response_count:1; response_revision:18998; }","duration":"170.345747ms","start":"2026-09-17T20:18:48.397727Z","end":"2026-09-17T20:18:48.568072Z","steps":["trace[2077634183] 'agreement among raft nodes before linearized reading' (duration: 169.646426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:48.568383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.005355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:940751"} {"level":"info","ts":"2026-09-17T20:18:48.568454Z","caller":"traceutil/trace.go:171","msg":"trace[966144212] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:18998; }","duration":"190.105708ms","start":"2026-09-17T20:18:48.378329Z","end":"2026-09-17T20:18:48.568434Z","steps":["trace[966144212] 'agreement among raft nodes before linearized reading' (duration: 188.797648ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:49.065197Z","caller":"traceutil/trace.go:171","msg":"trace[1050656907] linearizableReadLoop","detail":"{readStateIndex:19653; appliedIndex:19652; }","duration":"209.529158ms","start":"2026-09-17T20:18:48.855629Z","end":"2026-09-17T20:18:49.065158Z","steps":["trace[1050656907] 'read index received' (duration: 209.241119ms)","trace[1050656907] 'applied index is now lower than readState.Index' (duration: 286.579µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:18:49.065234Z","caller":"traceutil/trace.go:171","msg":"trace[1804679169] transaction","detail":"{read_only:false; response_revision:19002; number_of_response:1; }","duration":"237.537538ms","start":"2026-09-17T20:18:48.82767Z","end":"2026-09-17T20:18:49.065207Z","steps":["trace[1804679169] 'process raft request' (duration: 237.24477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:49.065443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.060078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-17T20:18:49.065435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.769945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-17T20:18:49.065511Z","caller":"traceutil/trace.go:171","msg":"trace[1796976806] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:19002; }","duration":"167.211543ms","start":"2026-09-17T20:18:48.898282Z","end":"2026-09-17T20:18:49.065493Z","steps":["trace[1796976806] 'agreement among raft nodes before linearized reading' (duration: 167.076819ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:49.065543Z","caller":"traceutil/trace.go:171","msg":"trace[160139670] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:19002; }","duration":"209.9379ms","start":"2026-09-17T20:18:48.855575Z","end":"2026-09-17T20:18:49.065513Z","steps":["trace[160139670] 'agreement among raft nodes before linearized reading' (duration: 209.719253ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:49.065801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.511369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-09-17T20:18:49.065855Z","caller":"traceutil/trace.go:171","msg":"trace[163985812] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19002; }","duration":"111.616332ms","start":"2026-09-17T20:18:48.954227Z","end":"2026-09-17T20:18:49.065843Z","steps":["trace[163985812] 'agreement among raft nodes before linearized reading' (duration: 111.483778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:49.586052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.293327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-17T20:18:49.586208Z","caller":"traceutil/trace.go:171","msg":"trace[307463234] linearizableReadLoop","detail":"{readStateIndex:19654; appliedIndex:19653; }","duration":"382.314619ms","start":"2026-09-17T20:18:49.203866Z","end":"2026-09-17T20:18:49.586181Z","steps":["trace[307463234] 'read index received' (duration: 257.772586ms)","trace[307463234] 'applied index is now lower than readState.Index' (duration: 124.539293ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:18:49.586241Z","caller":"traceutil/trace.go:171","msg":"trace[1289455072] transaction","detail":"{read_only:false; response_revision:19003; number_of_response:1; }","duration":"512.457374ms","start":"2026-09-17T20:18:49.073742Z","end":"2026-09-17T20:18:49.586199Z","steps":["trace[1289455072] 'process raft request' (duration: 387.864808ms)","trace[1289455072] 'compare' (duration: 124.10058ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:18:49.586444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:18:49.073715Z","time spent":"512.62678ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T20:18:49.58651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.63193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-09-17T20:18:49.586589Z","caller":"traceutil/trace.go:171","msg":"trace[2067341040] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:19003; }","duration":"382.714202ms","start":"2026-09-17T20:18:49.203856Z","end":"2026-09-17T20:18:49.58657Z","steps":["trace[2067341040] 'agreement among raft nodes before linearized reading' (duration: 382.436994ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:49.586652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:18:49.203826Z","time spent":"382.810245ms","remote":"127.0.0.1:45722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1960,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-09-17T20:18:49.831022Z","caller":"traceutil/trace.go:171","msg":"trace[374586894] linearizableReadLoop","detail":"{readStateIndex:19655; appliedIndex:19654; }","duration":"244.707017ms","start":"2026-09-17T20:18:49.586244Z","end":"2026-09-17T20:18:49.830951Z","steps":["trace[374586894] 'read index received' (duration: 198.179733ms)","trace[374586894] 'applied index is now lower than readState.Index' (duration: 46.525464ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:18:49.831067Z","caller":"traceutil/trace.go:171","msg":"trace[2140410178] transaction","detail":"{read_only:false; response_revision:19004; number_of_response:1; }","duration":"295.709897ms","start":"2026-09-17T20:18:49.535307Z","end":"2026-09-17T20:18:49.831017Z","steps":["trace[2140410178] 'process raft request' (duration: 249.248685ms)","trace[2140410178] 'compare' (duration: 46.211144ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:18:49.831426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.425389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7309"} {"level":"info","ts":"2026-09-17T20:18:49.831483Z","caller":"traceutil/trace.go:171","msg":"trace[1717046916] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:19004; }","duration":"296.517812ms","start":"2026-09-17T20:18:49.534948Z","end":"2026-09-17T20:18:49.831466Z","steps":["trace[1717046916] 'agreement among raft nodes before linearized reading' (duration: 296.250973ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:49.831502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.300732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-09-17T20:18:49.831529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.83178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-71217f38-eb0f-427f-b613-161510a2b6f3\" ","response":"range_response_count:1 size:1451"} {"level":"warn","ts":"2026-09-17T20:18:49.831529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.402783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7309"} {"level":"info","ts":"2026-09-17T20:18:49.831603Z","caller":"traceutil/trace.go:171","msg":"trace[2112783518] range","detail":"{range_begin:/registry/persistentvolumes/pvc-71217f38-eb0f-427f-b613-161510a2b6f3; range_end:; response_count:1; response_revision:19004; }","duration":"241.957204ms","start":"2026-09-17T20:18:49.589623Z","end":"2026-09-17T20:18:49.83158Z","steps":["trace[2112783518] 'agreement among raft nodes before linearized reading' (duration: 241.764798ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:49.831616Z","caller":"traceutil/trace.go:171","msg":"trace[633186712] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:19004; }","duration":"308.551818ms","start":"2026-09-17T20:18:49.523045Z","end":"2026-09-17T20:18:49.831597Z","steps":["trace[633186712] 'agreement among raft nodes before linearized reading' (duration: 308.27306ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:49.83167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:18:49.523016Z","time spent":"308.642901ms","remote":"127.0.0.1:45816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":7333,"request content":"key:\"/registry/jobs/openstack/neutron-ks-user\" "} {"level":"info","ts":"2026-09-17T20:18:49.831552Z","caller":"traceutil/trace.go:171","msg":"trace[1006297357] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19004; }","duration":"311.424075ms","start":"2026-09-17T20:18:49.520116Z","end":"2026-09-17T20:18:49.83154Z","steps":["trace[1006297357] 'agreement among raft nodes before linearized reading' (duration: 311.188668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:49.831816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:18:49.52009Z","time spent":"311.709804ms","remote":"127.0.0.1:45732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-09-17T20:18:54.549697Z","caller":"traceutil/trace.go:171","msg":"trace[471891885] transaction","detail":"{read_only:false; response_revision:19045; number_of_response:1; }","duration":"109.567229ms","start":"2026-09-17T20:18:54.440099Z","end":"2026-09-17T20:18:54.549666Z","steps":["trace[471891885] 'process raft request' (duration: 109.381814ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:58.125219Z","caller":"traceutil/trace.go:171","msg":"trace[269990316] linearizableReadLoop","detail":"{readStateIndex:19770; appliedIndex:19769; }","duration":"127.776893ms","start":"2026-09-17T20:18:57.997415Z","end":"2026-09-17T20:18:58.125192Z","steps":["trace[269990316] 'read index received' (duration: 127.630599ms)","trace[269990316] 'applied index is now lower than readState.Index' (duration: 144.904µs)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:18:58.125449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.00166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:18:58.125522Z","caller":"traceutil/trace.go:171","msg":"trace[1397106725] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:19113; }","duration":"128.144025ms","start":"2026-09-17T20:18:57.997364Z","end":"2026-09-17T20:18:58.125508Z","steps":["trace[1397106725] 'agreement among raft nodes before linearized reading' (duration: 127.99831ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:58.125344Z","caller":"traceutil/trace.go:171","msg":"trace[1162579247] transaction","detail":"{read_only:false; response_revision:19113; number_of_response:1; }","duration":"138.20517ms","start":"2026-09-17T20:18:57.987102Z","end":"2026-09-17T20:18:58.125307Z","steps":["trace[1162579247] 'process raft request' (duration: 137.87554ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:58.157205Z","caller":"traceutil/trace.go:171","msg":"trace[1187675510] transaction","detail":"{read_only:false; response_revision:19114; number_of_response:1; }","duration":"157.180287ms","start":"2026-09-17T20:18:57.999981Z","end":"2026-09-17T20:18:58.157161Z","steps":["trace[1187675510] 'process raft request' (duration: 156.857817ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:58.157297Z","caller":"traceutil/trace.go:171","msg":"trace[1441582678] transaction","detail":"{read_only:false; response_revision:19115; number_of_response:1; }","duration":"157.003051ms","start":"2026-09-17T20:18:58.000249Z","end":"2026-09-17T20:18:58.157252Z","steps":["trace[1441582678] 'process raft request' (duration: 156.822766ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:58.157459Z","caller":"traceutil/trace.go:171","msg":"trace[369827779] transaction","detail":"{read_only:false; response_revision:19117; number_of_response:1; }","duration":"156.832936ms","start":"2026-09-17T20:18:58.000592Z","end":"2026-09-17T20:18:58.157425Z","steps":["trace[369827779] 'process raft request' (duration: 156.60602ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:58.15754Z","caller":"traceutil/trace.go:171","msg":"trace[1024151231] transaction","detail":"{read_only:false; response_revision:19116; number_of_response:1; }","duration":"157.053862ms","start":"2026-09-17T20:18:58.000475Z","end":"2026-09-17T20:18:58.157529Z","steps":["trace[1024151231] 'process raft request' (duration: 156.656711ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:58.157635Z","caller":"traceutil/trace.go:171","msg":"trace[1015028812] transaction","detail":"{read_only:false; response_revision:19118; number_of_response:1; }","duration":"157.010831ms","start":"2026-09-17T20:18:58.000586Z","end":"2026-09-17T20:18:58.157597Z","steps":["trace[1015028812] 'process raft request' (duration: 156.656701ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:58.157888Z","caller":"traceutil/trace.go:171","msg":"trace[125872240] transaction","detail":"{read_only:false; response_revision:19119; number_of_response:1; }","duration":"157.266449ms","start":"2026-09-17T20:18:58.000612Z","end":"2026-09-17T20:18:58.157879Z","steps":["trace[125872240] 'process raft request' (duration: 156.679071ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:58.158128Z","caller":"traceutil/trace.go:171","msg":"trace[681417656] transaction","detail":"{read_only:false; response_revision:19120; number_of_response:1; }","duration":"156.246639ms","start":"2026-09-17T20:18:58.001872Z","end":"2026-09-17T20:18:58.158118Z","steps":["trace[681417656] 'process raft request' (duration: 155.473535ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:58.15814Z","caller":"traceutil/trace.go:171","msg":"trace[1644846901] transaction","detail":"{read_only:false; response_revision:19121; number_of_response:1; }","duration":"132.445655ms","start":"2026-09-17T20:18:58.025677Z","end":"2026-09-17T20:18:58.158122Z","steps":["trace[1644846901] 'process raft request' (duration: 131.756364ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:58.502327Z","caller":"traceutil/trace.go:171","msg":"trace[1674438672] linearizableReadLoop","detail":"{readStateIndex:19791; appliedIndex:19789; }","duration":"119.297355ms","start":"2026-09-17T20:18:58.382997Z","end":"2026-09-17T20:18:58.502294Z","steps":["trace[1674438672] 'read index received' (duration: 119.158151ms)","trace[1674438672] 'applied index is now lower than readState.Index' (duration: 138.244µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:18:58.502377Z","caller":"traceutil/trace.go:171","msg":"trace[718750] transaction","detail":"{read_only:false; response_revision:19132; number_of_response:1; }","duration":"123.559995ms","start":"2026-09-17T20:18:58.378772Z","end":"2026-09-17T20:18:58.502332Z","steps":["trace[718750] 'process raft request' (duration: 123.320797ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:58.502443Z","caller":"traceutil/trace.go:171","msg":"trace[946608582] transaction","detail":"{read_only:false; response_revision:19133; number_of_response:1; }","duration":"123.66961ms","start":"2026-09-17T20:18:58.378762Z","end":"2026-09-17T20:18:58.502432Z","steps":["trace[946608582] 'process raft request' (duration: 123.493083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:58.502796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.76578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-09-17T20:18:58.502901Z","caller":"traceutil/trace.go:171","msg":"trace[971215533] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:19133; }","duration":"119.879934ms","start":"2026-09-17T20:18:58.382993Z","end":"2026-09-17T20:18:58.502873Z","steps":["trace[971215533] 'agreement among raft nodes before linearized reading' (duration: 119.525273ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:58.534388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.098027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-17T20:18:58.534464Z","caller":"traceutil/trace.go:171","msg":"trace[343842168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19135; }","duration":"114.231231ms","start":"2026-09-17T20:18:58.420214Z","end":"2026-09-17T20:18:58.534445Z","steps":["trace[343842168] 'agreement among raft nodes before linearized reading' (duration: 114.000314ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:58.534503Z","caller":"traceutil/trace.go:171","msg":"trace[1792581748] transaction","detail":"{read_only:false; response_revision:19134; number_of_response:1; }","duration":"117.033627ms","start":"2026-09-17T20:18:58.417441Z","end":"2026-09-17T20:18:58.534475Z","steps":["trace[1792581748] 'process raft request' (duration: 116.45879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:58.534828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.400182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-17T20:18:58.534878Z","caller":"traceutil/trace.go:171","msg":"trace[1361437358] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:19135; }","duration":"150.475104ms","start":"2026-09-17T20:18:58.38439Z","end":"2026-09-17T20:18:58.534865Z","steps":["trace[1361437358] 'agreement among raft nodes before linearized reading' (duration: 150.34579ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:58.673326Z","caller":"traceutil/trace.go:171","msg":"trace[605339083] transaction","detail":"{read_only:false; response_revision:19138; number_of_response:1; }","duration":"113.716966ms","start":"2026-09-17T20:18:58.55957Z","end":"2026-09-17T20:18:58.673287Z","steps":["trace[605339083] 'process raft request' (duration: 113.437097ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:58.673415Z","caller":"traceutil/trace.go:171","msg":"trace[190013491] transaction","detail":"{read_only:false; response_revision:19137; number_of_response:1; }","duration":"133.939101ms","start":"2026-09-17T20:18:58.539453Z","end":"2026-09-17T20:18:58.673392Z","steps":["trace[190013491] 'process raft request' (duration: 133.184188ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:18:58.673567Z","caller":"traceutil/trace.go:171","msg":"trace[127623832] linearizableReadLoop","detail":"{readStateIndex:19796; appliedIndex:19794; }","duration":"105.102085ms","start":"2026-09-17T20:18:58.568035Z","end":"2026-09-17T20:18:58.673137Z","steps":["trace[127623832] 'read index received' (duration: 104.805066ms)","trace[127623832] 'applied index is now lower than readState.Index' (duration: 295.999µs)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:18:58.673927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.33272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:18:58.674183Z","caller":"traceutil/trace.go:171","msg":"trace[1618191898] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:19138; }","duration":"127.724361ms","start":"2026-09-17T20:18:58.546443Z","end":"2026-09-17T20:18:58.674167Z","steps":["trace[1618191898] 'agreement among raft nodes before linearized reading' (duration: 127.153914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:18:58.67571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.251338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:18:58.675824Z","caller":"traceutil/trace.go:171","msg":"trace[631166181] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:19140; }","duration":"104.402983ms","start":"2026-09-17T20:18:58.571397Z","end":"2026-09-17T20:18:58.675799Z","steps":["trace[631166181] 'agreement among raft nodes before linearized reading' (duration: 104.208447ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:19:08.184183Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16939} {"level":"info","ts":"2026-09-17T20:19:08.320382Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16939,"took":"132.970871ms","hash":1798721183,"current-db-size-bytes":52359168,"current-db-size":"52 MB","current-db-size-in-use-bytes":32067584,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-17T20:19:08.32048Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1798721183,"revision":16939,"compact-revision":14518} {"level":"info","ts":"2026-09-17T20:19:21.296647Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"865c2c1f02504fb9","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-17T20:19:21.303757Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-17T20:19:21.303906Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-17T20:23:11.295209Z","caller":"traceutil/trace.go:171","msg":"trace[966990341] transaction","detail":"{read_only:false; response_revision:21105; number_of_response:1; }","duration":"101.784336ms","start":"2026-09-17T20:23:11.1934Z","end":"2026-09-17T20:23:11.295185Z","steps":["trace[966990341] 'process raft request' (duration: 101.589209ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:23:11.336385Z","caller":"traceutil/trace.go:171","msg":"trace[2099262632] transaction","detail":"{read_only:false; response_revision:21106; number_of_response:1; }","duration":"142.298048ms","start":"2026-09-17T20:23:11.194058Z","end":"2026-09-17T20:23:11.336356Z","steps":["trace[2099262632] 'process raft request' (duration: 142.070551ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:23:11.336983Z","caller":"traceutil/trace.go:171","msg":"trace[598423432] transaction","detail":"{read_only:false; response_revision:21107; number_of_response:1; }","duration":"142.266556ms","start":"2026-09-17T20:23:11.194687Z","end":"2026-09-17T20:23:11.336954Z","steps":["trace[598423432] 'process raft request' (duration: 141.588755ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:23:11.337447Z","caller":"traceutil/trace.go:171","msg":"trace[435975712] transaction","detail":"{read_only:false; response_revision:21108; number_of_response:1; }","duration":"142.388791ms","start":"2026-09-17T20:23:11.195048Z","end":"2026-09-17T20:23:11.337437Z","steps":["trace[435975712] 'process raft request' (duration: 141.662708ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:23:11.337732Z","caller":"traceutil/trace.go:171","msg":"trace[1406167549] transaction","detail":"{read_only:false; response_revision:21109; number_of_response:1; }","duration":"139.075819ms","start":"2026-09-17T20:23:11.198647Z","end":"2026-09-17T20:23:11.337723Z","steps":["trace[1406167549] 'process raft request' (duration: 138.585165ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:23:14.364605Z","caller":"traceutil/trace.go:171","msg":"trace[1984168781] transaction","detail":"{read_only:false; response_revision:21124; number_of_response:1; }","duration":"105.588232ms","start":"2026-09-17T20:23:14.258987Z","end":"2026-09-17T20:23:14.364575Z","steps":["trace[1984168781] 'process raft request' (duration: 105.286423ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:23:14.491466Z","caller":"traceutil/trace.go:171","msg":"trace[149643771] transaction","detail":"{read_only:false; response_revision:21126; number_of_response:1; }","duration":"121.503384ms","start":"2026-09-17T20:23:14.369921Z","end":"2026-09-17T20:23:14.491425Z","steps":["trace[149643771] 'process raft request' (duration: 53.257159ms)","trace[149643771] 'compare' (duration: 67.511714ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:23:14.492212Z","caller":"traceutil/trace.go:171","msg":"trace[1691557728] transaction","detail":"{read_only:false; response_revision:21127; number_of_response:1; }","duration":"121.623079ms","start":"2026-09-17T20:23:14.37055Z","end":"2026-09-17T20:23:14.492173Z","steps":["trace[1691557728] 'process raft request' (duration: 121.405192ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:23:15.669021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.742778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-17T20:23:15.669207Z","caller":"traceutil/trace.go:171","msg":"trace[699675917] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21130; }","duration":"354.964855ms","start":"2026-09-17T20:23:15.314217Z","end":"2026-09-17T20:23:15.669182Z","steps":["trace[699675917] 'count revisions from in-memory index tree' (duration: 354.592753ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:23:15.669324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:23:15.314139Z","time spent":"355.166932ms","remote":"127.0.0.1:54028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-09-17T20:23:15.670597Z","caller":"traceutil/trace.go:171","msg":"trace[710294432] transaction","detail":"{read_only:false; response_revision:21131; number_of_response:1; }","duration":"272.672672ms","start":"2026-09-17T20:23:15.396812Z","end":"2026-09-17T20:23:15.669485Z","steps":["trace[710294432] 'process raft request' (duration: 199.2954ms)","trace[710294432] 'compare' (duration: 73.00064ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:23:17.313262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.841181ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-09-17T20:23:17.370277Z","caller":"traceutil/trace.go:171","msg":"trace[1996830840] transaction","detail":"{read_only:false; response_revision:21140; number_of_response:1; }","duration":"188.572454ms","start":"2026-09-17T20:23:17.181657Z","end":"2026-09-17T20:23:17.37023Z","steps":["trace[1996830840] 'process raft request' (duration: 188.323936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:23:18.793016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.690138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-17T20:23:18.793207Z","caller":"traceutil/trace.go:171","msg":"trace[448458462] transaction","detail":"{read_only:false; response_revision:21146; number_of_response:1; }","duration":"234.593895ms","start":"2026-09-17T20:23:18.558567Z","end":"2026-09-17T20:23:18.793161Z","steps":["trace[448458462] 'process raft request' (duration: 95.643888ms)","trace[448458462] 'compare' (duration: 138.487702ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:23:19.137867Z","caller":"traceutil/trace.go:171","msg":"trace[973358639] transaction","detail":"{read_only:false; response_revision:21148; number_of_response:1; }","duration":"164.619246ms","start":"2026-09-17T20:23:18.973173Z","end":"2026-09-17T20:23:19.137792Z","steps":["trace[973358639] 'process raft request' (duration: 78.241999ms)","trace[973358639] 'compare' (duration: 86.086969ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:23:19.502935Z","caller":"traceutil/trace.go:171","msg":"trace[1090090193] transaction","detail":"{read_only:false; response_revision:21150; number_of_response:1; }","duration":"126.397965ms","start":"2026-09-17T20:23:19.376504Z","end":"2026-09-17T20:23:19.502902Z","steps":["trace[1090090193] 'process raft request' (duration: 30.341893ms)","trace[1090090193] 'compare' (duration: 95.505954ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:23:21.066983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.436748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-17T20:23:21.067296Z","caller":"traceutil/trace.go:171","msg":"trace[1971175952] transaction","detail":"{read_only:false; response_revision:21154; number_of_response:1; }","duration":"516.207729ms","start":"2026-09-17T20:23:20.551003Z","end":"2026-09-17T20:23:21.067211Z","steps":["trace[1971175952] 'process raft request' (duration: 333.374149ms)","trace[1971175952] 'compare' (duration: 181.027216ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:23:21.06751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:23:20.550986Z","time spent":"516.421095ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T20:23:21.067566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.35275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-17T20:23:21.067656Z","caller":"traceutil/trace.go:171","msg":"trace[1341736725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21154; }","duration":"510.477624ms","start":"2026-09-17T20:23:20.557156Z","end":"2026-09-17T20:23:21.067634Z","steps":["trace[1341736725] 'agreement among raft nodes before linearized reading' (duration: 510.172735ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:23:21.067696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:23:20.557144Z","time spent":"510.544135ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-17T20:23:21.06862Z","caller":"traceutil/trace.go:171","msg":"trace[1881668891] linearizableReadLoop","detail":"{readStateIndex:21870; appliedIndex:21869; }","duration":"509.909557ms","start":"2026-09-17T20:23:20.557183Z","end":"2026-09-17T20:23:21.067092Z","steps":["trace[1881668891] 'read index received' (duration: 327.070656ms)","trace[1881668891] 'applied index is now lower than readState.Index' (duration: 182.837211ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:23:21.090628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.213587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-09-17T20:23:21.090637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.064028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:23:21.090737Z","caller":"traceutil/trace.go:171","msg":"trace[915953720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21155; }","duration":"292.402072ms","start":"2026-09-17T20:23:20.798309Z","end":"2026-09-17T20:23:21.090711Z","steps":["trace[915953720] 'agreement among raft nodes before linearized reading' (duration: 292.200995ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:23:21.090756Z","caller":"traceutil/trace.go:171","msg":"trace[1513472035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21155; }","duration":"333.218314ms","start":"2026-09-17T20:23:20.757504Z","end":"2026-09-17T20:23:21.090723Z","steps":["trace[1513472035] 'agreement among raft nodes before linearized reading' (duration: 332.979015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:23:21.09081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:23:20.757496Z","time spent":"333.305536ms","remote":"127.0.0.1:45552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-17T20:23:21.091348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.970537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:23:21.091391Z","caller":"traceutil/trace.go:171","msg":"trace[693798755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21155; }","duration":"362.041649ms","start":"2026-09-17T20:23:20.729338Z","end":"2026-09-17T20:23:21.09138Z","steps":["trace[693798755] 'agreement among raft nodes before linearized reading' (duration: 361.977567ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:23:21.091419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:23:20.729317Z","time spent":"362.095661ms","remote":"127.0.0.1:45536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-17T20:23:21.093107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.744323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:23:21.093648Z","caller":"traceutil/trace.go:171","msg":"trace[481112581] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:21155; }","duration":"467.280291ms","start":"2026-09-17T20:23:20.626054Z","end":"2026-09-17T20:23:21.093334Z","steps":["trace[481112581] 'agreement among raft nodes before linearized reading' (duration: 463.112254ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:23:21.093777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:23:20.626027Z","time spent":"467.673522ms","remote":"127.0.0.1:56948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"info","ts":"2026-09-17T20:23:21.245889Z","caller":"traceutil/trace.go:171","msg":"trace[444345865] linearizableReadLoop","detail":"{readStateIndex:21873; appliedIndex:21871; }","duration":"149.669251ms","start":"2026-09-17T20:23:21.096189Z","end":"2026-09-17T20:23:21.245858Z","steps":["trace[444345865] 'read index received' (duration: 38.786999ms)","trace[444345865] 'applied index is now lower than readState.Index' (duration: 110.881082ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:23:21.246304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.081114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-api-5cb8b9c7b6-4dc76.18d635609d148387\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-09-17T20:23:21.246354Z","caller":"traceutil/trace.go:171","msg":"trace[140321911] range","detail":"{range_begin:/registry/events/openstack/heat-api-5cb8b9c7b6-4dc76.18d635609d148387; range_end:; response_count:1; response_revision:21158; }","duration":"150.174357ms","start":"2026-09-17T20:23:21.096164Z","end":"2026-09-17T20:23:21.246338Z","steps":["trace[140321911] 'agreement among raft nodes before linearized reading' (duration: 149.830357ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:23:21.246618Z","caller":"traceutil/trace.go:171","msg":"trace[1754911358] transaction","detail":"{read_only:false; response_revision:21157; number_of_response:1; }","duration":"150.60311ms","start":"2026-09-17T20:23:21.095978Z","end":"2026-09-17T20:23:21.246581Z","steps":["trace[1754911358] 'process raft request' (duration: 134.744368ms)","trace[1754911358] 'compare' (duration: 14.425229ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:23:21.246663Z","caller":"traceutil/trace.go:171","msg":"trace[2009419126] transaction","detail":"{read_only:false; response_revision:21158; number_of_response:1; }","duration":"149.61306ms","start":"2026-09-17T20:23:21.097031Z","end":"2026-09-17T20:23:21.246644Z","steps":["trace[2009419126] 'process raft request' (duration: 148.740783ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:23:21.25963Z","caller":"traceutil/trace.go:171","msg":"trace[1587785667] transaction","detail":"{read_only:false; response_revision:21159; number_of_response:1; }","duration":"116.042599ms","start":"2026-09-17T20:23:21.143555Z","end":"2026-09-17T20:23:21.259597Z","steps":["trace[1587785667] 'process raft request' (duration: 115.865433ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:23:21.591027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.311414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-17T20:23:21.591334Z","caller":"traceutil/trace.go:171","msg":"trace[1604714815] transaction","detail":"{read_only:false; response_revision:21163; number_of_response:1; }","duration":"115.843833ms","start":"2026-09-17T20:23:21.475419Z","end":"2026-09-17T20:23:21.591263Z","steps":["trace[1604714815] 'compare' (duration: 107.864411ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:23:21.941466Z","caller":"traceutil/trace.go:171","msg":"trace[982084415] transaction","detail":"{read_only:false; response_revision:21165; number_of_response:1; }","duration":"166.202654ms","start":"2026-09-17T20:23:21.775225Z","end":"2026-09-17T20:23:21.941428Z","steps":["trace[982084415] 'process raft request' (duration: 120.67144ms)","trace[982084415] 'compare' (duration: 45.149553ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:23:21.942089Z","caller":"traceutil/trace.go:171","msg":"trace[1279094014] transaction","detail":"{read_only:false; response_revision:21167; number_of_response:1; }","duration":"142.359169ms","start":"2026-09-17T20:23:21.799715Z","end":"2026-09-17T20:23:21.942074Z","steps":["trace[1279094014] 'process raft request' (duration: 142.307468ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:23:21.942225Z","caller":"traceutil/trace.go:171","msg":"trace[1379620280] transaction","detail":"{read_only:false; response_revision:21166; number_of_response:1; }","duration":"150.323562ms","start":"2026-09-17T20:23:21.791863Z","end":"2026-09-17T20:23:21.942187Z","steps":["trace[1379620280] 'process raft request' (duration: 150.054094ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:23:23.395406Z","caller":"traceutil/trace.go:171","msg":"trace[626557325] transaction","detail":"{read_only:false; response_revision:21173; number_of_response:1; }","duration":"129.946652ms","start":"2026-09-17T20:23:23.265426Z","end":"2026-09-17T20:23:23.395373Z","steps":["trace[626557325] 'process raft request' (duration: 129.86824ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:23:23.395476Z","caller":"traceutil/trace.go:171","msg":"trace[738541501] transaction","detail":"{read_only:false; response_revision:21172; number_of_response:1; }","duration":"139.06932ms","start":"2026-09-17T20:23:23.256371Z","end":"2026-09-17T20:23:23.395441Z","steps":["trace[738541501] 'process raft request' (duration: 124.433805ms)","trace[738541501] 'compare' (duration: 14.270493ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:23:25.405946Z","caller":"traceutil/trace.go:171","msg":"trace[1807888831] transaction","detail":"{read_only:false; response_revision:21183; number_of_response:1; }","duration":"112.156912ms","start":"2026-09-17T20:23:25.293739Z","end":"2026-09-17T20:23:25.405896Z","steps":["trace[1807888831] 'process raft request' (duration: 72.930029ms)","trace[1807888831] 'compare' (duration: 38.954134ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:23:26.247622Z","caller":"traceutil/trace.go:171","msg":"trace[1113666897] transaction","detail":"{read_only:false; response_revision:21187; number_of_response:1; }","duration":"237.999778ms","start":"2026-09-17T20:23:26.009585Z","end":"2026-09-17T20:23:26.247584Z","steps":["trace[1113666897] 'process raft request' (duration: 237.777631ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:23:26.618754Z","caller":"traceutil/trace.go:171","msg":"trace[1115263614] linearizableReadLoop","detail":"{readStateIndex:21905; appliedIndex:21904; }","duration":"333.402459ms","start":"2026-09-17T20:23:26.285312Z","end":"2026-09-17T20:23:26.618714Z","steps":["trace[1115263614] 'read index received' (duration: 333.059529ms)","trace[1115263614] 'applied index is now lower than readState.Index' (duration: 341.22µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:23:26.618889Z","caller":"traceutil/trace.go:171","msg":"trace[1238669949] transaction","detail":"{read_only:false; response_revision:21188; number_of_response:1; }","duration":"581.906107ms","start":"2026-09-17T20:23:26.036898Z","end":"2026-09-17T20:23:26.618804Z","steps":["trace[1238669949] 'process raft request' (duration: 581.537826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:23:26.619131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:23:26.036863Z","time spent":"582.128253ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T20:23:26.619199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.128001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:23:26.619268Z","caller":"traceutil/trace.go:171","msg":"trace[1353529327] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:21188; }","duration":"163.209183ms","start":"2026-09-17T20:23:26.45604Z","end":"2026-09-17T20:23:26.619249Z","steps":["trace[1353529327] 'agreement among raft nodes before linearized reading' (duration: 163.020028ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:23:26.619181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.823791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-17T20:23:26.61935Z","caller":"traceutil/trace.go:171","msg":"trace[2061244368] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:21188; }","duration":"334.035027ms","start":"2026-09-17T20:23:26.285294Z","end":"2026-09-17T20:23:26.619329Z","steps":["trace[2061244368] 'agreement among raft nodes before linearized reading' (duration: 333.667807ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:23:26.619416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:23:26.285224Z","time spent":"334.171442ms","remote":"127.0.0.1:45672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":13,"response size":32,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-09-17T20:23:26.87836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.225661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-17T20:23:26.878449Z","caller":"traceutil/trace.go:171","msg":"trace[1497214583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21188; }","duration":"228.381095ms","start":"2026-09-17T20:23:26.650045Z","end":"2026-09-17T20:23:26.878426Z","steps":["trace[1497214583] 'range keys from in-memory index tree' (duration: 228.003614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:23:27.443306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.320624ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-09-17T20:23:27.443444Z","caller":"traceutil/trace.go:171","msg":"trace[1136177056] linearizableReadLoop","detail":"{readStateIndex:21908; appliedIndex:21907; }","duration":"247.177788ms","start":"2026-09-17T20:23:27.196242Z","end":"2026-09-17T20:23:27.44342Z","steps":["trace[1136177056] 'read index received' (duration: 53.412µs)","trace[1136177056] 'applied index is now lower than readState.Index' (duration: 247.122546ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:23:27.443909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.646171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-17T20:23:27.443967Z","caller":"traceutil/trace.go:171","msg":"trace[71805639] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21190; }","duration":"247.771206ms","start":"2026-09-17T20:23:27.19618Z","end":"2026-09-17T20:23:27.443952Z","steps":["trace[71805639] 'agreement among raft nodes before linearized reading' (duration: 247.487567ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:23:27.459145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.788342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:23:27.45922Z","caller":"traceutil/trace.go:171","msg":"trace[1755427858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21191; }","duration":"188.916225ms","start":"2026-09-17T20:23:27.270287Z","end":"2026-09-17T20:23:27.459203Z","steps":["trace[1755427858] 'agreement among raft nodes before linearized reading' (duration: 188.796662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:23:27.459776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.26242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-17T20:23:27.459834Z","caller":"traceutil/trace.go:171","msg":"trace[1954912983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21191; }","duration":"177.331142ms","start":"2026-09-17T20:23:27.282476Z","end":"2026-09-17T20:23:27.459808Z","steps":["trace[1954912983] 'agreement among raft nodes before linearized reading' (duration: 177.211549ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:23:31.178736Z","caller":"traceutil/trace.go:171","msg":"trace[1808652524] transaction","detail":"{read_only:false; response_revision:21212; number_of_response:1; }","duration":"119.3149ms","start":"2026-09-17T20:23:31.059374Z","end":"2026-09-17T20:23:31.178689Z","steps":["trace[1808652524] 'process raft request' (duration: 102.89851ms)","trace[1808652524] 'compare' (duration: 16.182152ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:24:08.20166Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19234} {"level":"info","ts":"2026-09-17T20:24:08.318327Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19234,"took":"112.580273ms","hash":2180623717,"current-db-size-bytes":52359168,"current-db-size":"52 MB","current-db-size-in-use-bytes":34660352,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-17T20:24:08.318422Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2180623717,"revision":19234,"compact-revision":16939} {"level":"info","ts":"2026-09-17T20:24:38.229041Z","caller":"traceutil/trace.go:171","msg":"trace[1614230797] transaction","detail":"{read_only:false; response_revision:21756; number_of_response:1; }","duration":"148.815257ms","start":"2026-09-17T20:24:38.080177Z","end":"2026-09-17T20:24:38.228992Z","steps":["trace[1614230797] 'process raft request' (duration: 137.364718ms)","trace[1614230797] 'compare' (duration: 10.797848ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:25:22.078148Z","caller":"traceutil/trace.go:171","msg":"trace[1892884196] transaction","detail":"{read_only:false; response_revision:22029; number_of_response:1; }","duration":"102.218978ms","start":"2026-09-17T20:25:21.975882Z","end":"2026-09-17T20:25:22.078101Z","steps":["trace[1892884196] 'process raft request' (duration: 102.012322ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:25:38.28099Z","caller":"traceutil/trace.go:171","msg":"trace[469849760] transaction","detail":"{read_only:false; response_revision:22110; number_of_response:1; }","duration":"134.598734ms","start":"2026-09-17T20:25:38.146361Z","end":"2026-09-17T20:25:38.28096Z","steps":["trace[469849760] 'process raft request' (duration: 134.198262ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:25:38.281976Z","caller":"traceutil/trace.go:171","msg":"trace[1934766544] linearizableReadLoop","detail":"{readStateIndex:22864; appliedIndex:22862; }","duration":"107.785478ms","start":"2026-09-17T20:25:38.174161Z","end":"2026-09-17T20:25:38.281946Z","steps":["trace[1934766544] 'read index received' (duration: 106.370425ms)","trace[1934766544] 'applied index is now lower than readState.Index' (duration: 1.413823ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:25:38.282181Z","caller":"traceutil/trace.go:171","msg":"trace[778116223] transaction","detail":"{read_only:false; response_revision:22111; number_of_response:1; }","duration":"121.855306ms","start":"2026-09-17T20:25:38.160305Z","end":"2026-09-17T20:25:38.282161Z","steps":["trace[778116223] 'process raft request' (duration: 121.492555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:25:38.28221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.037605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-17T20:25:38.282254Z","caller":"traceutil/trace.go:171","msg":"trace[286094096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22111; }","duration":"108.20198ms","start":"2026-09-17T20:25:38.17404Z","end":"2026-09-17T20:25:38.282242Z","steps":["trace[286094096] 'agreement among raft nodes before linearized reading' (duration: 108.018384ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:25:44.136478Z","caller":"traceutil/trace.go:171","msg":"trace[152766789] transaction","detail":"{read_only:false; response_revision:22142; number_of_response:1; }","duration":"112.566522ms","start":"2026-09-17T20:25:44.023867Z","end":"2026-09-17T20:25:44.136434Z","steps":["trace[152766789] 'process raft request' (duration: 112.329385ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:25:44.510086Z","caller":"traceutil/trace.go:171","msg":"trace[1300830271] transaction","detail":"{read_only:false; response_revision:22143; number_of_response:1; }","duration":"204.677844ms","start":"2026-09-17T20:25:44.305375Z","end":"2026-09-17T20:25:44.510053Z","steps":["trace[1300830271] 'process raft request' (duration: 204.458407ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:25:44.510429Z","caller":"traceutil/trace.go:171","msg":"trace[741482418] linearizableReadLoop","detail":"{readStateIndex:22897; appliedIndex:22897; }","duration":"197.063422ms","start":"2026-09-17T20:25:44.31334Z","end":"2026-09-17T20:25:44.510403Z","steps":["trace[741482418] 'read index received' (duration: 197.045982ms)","trace[741482418] 'applied index is now lower than readState.Index' (duration: 12.4µs)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:25:44.510689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.338021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-17T20:25:44.51079Z","caller":"traceutil/trace.go:171","msg":"trace[1341586988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22143; }","duration":"197.490795ms","start":"2026-09-17T20:25:44.313247Z","end":"2026-09-17T20:25:44.510738Z","steps":["trace[1341586988] 'agreement among raft nodes before linearized reading' (duration: 197.284738ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:25:45.063401Z","caller":"traceutil/trace.go:171","msg":"trace[1155557223] transaction","detail":"{read_only:false; response_revision:22145; number_of_response:1; }","duration":"547.067147ms","start":"2026-09-17T20:25:44.5163Z","end":"2026-09-17T20:25:45.063367Z","steps":["trace[1155557223] 'process raft request' (duration: 546.815359ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:25:45.063465Z","caller":"traceutil/trace.go:171","msg":"trace[602862410] linearizableReadLoop","detail":"{readStateIndex:22903; appliedIndex:22898; }","duration":"332.963205ms","start":"2026-09-17T20:25:44.730486Z","end":"2026-09-17T20:25:45.063449Z","steps":["trace[602862410] 'read index received' (duration: 332.745599ms)","trace[602862410] 'applied index is now lower than readState.Index' (duration: 216.916µs)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:25:45.063569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:25:44.516284Z","time spent":"547.19605ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T20:25:45.063606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.123509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:25:45.063654Z","caller":"traceutil/trace.go:171","msg":"trace[1835545088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22149; }","duration":"333.41891ms","start":"2026-09-17T20:25:44.730222Z","end":"2026-09-17T20:25:45.06364Z","steps":["trace[1835545088] 'agreement among raft nodes before linearized reading' (duration: 333.286246ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:25:45.063684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:25:44.730183Z","time spent":"333.494512ms","remote":"127.0.0.1:45552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-17T20:25:45.063655Z","caller":"traceutil/trace.go:171","msg":"trace[461483737] transaction","detail":"{read_only:false; response_revision:22149; number_of_response:1; }","duration":"496.657053ms","start":"2026-09-17T20:25:44.566943Z","end":"2026-09-17T20:25:45.0636Z","steps":["trace[461483737] 'process raft request' (duration: 496.461257ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:25:45.063781Z","caller":"traceutil/trace.go:171","msg":"trace[2038390516] transaction","detail":"{read_only:false; response_revision:22147; number_of_response:1; }","duration":"547.179169ms","start":"2026-09-17T20:25:44.516442Z","end":"2026-09-17T20:25:45.063621Z","steps":["trace[2038390516] 'process raft request' (duration: 546.885821ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:25:45.064003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:25:44.566928Z","time spent":"496.906552ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T20:25:45.063518Z","caller":"traceutil/trace.go:171","msg":"trace[1845791169] transaction","detail":"{read_only:false; response_revision:22146; number_of_response:1; }","duration":"547.069286ms","start":"2026-09-17T20:25:44.516388Z","end":"2026-09-17T20:25:45.063457Z","steps":["trace[1845791169] 'process raft request' (duration: 546.896741ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:25:45.064242Z","caller":"traceutil/trace.go:171","msg":"trace[846118951] transaction","detail":"{read_only:false; response_revision:22148; number_of_response:1; }","duration":"531.356519ms","start":"2026-09-17T20:25:44.532799Z","end":"2026-09-17T20:25:45.064156Z","steps":["trace[846118951] 'process raft request' (duration: 530.559084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:25:45.064349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:25:44.516355Z","time spent":"547.945113ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T20:25:45.064393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:25:44.516399Z","time spent":"547.591353ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T20:25:45.064502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:25:44.532778Z","time spent":"531.594427ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T20:25:45.44585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.382268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-17T20:25:45.446231Z","caller":"traceutil/trace.go:171","msg":"trace[179898623] transaction","detail":"{read_only:false; response_revision:22150; number_of_response:1; }","duration":"560.120373ms","start":"2026-09-17T20:25:44.886062Z","end":"2026-09-17T20:25:45.446183Z","steps":["trace[179898623] 'process raft request' (duration: 347.231419ms)","trace[179898623] 'compare' (duration: 212.221703ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:25:45.446471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:25:44.886026Z","time spent":"560.30817ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T20:25:45.446987Z","caller":"traceutil/trace.go:171","msg":"trace[1119702745] linearizableReadLoop","detail":"{readStateIndex:22904; appliedIndex:22903; }","duration":"379.916825ms","start":"2026-09-17T20:25:45.066046Z","end":"2026-09-17T20:25:45.445963Z","steps":["trace[1119702745] 'read index received' (duration: 167.152805ms)","trace[1119702745] 'applied index is now lower than readState.Index' (duration: 212.76249ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:25:45.447072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.979736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:25:45.447214Z","caller":"traceutil/trace.go:171","msg":"trace[1026354724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22150; }","duration":"381.183412ms","start":"2026-09-17T20:25:45.065994Z","end":"2026-09-17T20:25:45.447178Z","steps":["trace[1026354724] 'agreement among raft nodes before linearized reading' (duration: 380.933164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:25:45.447282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:25:45.065972Z","time spent":"381.293685ms","remote":"127.0.0.1:45536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-17T20:25:45.497131Z","caller":"traceutil/trace.go:171","msg":"trace[120902545] transaction","detail":"{read_only:false; response_revision:22151; number_of_response:1; }","duration":"228.159298ms","start":"2026-09-17T20:25:45.268937Z","end":"2026-09-17T20:25:45.497096Z","steps":["trace[120902545] 'process raft request' (duration: 227.953962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:25:45.497224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.095938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:25:45.497397Z","caller":"traceutil/trace.go:171","msg":"trace[1360151206] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:22151; }","duration":"135.227752ms","start":"2026-09-17T20:25:45.362043Z","end":"2026-09-17T20:25:45.497271Z","steps":["trace[1360151206] 'agreement among raft nodes before linearized reading' (duration: 135.081217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:25:45.497433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.187855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-17T20:25:45.497606Z","caller":"traceutil/trace.go:171","msg":"trace[387757976] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:22151; }","duration":"338.478753ms","start":"2026-09-17T20:25:45.159078Z","end":"2026-09-17T20:25:45.497557Z","steps":["trace[387757976] 'agreement among raft nodes before linearized reading' (duration: 337.981948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:25:45.497675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:25:45.159049Z","time spent":"338.611817ms","remote":"127.0.0.1:45772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":210,"response size":33,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-09-17T20:29:08.210717Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21567} {"level":"info","ts":"2026-09-17T20:29:08.332926Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21567,"took":"117.795861ms","hash":2922238050,"current-db-size-bytes":52359168,"current-db-size":"52 MB","current-db-size-in-use-bytes":30654464,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-17T20:29:08.333002Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2922238050,"revision":21567,"compact-revision":19234} {"level":"info","ts":"2026-09-17T20:30:03.476427Z","caller":"traceutil/trace.go:171","msg":"trace[483873150] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"171.331918ms","start":"2026-09-17T20:30:03.305058Z","end":"2026-09-17T20:30:03.47639Z","steps":["trace[483873150] 'process raft request' (duration: 171.032989ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:30:03.98827Z","caller":"traceutil/trace.go:171","msg":"trace[89335088] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"435.805346ms","start":"2026-09-17T20:30:03.552425Z","end":"2026-09-17T20:30:03.98823Z","steps":["trace[89335088] 'process raft request' (duration: 435.515517ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:30:03.988736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:30:03.552392Z","time spent":"436.069944ms","remote":"127.0.0.1:45752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5606,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T20:30:03.993725Z","caller":"traceutil/trace.go:171","msg":"trace[2134045370] linearizableReadLoop","detail":"{readStateIndex:25505; appliedIndex:25503; }","duration":"263.049615ms","start":"2026-09-17T20:30:03.730636Z","end":"2026-09-17T20:30:03.993686Z","steps":["trace[2134045370] 'read index received' (duration: 257.314281ms)","trace[2134045370] 'applied index is now lower than readState.Index' (duration: 5.733614ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:30:03.993852Z","caller":"traceutil/trace.go:171","msg":"trace[1990930776] transaction","detail":"{read_only:false; response_revision:24672; number_of_response:1; }","duration":"403.334179ms","start":"2026-09-17T20:30:03.590465Z","end":"2026-09-17T20:30:03.993799Z","steps":["trace[1990930776] 'process raft request' (duration: 402.841205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:30:03.994052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:30:03.590427Z","time spent":"403.499604ms","remote":"127.0.0.1:45600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":44833,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T20:30:03.994064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.423567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T20:30:03.994124Z","caller":"traceutil/trace.go:171","msg":"trace[98064093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24673; }","duration":"263.489208ms","start":"2026-09-17T20:30:03.730617Z","end":"2026-09-17T20:30:03.994107Z","steps":["trace[98064093] 'agreement among raft nodes before linearized reading' (duration: 263.375915ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:30:03.994118Z","caller":"traceutil/trace.go:171","msg":"trace[1799662694] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"225.851074ms","start":"2026-09-17T20:30:03.768016Z","end":"2026-09-17T20:30:03.993867Z","steps":["trace[1799662694] 'process raft request' (duration: 225.543085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:30:03.994359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.935995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-17T20:30:03.994469Z","caller":"traceutil/trace.go:171","msg":"trace[630191161] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24673; }","duration":"195.104859ms","start":"2026-09-17T20:30:03.799335Z","end":"2026-09-17T20:30:03.99444Z","steps":["trace[630191161] 'agreement among raft nodes before linearized reading' (duration: 194.837381ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:30:03.994542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.442218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-17T20:30:03.994803Z","caller":"traceutil/trace.go:171","msg":"trace[1029719305] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:24673; }","duration":"216.562572ms","start":"2026-09-17T20:30:03.778033Z","end":"2026-09-17T20:30:03.994596Z","steps":["trace[1029719305] 'agreement among raft nodes before linearized reading' (duration: 216.135029ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:32:00.803439Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006861.wal"} {"level":"info","ts":"2026-09-17T20:34:08.218601Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23720} {"level":"info","ts":"2026-09-17T20:34:08.349256Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23720,"took":"125.911138ms","hash":1716627806,"current-db-size-bytes":54046720,"current-db-size":"54 MB","current-db-size-in-use-bytes":42528768,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-17T20:34:08.349341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1716627806,"revision":23720,"compact-revision":21567} {"level":"info","ts":"2026-09-17T20:39:08.224986Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26802} {"level":"info","ts":"2026-09-17T20:39:08.382217Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26802,"took":"152.394053ms","hash":4164212483,"current-db-size-bytes":54046720,"current-db-size":"54 MB","current-db-size-in-use-bytes":30154752,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-17T20:39:08.382309Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4164212483,"revision":26802,"compact-revision":23720} {"level":"info","ts":"2026-09-17T20:39:11.491195Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"865c2c1f02504fb9","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-17T20:39:11.499185Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-17T20:39:11.499325Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}