{"level":"warn","ts":"2026-04-13T06:03:25.732036Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T06:03:25.732141Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-13T06:03:25.732212Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T06:03:25.732228Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-04-13T06:03:25.732247Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-13T06:03:25.733057Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-04-13T06:03:25.733142Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-13T06:03:25.739122Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.743292ms"} {"level":"info","ts":"2026-04-13T06:03:25.746552Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-04-13T06:03:25.746664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T06:03:25.746698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-04-13T06:03:25.746721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T06:03:25.746738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-04-13T06:03:25.746781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-04-13T06:03:25.754096Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T06:03:25.755456Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T06:03:25.756367Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-13T06:03:25.759116Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T06:03:25.759313Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T06:03:25.759938Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T06:03:25.760066Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T06:03:25.760083Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T06:03:25.760849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-04-13T06:03:25.761255Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-04-13T06:03:25.763903Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-13T06:03:25.76411Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T06:03:25.764147Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T06:03:25.764716Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-04-13T06:03:25.764732Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-04-13T06:03:26.447114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T06:03:26.447187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T06:03:26.447225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-04-13T06:03:26.447246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-04-13T06:03:26.447257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-04-13T06:03:26.447273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-04-13T06:03:26.447294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-04-13T06:03:26.448416Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T06:03:26.448594Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T06:03:26.44876Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T06:03:26.44881Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T06:03:26.45079Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T06:03:26.451045Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T06:03:26.451102Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T06:03:26.453179Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-04-13T06:03:26.462568Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T06:03:26.471658Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T06:03:26.47168Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T06:03:30.0834Z","caller":"traceutil/trace.go:171","msg":"trace[985966150] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"172.035863ms","start":"2026-04-13T06:03:29.911336Z","end":"2026-04-13T06:03:30.083372Z","steps":["trace[985966150] 'process raft request' (duration: 171.790332ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:03:30.303759Z","caller":"traceutil/trace.go:171","msg":"trace[952597493] linearizableReadLoop","detail":"{readStateIndex:215; appliedIndex:213; }","duration":"383.295327ms","start":"2026-04-13T06:03:29.920424Z","end":"2026-04-13T06:03:30.303719Z","steps":["trace[952597493] 'read index received' (duration: 162.814744ms)","trace[952597493] 'applied index is now lower than readState.Index' (duration: 220.478313ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:03:30.30404Z","caller":"traceutil/trace.go:171","msg":"trace[503644982] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"392.477263ms","start":"2026-04-13T06:03:29.911536Z","end":"2026-04-13T06:03:30.304014Z","steps":["trace[503644982] 'process raft request' (duration: 388.070046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:03:30.304171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.634712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-04-13T06:03:30.304319Z","caller":"traceutil/trace.go:171","msg":"trace[126262024] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:210; }","duration":"383.909285ms","start":"2026-04-13T06:03:29.920394Z","end":"2026-04-13T06:03:30.304303Z","steps":["trace[126262024] 'agreement among raft nodes before linearized reading' (duration: 383.602391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:03:30.304406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:03:29.920386Z","time spent":"384.005809ms","remote":"127.0.0.1:36774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3514,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-13T06:03:30.30455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:03:29.911517Z","time spent":"392.593089ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:03:30.305002Z","caller":"traceutil/trace.go:171","msg":"trace[852365115] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"134.140647ms","start":"2026-04-13T06:03:30.170841Z","end":"2026-04-13T06:03:30.304982Z","steps":["trace[852365115] 'process raft request' (duration: 133.255887ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:03:58.201726Z","caller":"traceutil/trace.go:171","msg":"trace[38629218] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"102.397096ms","start":"2026-04-13T06:03:58.099309Z","end":"2026-04-13T06:03:58.201706Z","steps":["trace[38629218] 'process raft request' (duration: 69.182013ms)","trace[38629218] 'compare' (duration: 33.1385ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:04:03.546413Z","caller":"traceutil/trace.go:171","msg":"trace[1616178248] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"278.760432ms","start":"2026-04-13T06:04:03.267626Z","end":"2026-04-13T06:04:03.546386Z","steps":["trace[1616178248] 'process raft request' (duration: 269.321144ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:04:04.18956Z","caller":"traceutil/trace.go:171","msg":"trace[1473014814] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"242.39349ms","start":"2026-04-13T06:04:03.947073Z","end":"2026-04-13T06:04:04.189466Z","steps":["trace[1473014814] 'process raft request' (duration: 241.893864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:04:04.762504Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912282379,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T06:04:04.818924Z","caller":"traceutil/trace.go:171","msg":"trace[1148058829] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"577.416154ms","start":"2026-04-13T06:04:04.24148Z","end":"2026-04-13T06:04:04.818896Z","steps":["trace[1148058829] 'process raft request' (duration: 577.173195ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:04:04.81887Z","caller":"traceutil/trace.go:171","msg":"trace[1429985090] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"557.329553ms","start":"2026-04-13T06:04:04.261517Z","end":"2026-04-13T06:04:04.818847Z","steps":["trace[1429985090] 'read index received' (duration: 557.180818ms)","trace[1429985090] 'applied index is now lower than readState.Index' (duration: 147.415µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:04:04.819054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:04:04.241453Z","time spent":"577.539688ms","remote":"127.0.0.1:37098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T06:04:04.81912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.584982ms","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-04-13T06:04:04.819162Z","caller":"traceutil/trace.go:171","msg":"trace[299996922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"557.645553ms","start":"2026-04-13T06:04:04.261507Z","end":"2026-04-13T06:04:04.819153Z","steps":["trace[299996922] 'agreement among raft nodes before linearized reading' (duration: 557.470707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:04:04.819199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:04:04.261465Z","time spent":"557.725175ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T06:04:04.833599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.909006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T06:04:04.833743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.605439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:04:04.833792Z","caller":"traceutil/trace.go:171","msg":"trace[181455808] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:624; }","duration":"132.672231ms","start":"2026-04-13T06:04:04.701104Z","end":"2026-04-13T06:04:04.833776Z","steps":["trace[181455808] 'agreement among raft nodes before linearized reading' (duration: 132.617898ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:04:04.833757Z","caller":"traceutil/trace.go:171","msg":"trace[1613651949] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:624; }","duration":"132.092282ms","start":"2026-04-13T06:04:04.701647Z","end":"2026-04-13T06:04:04.83374Z","steps":["trace[1613651949] 'agreement among raft nodes before linearized reading' (duration: 131.844763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:04:04.833632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.974354ms","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-04-13T06:04:04.834025Z","caller":"traceutil/trace.go:171","msg":"trace[916839530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"346.399757ms","start":"2026-04-13T06:04:04.487613Z","end":"2026-04-13T06:04:04.834012Z","steps":["trace[916839530] 'agreement among raft nodes before linearized reading' (duration: 345.926292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:04:04.834075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.859718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-13T06:04:04.834114Z","caller":"traceutil/trace.go:171","msg":"trace[1653112917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"341.918218ms","start":"2026-04-13T06:04:04.492186Z","end":"2026-04-13T06:04:04.834104Z","steps":["trace[1653112917] 'agreement among raft nodes before linearized reading' (duration: 341.829235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:04:04.83414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:04:04.492176Z","time spent":"341.95638ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T06:04:04.833705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.544617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:04:04.834416Z","caller":"traceutil/trace.go:171","msg":"trace[673354660] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:624; }","duration":"133.296622ms","start":"2026-04-13T06:04:04.701102Z","end":"2026-04-13T06:04:04.834398Z","steps":["trace[673354660] 'agreement among raft nodes before linearized reading' (duration: 132.575327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:04:04.834094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:04:04.4876Z","time spent":"346.480042ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T06:04:04.834312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.266007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-98rwg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-13T06:04:04.835603Z","caller":"traceutil/trace.go:171","msg":"trace[17614800] range","detail":"{range_begin:/registry/pods/kube-system/cilium-98rwg; range_end:; response_count:1; response_revision:624; }","duration":"413.575782ms","start":"2026-04-13T06:04:04.422015Z","end":"2026-04-13T06:04:04.835591Z","steps":["trace[17614800] 'agreement among raft nodes before linearized reading' (duration: 412.257298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:04:04.833754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.988727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T06:04:04.838154Z","caller":"traceutil/trace.go:171","msg":"trace[1881128774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"498.437665ms","start":"2026-04-13T06:04:04.339693Z","end":"2026-04-13T06:04:04.83813Z","steps":["trace[1881128774] 'agreement among raft nodes before linearized reading' (duration: 493.980177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:04:04.838249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:04:04.339671Z","time spent":"498.54581ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T06:04:04.838447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:04:04.422001Z","time spent":"416.435607ms","remote":"127.0.0.1:36780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-98rwg\" "} {"level":"info","ts":"2026-04-13T06:04:10.642264Z","caller":"traceutil/trace.go:171","msg":"trace[1361883822] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:736; }","duration":"140.0421ms","start":"2026-04-13T06:04:10.502196Z","end":"2026-04-13T06:04:10.642239Z","steps":["trace[1361883822] 'read index received' (duration: 27.809128ms)","trace[1361883822] 'applied index is now lower than readState.Index' (duration: 112.231802ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:04:10.642499Z","caller":"traceutil/trace.go:171","msg":"trace[1273034279] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"161.058301ms","start":"2026-04-13T06:04:10.481379Z","end":"2026-04-13T06:04:10.642437Z","steps":["trace[1273034279] 'process raft request' (duration: 119.883117ms)","trace[1273034279] 'compare' (duration: 40.852154ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:04:10.642576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.36032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-04-13T06:04:10.642619Z","caller":"traceutil/trace.go:171","msg":"trace[349876650] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:713; }","duration":"140.450953ms","start":"2026-04-13T06:04:10.502155Z","end":"2026-04-13T06:04:10.642606Z","steps":["trace[349876650] 'agreement among raft nodes before linearized reading' (duration: 140.205835ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:05:04.722674Z","caller":"traceutil/trace.go:171","msg":"trace[1245132815] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"264.286758ms","start":"2026-04-13T06:05:04.458346Z","end":"2026-04-13T06:05:04.722633Z","steps":["trace[1245132815] 'process raft request' (duration: 264.017711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:05:28.063342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.036233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:05:28.063415Z","caller":"traceutil/trace.go:171","msg":"trace[971611071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1504; }","duration":"109.159846ms","start":"2026-04-13T06:05:27.954243Z","end":"2026-04-13T06:05:28.063402Z","steps":["trace[971611071] 'range keys from in-memory index tree' (duration: 108.952932ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:05:51.644399Z","caller":"traceutil/trace.go:171","msg":"trace[896128880] linearizableReadLoop","detail":"{readStateIndex:1996; appliedIndex:1995; }","duration":"217.565701ms","start":"2026-04-13T06:05:51.426787Z","end":"2026-04-13T06:05:51.644353Z","steps":["trace[896128880] 'read index received' (duration: 217.407308ms)","trace[896128880] 'applied index is now lower than readState.Index' (duration: 157.003µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:05:51.644526Z","caller":"traceutil/trace.go:171","msg":"trace[396237106] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"225.085072ms","start":"2026-04-13T06:05:51.4194Z","end":"2026-04-13T06:05:51.644486Z","steps":["trace[396237106] 'process raft request' (duration: 224.765625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:05:51.644702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.051911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T06:05:51.644741Z","caller":"traceutil/trace.go:171","msg":"trace[1289249397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1919; }","duration":"216.144642ms","start":"2026-04-13T06:05:51.428584Z","end":"2026-04-13T06:05:51.644729Z","steps":["trace[1289249397] 'agreement among raft nodes before linearized reading' (duration: 215.952728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:05:51.644742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.930978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-13T06:05:51.644804Z","caller":"traceutil/trace.go:171","msg":"trace[1072599488] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1919; }","duration":"218.052711ms","start":"2026-04-13T06:05:51.426734Z","end":"2026-04-13T06:05:51.644786Z","steps":["trace[1072599488] 'agreement among raft nodes before linearized reading' (duration: 217.793285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:05:51.644824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.447024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e4b2b803-9dee-4604-b743-820a06cf4eb2\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-13T06:05:51.644867Z","caller":"traceutil/trace.go:171","msg":"trace[163638667] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e4b2b803-9dee-4604-b743-820a06cf4eb2; range_end:; response_count:1; response_revision:1919; }","duration":"111.487905ms","start":"2026-04-13T06:05:51.533361Z","end":"2026-04-13T06:05:51.644849Z","steps":["trace[163638667] 'agreement among raft nodes before linearized reading' (duration: 111.369563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:05:51.644972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.282571ms","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-04-13T06:05:51.645004Z","caller":"traceutil/trace.go:171","msg":"trace[853468002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1919; }","duration":"144.374494ms","start":"2026-04-13T06:05:51.50062Z","end":"2026-04-13T06:05:51.644994Z","steps":["trace[853468002] 'agreement among raft nodes before linearized reading' (duration: 144.286092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:05:51.645135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.472826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-13T06:05:51.645249Z","caller":"traceutil/trace.go:171","msg":"trace[435445642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1919; }","duration":"144.620359ms","start":"2026-04-13T06:05:51.500599Z","end":"2026-04-13T06:05:51.64522Z","steps":["trace[435445642] 'agreement among raft nodes before linearized reading' (duration: 144.355703ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:05:51.815959Z","caller":"traceutil/trace.go:171","msg":"trace[927126516] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"165.775072ms","start":"2026-04-13T06:05:51.649947Z","end":"2026-04-13T06:05:51.815722Z","steps":["trace[927126516] 'process raft request' (duration: 165.586648ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:05:51.826341Z","caller":"traceutil/trace.go:171","msg":"trace[1434057220] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"175.747682ms","start":"2026-04-13T06:05:51.650581Z","end":"2026-04-13T06:05:51.826329Z","steps":["trace[1434057220] 'process raft request' (duration: 175.63108ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:05:51.826458Z","caller":"traceutil/trace.go:171","msg":"trace[1984624448] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"175.387065ms","start":"2026-04-13T06:05:51.651057Z","end":"2026-04-13T06:05:51.826444Z","steps":["trace[1984624448] 'process raft request' (duration: 175.218332ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:05:52.201662Z","caller":"traceutil/trace.go:171","msg":"trace[537522469] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"298.479222ms","start":"2026-04-13T06:05:51.903146Z","end":"2026-04-13T06:05:52.201625Z","steps":["trace[537522469] 'process raft request' (duration: 234.151127ms)","trace[537522469] 'compare' (duration: 64.150153ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:05:52.201666Z","caller":"traceutil/trace.go:171","msg":"trace[313726310] linearizableReadLoop","detail":"{readStateIndex:2000; appliedIndex:1999; }","duration":"258.462931ms","start":"2026-04-13T06:05:51.943164Z","end":"2026-04-13T06:05:52.201627Z","steps":["trace[313726310] 'read index received' (duration: 194.134706ms)","trace[313726310] 'applied index is now lower than readState.Index' (duration: 64.326255ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:05:52.20195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.106503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T06:05:52.201968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.780998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e4b2b803-9dee-4604-b743-820a06cf4eb2\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-13T06:05:52.201999Z","caller":"traceutil/trace.go:171","msg":"trace[1914836405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1923; }","duration":"249.256517ms","start":"2026-04-13T06:05:51.952731Z","end":"2026-04-13T06:05:52.201987Z","steps":["trace[1914836405] 'agreement among raft nodes before linearized reading' (duration: 249.118474ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:05:52.202044Z","caller":"traceutil/trace.go:171","msg":"trace[1413414139] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e4b2b803-9dee-4604-b743-820a06cf4eb2; range_end:; response_count:1; response_revision:1923; }","duration":"258.92067ms","start":"2026-04-13T06:05:51.943105Z","end":"2026-04-13T06:05:52.202026Z","steps":["trace[1413414139] 'agreement among raft nodes before linearized reading' (duration: 258.624924ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:06:04.890134Z","caller":"traceutil/trace.go:171","msg":"trace[1484714082] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"102.793412ms","start":"2026-04-13T06:06:04.787315Z","end":"2026-04-13T06:06:04.890108Z","steps":["trace[1484714082] 'process raft request' (duration: 97.107062ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:06:07.713482Z","caller":"traceutil/trace.go:171","msg":"trace[1031861430] linearizableReadLoop","detail":"{readStateIndex:2123; appliedIndex:2122; }","duration":"284.165163ms","start":"2026-04-13T06:06:07.429295Z","end":"2026-04-13T06:06:07.71346Z","steps":["trace[1031861430] 'read index received' (duration: 284.018961ms)","trace[1031861430] 'applied index is now lower than readState.Index' (duration: 144.932µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:06:07.713582Z","caller":"traceutil/trace.go:171","msg":"trace[1252245] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"296.609105ms","start":"2026-04-13T06:06:07.416939Z","end":"2026-04-13T06:06:07.713548Z","steps":["trace[1252245] 'process raft request' (duration: 296.35466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:06:07.713626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.299386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:06:07.713658Z","caller":"traceutil/trace.go:171","msg":"trace[141367377] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2043; }","duration":"284.396628ms","start":"2026-04-13T06:06:07.429252Z","end":"2026-04-13T06:06:07.713649Z","steps":["trace[141367377] 'agreement among raft nodes before linearized reading' (duration: 284.292256ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:06:26.58372Z","caller":"traceutil/trace.go:171","msg":"trace[776359846] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"147.463925ms","start":"2026-04-13T06:06:26.436233Z","end":"2026-04-13T06:06:26.583697Z","steps":["trace[776359846] 'process raft request' (duration: 147.300423ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:06:27.075868Z","caller":"traceutil/trace.go:171","msg":"trace[1339307575] linearizableReadLoop","detail":"{readStateIndex:2245; appliedIndex:2244; }","duration":"199.191114ms","start":"2026-04-13T06:06:26.876653Z","end":"2026-04-13T06:06:27.075844Z","steps":["trace[1339307575] 'read index received' (duration: 199.098872ms)","trace[1339307575] 'applied index is now lower than readState.Index' (duration: 90.762µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:06:27.075935Z","caller":"traceutil/trace.go:171","msg":"trace[1263196734] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"210.958445ms","start":"2026-04-13T06:06:26.864938Z","end":"2026-04-13T06:06:27.075896Z","steps":["trace[1263196734] 'process raft request' (duration: 210.729101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:06:27.076388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.551871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-13T06:06:27.076524Z","caller":"traceutil/trace.go:171","msg":"trace[1530605918] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2161; }","duration":"199.858427ms","start":"2026-04-13T06:06:26.876622Z","end":"2026-04-13T06:06:27.07648Z","steps":["trace[1530605918] 'agreement among raft nodes before linearized reading' (duration: 199.380758ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:06:27.238409Z","caller":"traceutil/trace.go:171","msg":"trace[1827666431] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"279.546408ms","start":"2026-04-13T06:06:26.958841Z","end":"2026-04-13T06:06:27.238387Z","steps":["trace[1827666431] 'process raft request' (duration: 274.680436ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:06:27.238754Z","caller":"traceutil/trace.go:171","msg":"trace[681686571] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"279.783972ms","start":"2026-04-13T06:06:26.958945Z","end":"2026-04-13T06:06:27.238729Z","steps":["trace[681686571] 'process raft request' (duration: 279.401394ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:06:27.456797Z","caller":"traceutil/trace.go:171","msg":"trace[198474839] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"212.686192ms","start":"2026-04-13T06:06:27.244081Z","end":"2026-04-13T06:06:27.456768Z","steps":["trace[198474839] 'process raft request' (duration: 162.205018ms)","trace[198474839] 'compare' (duration: 50.294021ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:07:21.412609Z","caller":"traceutil/trace.go:171","msg":"trace[233685869] linearizableReadLoop","detail":"{readStateIndex:2657; appliedIndex:2656; }","duration":"124.53781ms","start":"2026-04-13T06:07:21.288052Z","end":"2026-04-13T06:07:21.41259Z","steps":["trace[233685869] 'read index received' (duration: 124.362467ms)","trace[233685869] 'applied index is now lower than readState.Index' (duration: 172.313µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:07:21.412765Z","caller":"traceutil/trace.go:171","msg":"trace[1644923824] transaction","detail":"{read_only:false; response_revision:2560; number_of_response:1; }","duration":"158.822268ms","start":"2026-04-13T06:07:21.253903Z","end":"2026-04-13T06:07:21.412725Z","steps":["trace[1644923824] 'process raft request' (duration: 158.558933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:07:21.412818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.747854ms","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-04-13T06:07:21.412851Z","caller":"traceutil/trace.go:171","msg":"trace[731961848] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2560; }","duration":"124.826724ms","start":"2026-04-13T06:07:21.288015Z","end":"2026-04-13T06:07:21.412842Z","steps":["trace[731961848] 'agreement among raft nodes before linearized reading' (duration: 124.657131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:29.788482Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287423,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:08:30.288629Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287423,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:08:30.789299Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287423,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:08:31.289965Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287423,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:08:31.790239Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287423,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:08:32.291048Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287423,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:08:32.791717Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287423,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:08:32.953948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999942256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-13T06:08:32.954042Z","caller":"traceutil/trace.go:171","msg":"trace[1920752214] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00009398s","start":"2026-04-13T06:08:30.953925Z","end":"2026-04-13T06:08:32.954019Z","steps":["trace[1920752214] 'agreement among raft nodes before linearized reading' (duration: 1.999981937s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:32.954151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:30.953897Z","time spent":"2.000194313s","remote":"127.0.0.1:36610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/13 06:08:32 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:08:33.292193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287423,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:08:33.793018Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287423,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:08:34.075104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:29.076275Z","time spent":"4.998820958s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-13T06:08:34.294109Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287423,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:08:34.67985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:29.681595Z","time spent":"4.998247996s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-13T06:08:34.682628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:29.683452Z","time spent":"4.999173232s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/13 06:08:34 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:08:34.682752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:29.683793Z","time spent":"4.998958355s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/13 06:08:34 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:08:34.794759Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287423,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:08:34.961712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000297952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-13T06:08:34.96181Z","caller":"traceutil/trace.go:171","msg":"trace[438129200] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000460246s","start":"2026-04-13T06:08:32.961326Z","end":"2026-04-13T06:08:34.961787Z","steps":["trace[438129200] 'agreement among raft nodes before linearized reading' (duration: 2.000328783s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:34.961932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:32.96131Z","time spent":"2.000549219s","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/13 06:08:34 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:08:35.29533Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287423,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:08:35.661257Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"6.584861353s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-13T06:08:35.752548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.70873434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"rangeKeys: context cancelled: context canceled"} {"level":"info","ts":"2026-04-13T06:08:35.752716Z","caller":"traceutil/trace.go:171","msg":"trace[439113879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"7.708948106s","start":"2026-04-13T06:08:28.043748Z","end":"2026-04-13T06:08:35.752696Z","steps":["trace[439113879] 'range keys from in-memory index tree' (duration: 7.708535565s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.75279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:28.043726Z","time spent":"7.709050158s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/04/13 06:08:35 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:08:35.754966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.171323261s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:08:35.755094Z","caller":"traceutil/trace.go:171","msg":"trace[268599946] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2919; }","duration":"3.171463784s","start":"2026-04-13T06:08:32.58361Z","end":"2026-04-13T06:08:35.755074Z","steps":["trace[268599946] 'range keys from in-memory index tree' (duration: 3.1713058s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.755324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.385544365s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:08:35.755663Z","caller":"traceutil/trace.go:171","msg":"trace[1552970786] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2919; }","duration":"7.385927856s","start":"2026-04-13T06:08:28.369719Z","end":"2026-04-13T06:08:35.755647Z","steps":["trace[1552970786] 'count revisions from in-memory index tree' (duration: 7.385457202s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.755918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:28.369693Z","time spent":"7.386208112s","remote":"127.0.0.1:47044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-04-13T06:08:35.756034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.034664372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"rangeKeys: context cancelled: context deadline exceeded"} {"level":"info","ts":"2026-04-13T06:08:35.756229Z","caller":"traceutil/trace.go:171","msg":"trace[195449427] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"7.034856537s","start":"2026-04-13T06:08:28.72136Z","end":"2026-04-13T06:08:35.756217Z","steps":["trace[195449427] 'range keys from in-memory index tree' (duration: 7.034553138s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.756322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:28.72131Z","time spent":"7.034998821s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/04/13 06:08:35 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:08:35.756594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.975800939s","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-04-13T06:08:35.75685Z","caller":"traceutil/trace.go:171","msg":"trace[468569308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2919; }","duration":"6.975922652s","start":"2026-04-13T06:08:28.780747Z","end":"2026-04-13T06:08:35.75667Z","steps":["trace[468569308] 'range keys from in-memory index tree' (duration: 6.975664125s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.756916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:28.780728Z","time spent":"6.976177459s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T06:08:35.755903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.354675707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"rangeKeys: context cancelled: context canceled"} {"level":"info","ts":"2026-04-13T06:08:35.757068Z","caller":"traceutil/trace.go:171","msg":"trace[80058334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"7.355927302s","start":"2026-04-13T06:08:28.401129Z","end":"2026-04-13T06:08:35.757056Z","steps":["trace[80058334] 'range keys from in-memory index tree' (duration: 7.354599045s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.757157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.96766196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-13T06:08:35.757179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:28.401103Z","time spent":"7.356058855s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/04/13 06:08:35 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-04-13T06:08:35.757284Z","caller":"traceutil/trace.go:171","msg":"trace[1935457427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2919; }","duration":"6.967790793s","start":"2026-04-13T06:08:28.789412Z","end":"2026-04-13T06:08:35.757203Z","steps":["trace[1935457427] 'range keys from in-memory index tree' (duration: 6.967452674s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.752657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.79981791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T06:08:35.757385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:28.789394Z","time spent":"6.967954169s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T06:08:35.757446Z","caller":"traceutil/trace.go:171","msg":"trace[981564597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2919; }","duration":"6.804606232s","start":"2026-04-13T06:08:28.952797Z","end":"2026-04-13T06:08:35.757403Z","steps":["trace[981564597] 'range keys from in-memory index tree' (duration: 6.799749828s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.75752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:28.952778Z","time spent":"6.804729285s","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T06:08:35.759099Z","caller":"traceutil/trace.go:171","msg":"trace[445018080] transaction","detail":"{read_only:false; response_revision:2924; number_of_response:1; }","duration":"4.437440524s","start":"2026-04-13T06:08:31.321627Z","end":"2026-04-13T06:08:35.759067Z","steps":["trace[445018080] 'process raft request' (duration: 4.437060873s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.759314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:31.321596Z","time spent":"4.437608639s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:08:35.759665Z","caller":"traceutil/trace.go:171","msg":"trace[1275916457] transaction","detail":"{read_only:false; response_revision:2925; number_of_response:1; }","duration":"1.75528399s","start":"2026-04-13T06:08:34.004369Z","end":"2026-04-13T06:08:35.759653Z","steps":["trace[1275916457] 'process raft request' (duration: 1.754597421s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.759739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:34.004345Z","time spent":"1.755355942s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T06:08:35.928671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.920898ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-13T06:08:35.928903Z","caller":"traceutil/trace.go:171","msg":"trace[2110540162] linearizableReadLoop","detail":"{readStateIndex:3037; appliedIndex:3030; }","duration":"6.640474249s","start":"2026-04-13T06:08:29.288405Z","end":"2026-04-13T06:08:35.928879Z","steps":["trace[2110540162] 'read index received' (duration: 6.373090625s)","trace[2110540162] 'applied index is now lower than readState.Index' (duration: 267.381914ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:08:35.92906Z","caller":"traceutil/trace.go:171","msg":"trace[89595847] transaction","detail":"{read_only:false; response_revision:2927; number_of_response:1; }","duration":"165.193237ms","start":"2026-04-13T06:08:35.76384Z","end":"2026-04-13T06:08:35.929033Z","steps":["trace[89595847] 'process raft request' (duration: 164.934449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.929149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.640731656s","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-04-13T06:08:35.929204Z","caller":"traceutil/trace.go:171","msg":"trace[1295965025] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2927; }","duration":"6.640833017s","start":"2026-04-13T06:08:29.288357Z","end":"2026-04-13T06:08:35.92919Z","steps":["trace[1295965025] 'agreement among raft nodes before linearized reading' (duration: 6.640635922s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.929259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:29.288336Z","time spent":"6.64090957s","remote":"127.0.0.1:36796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-04-13T06:08:35.937186Z","caller":"traceutil/trace.go:171","msg":"trace[1426768926] transaction","detail":"{read_only:false; response_revision:2928; number_of_response:1; }","duration":"172.753944ms","start":"2026-04-13T06:08:35.764416Z","end":"2026-04-13T06:08:35.93717Z","steps":["trace[1426768926] 'process raft request' (duration: 172.518298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.937378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.081319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:08:35.937422Z","caller":"traceutil/trace.go:171","msg":"trace[1148076217] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2928; }","duration":"171.149221ms","start":"2026-04-13T06:08:35.766261Z","end":"2026-04-13T06:08:35.93741Z","steps":["trace[1148076217] 'agreement among raft nodes before linearized reading' (duration: 171.073128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.937676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.358109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T06:08:35.937716Z","caller":"traceutil/trace.go:171","msg":"trace[2053874157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2928; }","duration":"215.434551ms","start":"2026-04-13T06:08:35.722272Z","end":"2026-04-13T06:08:35.937706Z","steps":["trace[2053874157] 'agreement among raft nodes before linearized reading' (duration: 215.270917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.937909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.99468ms","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-04-13T06:08:35.937945Z","caller":"traceutil/trace.go:171","msg":"trace[1098445413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2928; }","duration":"533.03017ms","start":"2026-04-13T06:08:35.404906Z","end":"2026-04-13T06:08:35.937936Z","steps":["trace[1098445413] 'agreement among raft nodes before linearized reading' (duration: 532.926247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.937981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:35.404857Z","time spent":"533.114243ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T06:08:35.938111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.665312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T06:08:35.938146Z","caller":"traceutil/trace.go:171","msg":"trace[1164167536] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:2928; }","duration":"614.700163ms","start":"2026-04-13T06:08:35.323436Z","end":"2026-04-13T06:08:35.938136Z","steps":["trace[1164167536] 'agreement among raft nodes before linearized reading' (duration: 614.650972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.93818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:35.323389Z","time spent":"614.783747ms","remote":"127.0.0.1:36766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":30,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-04-13T06:08:35.938356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.896974ms","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-04-13T06:08:35.93839Z","caller":"traceutil/trace.go:171","msg":"trace[797678816] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2928; }","duration":"893.970956ms","start":"2026-04-13T06:08:35.044411Z","end":"2026-04-13T06:08:35.938382Z","steps":["trace[797678816] 'agreement among raft nodes before linearized reading' (duration: 893.887683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.938425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:35.044389Z","time spent":"894.029098ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T06:08:35.938545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.521484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:08:35.938576Z","caller":"traceutil/trace.go:171","msg":"trace[2068607066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2928; }","duration":"976.595647ms","start":"2026-04-13T06:08:34.961973Z","end":"2026-04-13T06:08:35.938568Z","steps":["trace[2068607066] 'agreement among raft nodes before linearized reading' (duration: 976.551476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.938598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:34.961952Z","time spent":"976.642529ms","remote":"127.0.0.1:36610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T06:08:35.938695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249611215s","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-04-13T06:08:35.938718Z","caller":"traceutil/trace.go:171","msg":"trace[1968542444] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2928; }","duration":"1.24972569s","start":"2026-04-13T06:08:34.688985Z","end":"2026-04-13T06:08:35.938711Z","steps":["trace[1968542444] 'agreement among raft nodes before linearized reading' (duration: 1.249692288s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.938739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:34.688969Z","time spent":"1.24976528s","remote":"127.0.0.1:47190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-13T06:08:35.938843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.254223603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-13T06:08:35.938864Z","caller":"traceutil/trace.go:171","msg":"trace[478449912] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2928; }","duration":"1.254292225s","start":"2026-04-13T06:08:34.684566Z","end":"2026-04-13T06:08:35.938859Z","steps":["trace[478449912] 'agreement among raft nodes before linearized reading' (duration: 1.254240104s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.938909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:34.68454Z","time spent":"1.254363847s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-13T06:08:35.939055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.937294441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:08:35.939099Z","caller":"traceutil/trace.go:171","msg":"trace[1436317331] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:2928; }","duration":"4.937430222s","start":"2026-04-13T06:08:31.001658Z","end":"2026-04-13T06:08:35.939088Z","steps":["trace[1436317331] 'agreement among raft nodes before linearized reading' (duration: 4.93734509s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.939132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:31.001618Z","time spent":"4.937504885s","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"warn","ts":"2026-04-13T06:08:35.939155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.528618161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-04-13T06:08:35.939237Z","caller":"traceutil/trace.go:171","msg":"trace[1656686900] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2928; }","duration":"6.528749254s","start":"2026-04-13T06:08:29.410473Z","end":"2026-04-13T06:08:35.939222Z","steps":["trace[1656686900] 'agreement among raft nodes before linearized reading' (duration: 6.528499007s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.939293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:29.410454Z","time spent":"6.528830757s","remote":"127.0.0.1:36780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-13T06:08:35.939343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.475321918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-13T06:08:35.939384Z","caller":"traceutil/trace.go:171","msg":"trace[1911538460] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2928; }","duration":"4.475405952s","start":"2026-04-13T06:08:31.463966Z","end":"2026-04-13T06:08:35.939372Z","steps":["trace[1911538460] 'agreement among raft nodes before linearized reading' (duration: 4.475309709s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.939415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:31.463945Z","time spent":"4.475462532s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-13T06:08:35.939472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255709734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-04-13T06:08:35.93957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.509661901s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T06:08:35.939596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.790821609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-04-13T06:08:35.939617Z","caller":"traceutil/trace.go:171","msg":"trace[453914397] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:0; response_revision:2928; }","duration":"6.509729693s","start":"2026-04-13T06:08:29.429863Z","end":"2026-04-13T06:08:35.939592Z","steps":["trace[453914397] 'agreement among raft nodes before linearized reading' (duration: 6.509686961s)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:08:35.939629Z","caller":"traceutil/trace.go:171","msg":"trace[45666949] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2928; }","duration":"4.790917131s","start":"2026-04-13T06:08:31.148703Z","end":"2026-04-13T06:08:35.93962Z","steps":["trace[45666949] 'agreement among raft nodes before linearized reading' (duration: 4.790826999s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.939641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:29.429852Z","time spent":"6.509783823s","remote":"127.0.0.1:36638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"warn","ts":"2026-04-13T06:08:35.939658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:31.148669Z","time spent":"4.790980983s","remote":"127.0.0.1:36796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2011,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-04-13T06:08:35.939703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256856497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-13T06:08:35.939732Z","caller":"traceutil/trace.go:171","msg":"trace[820043781] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2928; }","duration":"1.256906667s","start":"2026-04-13T06:08:34.682818Z","end":"2026-04-13T06:08:35.939725Z","steps":["trace[820043781] 'agreement among raft nodes before linearized reading' (duration: 1.256842465s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.939749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:34.682808Z","time spent":"1.256937677s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-13T06:08:35.939082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.861961576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:549"} {"level":"warn","ts":"2026-04-13T06:08:35.939805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.35931592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a5d592cecc06d0\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-04-13T06:08:35.939829Z","caller":"traceutil/trace.go:171","msg":"trace[1606855416] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:2928; }","duration":"1.862729438s","start":"2026-04-13T06:08:34.077087Z","end":"2026-04-13T06:08:35.939817Z","steps":["trace[1606855416] 'agreement among raft nodes before linearized reading' (duration: 1.861890544s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.939863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:34.077073Z","time spent":"1.862781949s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":572,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-04-13T06:08:35.939563Z","caller":"traceutil/trace.go:171","msg":"trace[918303793] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2928; }","duration":"1.255912869s","start":"2026-04-13T06:08:34.683641Z","end":"2026-04-13T06:08:35.939554Z","steps":["trace[918303793] 'agreement among raft nodes before linearized reading' (duration: 1.25557068s)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:08:35.939835Z","caller":"traceutil/trace.go:171","msg":"trace[2029607479] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a5d592cecc06d0; range_end:; response_count:1; response_revision:2928; }","duration":"6.359365702s","start":"2026-04-13T06:08:29.580461Z","end":"2026-04-13T06:08:35.939827Z","steps":["trace[2029607479] 'agreement among raft nodes before linearized reading' (duration: 6.359050013s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.939966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.110752809s","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-04-13T06:08:35.939989Z","caller":"traceutil/trace.go:171","msg":"trace[1131226910] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2928; }","duration":"3.11080788s","start":"2026-04-13T06:08:32.829175Z","end":"2026-04-13T06:08:35.939983Z","steps":["trace[1131226910] 'agreement among raft nodes before linearized reading' (duration: 3.110769799s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.940008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:29.580451Z","time spent":"6.359549737s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":802,"request content":"key:\"/registry/events/auth-system/keycloak-0.18a5d592cecc06d0\" "} {"level":"warn","ts":"2026-04-13T06:08:35.940014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:32.829158Z","time spent":"3.110850852s","remote":"127.0.0.1:47150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-13T06:08:35.939975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:34.683635Z","time spent":"1.25633101s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-13T06:08:35.94039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.809698551s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T06:08:35.940428Z","caller":"traceutil/trace.go:171","msg":"trace[128376338] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2928; }","duration":"2.809773375s","start":"2026-04-13T06:08:33.130645Z","end":"2026-04-13T06:08:35.940418Z","steps":["trace[128376338] 'agreement among raft nodes before linearized reading' (duration: 2.809701372s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.94045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:33.130626Z","time spent":"2.809817615s","remote":"127.0.0.1:37012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-13T06:08:35.941348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.553234776s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-13T06:08:35.941441Z","caller":"traceutil/trace.go:171","msg":"trace[1476561537] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2928; }","duration":"3.553393519s","start":"2026-04-13T06:08:32.388027Z","end":"2026-04-13T06:08:35.941421Z","steps":["trace[1476561537] 'agreement among raft nodes before linearized reading' (duration: 3.550941401s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:08:35.941483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:08:32.388007Z","time spent":"3.553467471s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-13T06:09:04.615276Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:05.116386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:05.61706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:06.117694Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:06.61815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:06.954768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000476682s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-13T06:09:06.954956Z","caller":"traceutil/trace.go:171","msg":"trace[1377624581] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000621027s","start":"2026-04-13T06:09:04.954225Z","end":"2026-04-13T06:09:06.954846Z","steps":["trace[1377624581] 'agreement among raft nodes before linearized reading' (duration: 2.000495344s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:06.955075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:04.95421Z","time spent":"2.000851462s","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T06:09:07.11856Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:07.619447Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:08.104804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999968675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-13T06:09:08.104888Z","caller":"traceutil/trace.go:171","msg":"trace[637955751] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000108269s","start":"2026-04-13T06:09:06.10476Z","end":"2026-04-13T06:09:08.104868Z","steps":["trace[637955751] 'agreement among raft nodes before linearized reading' (duration: 2.000000516s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:08.105007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:06.104738Z","time spent":"2.000256733s","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T06:09:08.119668Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:08.620264Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:08.956085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000465136s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-13T06:09:08.956287Z","caller":"traceutil/trace.go:171","msg":"trace[307775506] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000577268s","start":"2026-04-13T06:09:06.955565Z","end":"2026-04-13T06:09:08.956142Z","steps":["trace[307775506] 'agreement among raft nodes before linearized reading' (duration: 2.000488626s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:08.956367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:06.955548Z","time spent":"2.000806605s","remote":"127.0.0.1:36610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/13 06:09:08 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:09:09.108516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:04.108553Z","time spent":"4.99995763s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/13 06:09:09 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:09:09.112964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:04.11322Z","time spent":"4.999741673s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/13 06:09:09 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:09:09.119501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:04.121302Z","time spent":"4.998196291s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-13T06:09:09.120471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:09.120581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:04.121242Z","time spent":"4.999337069s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-13T06:09:09.135616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999995023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-13T06:09:09.135706Z","caller":"traceutil/trace.go:171","msg":"trace[1537748078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.000090005s","start":"2026-04-13T06:09:04.135569Z","end":"2026-04-13T06:09:09.135659Z","steps":["trace[1537748078] 'agreement among raft nodes before linearized reading' (duration: 5.000017973s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:09.135788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:04.135553Z","time spent":"5.000196498s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/04/13 06:09:09 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:09:09.1376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99873899s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-13T06:09:09.137766Z","caller":"traceutil/trace.go:171","msg":"trace[1262999173] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.998809871s","start":"2026-04-13T06:09:04.138826Z","end":"2026-04-13T06:09:09.137636Z","steps":["trace[1262999173] 'agreement among raft nodes before linearized reading' (duration: 4.99875644s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:09.137927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:04.138817Z","time spent":"4.999096199s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/04/13 06:09:09 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:09:09.147802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999913647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-13T06:09:09.147914Z","caller":"traceutil/trace.go:171","msg":"trace[512854519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"5.000082633s","start":"2026-04-13T06:09:04.147806Z","end":"2026-04-13T06:09:09.147889Z","steps":["trace[512854519] 'agreement among raft nodes before linearized reading' (duration: 4.999924738s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:09.148052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:04.147797Z","time spent":"5.000222336s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/04/13 06:09:09 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:09:09.621958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:10.122563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:10.623069Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:10.964693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00077934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-13T06:09:10.964915Z","caller":"traceutil/trace.go:171","msg":"trace[472608795] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000976234s","start":"2026-04-13T06:09:08.963845Z","end":"2026-04-13T06:09:10.964821Z","steps":["trace[472608795] 'agreement among raft nodes before linearized reading' (duration: 2.00080554s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:10.965358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:08.963828Z","time spent":"2.001509479s","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T06:09:11.110433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:04.109782Z","time spent":"7.000644382s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-13T06:09:11.115563Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-04-13T06:09:11.115903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000859565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-04-13T06:09:11.116019Z","caller":"traceutil/trace.go:171","msg":"trace[1165340708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"7.000978808s","start":"2026-04-13T06:09:04.114966Z","end":"2026-04-13T06:09:11.115944Z","steps":["trace[1165340708] 'agreement among raft nodes before linearized reading' (duration: 7.000872486s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:11.116111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:04.114959Z","time spent":"7.001134922s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T06:09:11.616425Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287868,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:11.672466Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"041d9d85700acb9a","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-04-13T06:09:12.117323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287868,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:12.623647Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912287868,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:12.79111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:05.790317Z","time spent":"7.000783616s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-13T06:09:12.966497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997878006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-13T06:09:12.966581Z","caller":"traceutil/trace.go:171","msg":"trace[1291606015] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99802412s","start":"2026-04-13T06:09:10.968537Z","end":"2026-04-13T06:09:12.966561Z","steps":["trace[1291606015] 'agreement among raft nodes before linearized reading' (duration: 1.997923467s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:12.966626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:10.968507Z","time spent":"1.998108263s","remote":"127.0.0.1:36610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/13 06:09:12 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:09:13.276239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00084166s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-13T06:09:13.276551Z","caller":"traceutil/trace.go:171","msg":"trace[2028505211] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001316972s","start":"2026-04-13T06:09:11.275206Z","end":"2026-04-13T06:09:13.276523Z","steps":["trace[2028505211] 'agreement among raft nodes before linearized reading' (duration: 2.000874481s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.276638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:11.275179Z","time spent":"2.001443304s","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/13 06:09:13 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:09:13.289056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.630929ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-13T06:09:13.289936Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"33.411µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"info","ts":"2026-04-13T06:09:13.289954Z","caller":"traceutil/trace.go:171","msg":"trace[1612986019] transaction","detail":"{read_only:false; response_revision:3080; number_of_response:1; }","duration":"6.908318378s","start":"2026-04-13T06:09:06.381619Z","end":"2026-04-13T06:09:13.289937Z","steps":["trace[1612986019] 'process raft request' (duration: 6.908218055s)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.290012Z","caller":"traceutil/trace.go:171","msg":"trace[1127139375] linearizableReadLoop","detail":"{readStateIndex:3201; appliedIndex:3191; }","duration":"2.174244175s","start":"2026-04-13T06:09:11.115757Z","end":"2026-04-13T06:09:13.290001Z","steps":["trace[1127139375] 'read index received' (duration: 1.86152428s)","trace[1127139375] 'applied index is now lower than readState.Index' (duration: 312.718965ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:09:13.290065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:06.381584Z","time spent":"6.90841143s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T06:09:13.290158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.288421937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-04-13T06:09:13.290166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.763201421s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-04-13T06:09:13.290216Z","caller":"traceutil/trace.go:171","msg":"trace[1738123494] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3081; }","duration":"7.288504358s","start":"2026-04-13T06:09:06.001698Z","end":"2026-04-13T06:09:13.290202Z","steps":["trace[1738123494] 'agreement among raft nodes before linearized reading' (duration: 7.288348074s)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.290228Z","caller":"traceutil/trace.go:171","msg":"trace[1912839016] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3081; }","duration":"6.763281432s","start":"2026-04-13T06:09:06.526926Z","end":"2026-04-13T06:09:13.290207Z","steps":["trace[1912839016] 'agreement among raft nodes before linearized reading' (duration: 6.763112068s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.290249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:06.526911Z","time spent":"6.763332235s","remote":"127.0.0.1:36780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-13T06:09:13.290312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.987075609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T06:09:13.290323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.001131205s","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-04-13T06:09:13.290334Z","caller":"traceutil/trace.go:171","msg":"trace[1241173153] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:3081; }","duration":"6.98712116s","start":"2026-04-13T06:09:06.303208Z","end":"2026-04-13T06:09:13.290329Z","steps":["trace[1241173153] 'agreement among raft nodes before linearized reading' (duration: 6.987056339s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.290354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:06.303196Z","time spent":"6.987152802s","remote":"127.0.0.1:36960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":23,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-04-13T06:09:13.290426Z","caller":"traceutil/trace.go:171","msg":"trace[163871133] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3081; }","duration":"8.001232087s","start":"2026-04-13T06:09:05.28911Z","end":"2026-04-13T06:09:13.290343Z","steps":["trace[163871133] 'agreement among raft nodes before linearized reading' (duration: 8.001051283s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.290478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.018782633s","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-04-13T06:09:13.290517Z","caller":"traceutil/trace.go:171","msg":"trace[1981476975] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:3081; }","duration":"7.018878716s","start":"2026-04-13T06:09:06.27163Z","end":"2026-04-13T06:09:13.290509Z","steps":["trace[1981476975] 'agreement among raft nodes before linearized reading' (duration: 7.01866134s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.29053Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"041d9d85700acb9a","error":"lease not found"} {"level":"warn","ts":"2026-04-13T06:09:13.290546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:06.27161Z","time spent":"7.018930446s","remote":"127.0.0.1:37106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":297,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-13T06:09:13.290563Z","caller":"traceutil/trace.go:171","msg":"trace[1384312826] transaction","detail":"{read_only:false; response_revision:3081; number_of_response:1; }","duration":"4.292915797s","start":"2026-04-13T06:09:08.997637Z","end":"2026-04-13T06:09:13.290553Z","steps":["trace[1384312826] 'process raft request' (duration: 4.2922292s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.290569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.298307112s","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":"warn","ts":"2026-04-13T06:09:13.29046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:05.289091Z","time spent":"8.00136045s","remote":"127.0.0.1:36796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-04-13T06:09:13.290595Z","caller":"traceutil/trace.go:171","msg":"trace[642924000] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:3081; }","duration":"5.298367234s","start":"2026-04-13T06:09:07.992221Z","end":"2026-04-13T06:09:13.290588Z","steps":["trace[642924000] 'agreement among raft nodes before linearized reading' (duration: 5.298188729s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.290636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:08.99762Z","time spent":"4.292968439s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T06:09:13.290802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.177124323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"warn","ts":"2026-04-13T06:09:13.290829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.709937215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a5d592cecc06d0\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-04-13T06:09:13.290844Z","caller":"traceutil/trace.go:171","msg":"trace[1831501598] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3081; }","duration":"4.177152833s","start":"2026-04-13T06:09:09.113672Z","end":"2026-04-13T06:09:13.290824Z","steps":["trace[1831501598] 'agreement among raft nodes before linearized reading' (duration: 4.17704617s)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.290855Z","caller":"traceutil/trace.go:171","msg":"trace[1965364743] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a5d592cecc06d0; range_end:; response_count:1; response_revision:3081; }","duration":"8.709992136s","start":"2026-04-13T06:09:04.580857Z","end":"2026-04-13T06:09:13.29085Z","steps":["trace[1965364743] 'agreement among raft nodes before linearized reading' (duration: 8.709930265s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.29089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:04.580842Z","time spent":"8.710028147s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":802,"request content":"key:\"/registry/events/auth-system/keycloak-0.18a5d592cecc06d0\" "} {"level":"warn","ts":"2026-04-13T06:09:13.290867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:09.11365Z","time spent":"4.177212834s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":547,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-13T06:09:13.29101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.152978634s","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-04-13T06:09:13.29103Z","caller":"traceutil/trace.go:171","msg":"trace[80700581] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3081; }","duration":"2.153031305s","start":"2026-04-13T06:09:11.137994Z","end":"2026-04-13T06:09:13.291026Z","steps":["trace[80700581] 'agreement among raft nodes before linearized reading' (duration: 2.152980763s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.291044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:11.137976Z","time spent":"2.153064065s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T06:09:13.291079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.167810094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-04-13T06:09:13.291124Z","caller":"traceutil/trace.go:171","msg":"trace[2057742871] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3081; }","duration":"4.167870767s","start":"2026-04-13T06:09:09.12324Z","end":"2026-04-13T06:09:13.29111Z","steps":["trace[2057742871] 'agreement among raft nodes before linearized reading' (duration: 4.167777704s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.291152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:09.123229Z","time spent":"4.167916917s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-13T06:09:13.291203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.580362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-13T06:09:13.291224Z","caller":"traceutil/trace.go:171","msg":"trace[1543652352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3081; }","duration":"171.615573ms","start":"2026-04-13T06:09:13.119604Z","end":"2026-04-13T06:09:13.29122Z","steps":["trace[1543652352] 'agreement among raft nodes before linearized reading' (duration: 171.563581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.291295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.062295ms","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-04-13T06:09:13.291312Z","caller":"traceutil/trace.go:171","msg":"trace[325271706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3081; }","duration":"176.133527ms","start":"2026-04-13T06:09:13.115175Z","end":"2026-04-13T06:09:13.291309Z","steps":["trace[325271706] 'agreement among raft nodes before linearized reading' (duration: 176.089236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.291305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.29051207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-04-13T06:09:13.291217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:07.992206Z","time spent":"5.298404995s","remote":"127.0.0.1:48178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-13T06:09:13.291353Z","caller":"traceutil/trace.go:171","msg":"trace[1906351814] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3081; }","duration":"7.291203068s","start":"2026-04-13T06:09:06.000137Z","end":"2026-04-13T06:09:13.29134Z","steps":["trace[1906351814] 'agreement among raft nodes before linearized reading' (duration: 7.289920716s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.291361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.326193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:09:13.291374Z","caller":"traceutil/trace.go:171","msg":"trace[625513613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3081; }","duration":"317.367783ms","start":"2026-04-13T06:09:12.974002Z","end":"2026-04-13T06:09:13.29137Z","steps":["trace[625513613] 'agreement among raft nodes before linearized reading' (duration: 317.345482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.291378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:06.000118Z","time spent":"7.291251969s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-13T06:09:13.291391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:12.973984Z","time spent":"317.403303ms","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T06:09:13.291474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.779921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:1 size:489"} {"level":"info","ts":"2026-04-13T06:09:13.29149Z","caller":"traceutil/trace.go:171","msg":"trace[536323204] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:1; response_revision:3081; }","duration":"494.798442ms","start":"2026-04-13T06:09:12.796688Z","end":"2026-04-13T06:09:13.291486Z","steps":["trace[536323204] 'agreement among raft nodes before linearized reading' (duration: 494.766981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.291503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:12.796607Z","time spent":"494.893174ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":512,"request content":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" "} {"level":"warn","ts":"2026-04-13T06:09:13.291475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.027635903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T06:09:13.291559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.287132228s","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-04-13T06:09:13.291571Z","caller":"traceutil/trace.go:171","msg":"trace[547301901] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:3081; }","duration":"5.027824947s","start":"2026-04-13T06:09:08.263724Z","end":"2026-04-13T06:09:13.291549Z","steps":["trace[547301901] 'agreement among raft nodes before linearized reading' (duration: 5.027615821s)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.291594Z","caller":"traceutil/trace.go:171","msg":"trace[1185844942] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3081; }","duration":"6.287172739s","start":"2026-04-13T06:09:07.004411Z","end":"2026-04-13T06:09:13.291584Z","steps":["trace[1185844942] 'agreement among raft nodes before linearized reading' (duration: 6.287111857s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.291601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.151963937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-13T06:09:13.290253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:06.001692Z","time spent":"7.28855046s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-13T06:09:13.291622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:07.004349Z","time spent":"6.287265981s","remote":"127.0.0.1:47202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-13T06:09:13.291628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:08.263706Z","time spent":"5.027913788s","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-04-13T06:09:13.291632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.860153234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-13T06:09:13.291662Z","caller":"traceutil/trace.go:171","msg":"trace[202840803] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:3081; }","duration":"3.860269638s","start":"2026-04-13T06:09:09.431385Z","end":"2026-04-13T06:09:13.291655Z","steps":["trace[202840803] 'agreement among raft nodes before linearized reading' (duration: 3.860182976s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.291692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:09.431355Z","time spent":"3.860331359s","remote":"127.0.0.1:36638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"warn","ts":"2026-04-13T06:09:13.291688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.15353407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T06:09:13.290759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.167435626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-13T06:09:13.291786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.006907627s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:09:13.291809Z","caller":"traceutil/trace.go:171","msg":"trace[473152080] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:3081; }","duration":"6.00697007s","start":"2026-04-13T06:09:07.284833Z","end":"2026-04-13T06:09:13.291803Z","steps":["trace[473152080] 'agreement among raft nodes before linearized reading' (duration: 6.006933689s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.291827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:07.284812Z","time spent":"6.00701103s","remote":"127.0.0.1:47110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-04-13T06:09:13.291815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.181447642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-04-13T06:09:13.291851Z","caller":"traceutil/trace.go:171","msg":"trace[927414491] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:3081; }","duration":"4.181507853s","start":"2026-04-13T06:09:09.110336Z","end":"2026-04-13T06:09:13.291844Z","steps":["trace[927414491] 'agreement among raft nodes before linearized reading' (duration: 4.181437572s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.291567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.141673527s","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":"warn","ts":"2026-04-13T06:09:13.291878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.845753612s","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":"warn","ts":"2026-04-13T06:09:13.291887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:09.110324Z","time spent":"4.181558365s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":573,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-04-13T06:09:13.291902Z","caller":"traceutil/trace.go:171","msg":"trace[2098887245] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:3081; }","duration":"4.845800153s","start":"2026-04-13T06:09:08.446095Z","end":"2026-04-13T06:09:13.291895Z","steps":["trace[2098887245] 'agreement among raft nodes before linearized reading' (duration: 4.845754331s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.291929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:08.446082Z","time spent":"4.845840584s","remote":"127.0.0.1:36846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-04-13T06:09:13.291895Z","caller":"traceutil/trace.go:171","msg":"trace[1690337384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3081; }","duration":"2.142017136s","start":"2026-04-13T06:09:11.14987Z","end":"2026-04-13T06:09:13.291887Z","steps":["trace[1690337384] 'agreement among raft nodes before linearized reading' (duration: 2.141668997s)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.291873Z","caller":"traceutil/trace.go:171","msg":"trace[844484162] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3081; }","duration":"4.168493292s","start":"2026-04-13T06:09:09.123282Z","end":"2026-04-13T06:09:13.291775Z","steps":["trace[844484162] 'agreement among raft nodes before linearized reading' (duration: 4.166853911s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.292065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:09.123277Z","time spent":"4.168776989s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-13T06:09:13.291742Z","caller":"traceutil/trace.go:171","msg":"trace[1494118504] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3081; }","duration":"2.152120352s","start":"2026-04-13T06:09:11.139617Z","end":"2026-04-13T06:09:13.291737Z","steps":["trace[1494118504] 'agreement among raft nodes before linearized reading' (duration: 2.151959468s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.292242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:11.139605Z","time spent":"2.152627814s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T06:09:13.29174Z","caller":"traceutil/trace.go:171","msg":"trace[899276901] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:3081; }","duration":"4.153610241s","start":"2026-04-13T06:09:09.138109Z","end":"2026-04-13T06:09:13.291719Z","steps":["trace[899276901] 'agreement among raft nodes before linearized reading' (duration: 4.15354185s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.292339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:09.13809Z","time spent":"4.154236467s","remote":"127.0.0.1:48114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-04-13T06:09:13.291984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:11.14986Z","time spent":"2.142117188s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T06:09:13.291753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.834935931s","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-04-13T06:09:13.295509Z","caller":"traceutil/trace.go:171","msg":"trace[1606844622] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:3081; }","duration":"5.838722267s","start":"2026-04-13T06:09:07.456768Z","end":"2026-04-13T06:09:13.29549Z","steps":["trace[1606844622] 'agreement among raft nodes before linearized reading' (duration: 5.834963081s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.295551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:07.456744Z","time spent":"5.838794068s","remote":"127.0.0.1:45296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-13T06:09:13.444083Z","caller":"traceutil/trace.go:171","msg":"trace[1272075029] transaction","detail":"{read_only:false; response_revision:3082; number_of_response:1; }","duration":"145.138013ms","start":"2026-04-13T06:09:13.298924Z","end":"2026-04-13T06:09:13.444062Z","steps":["trace[1272075029] 'process raft request' (duration: 144.929028ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.444451Z","caller":"traceutil/trace.go:171","msg":"trace[1657061483] transaction","detail":"{read_only:false; response_revision:3086; number_of_response:1; }","duration":"143.0644ms","start":"2026-04-13T06:09:13.301367Z","end":"2026-04-13T06:09:13.444432Z","steps":["trace[1657061483] 'process raft request' (duration: 143.003419ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.444507Z","caller":"traceutil/trace.go:171","msg":"trace[950680980] transaction","detail":"{read_only:false; response_revision:3083; number_of_response:1; }","duration":"145.274746ms","start":"2026-04-13T06:09:13.299218Z","end":"2026-04-13T06:09:13.444493Z","steps":["trace[950680980] 'process raft request' (duration: 144.799443ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.444584Z","caller":"traceutil/trace.go:171","msg":"trace[262732539] transaction","detail":"{read_only:false; response_revision:3085; number_of_response:1; }","duration":"143.294396ms","start":"2026-04-13T06:09:13.301282Z","end":"2026-04-13T06:09:13.444576Z","steps":["trace[262732539] 'process raft request' (duration: 143.059399ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.444672Z","caller":"traceutil/trace.go:171","msg":"trace[1360309322] transaction","detail":"{read_only:false; response_revision:3087; number_of_response:1; }","duration":"143.262355ms","start":"2026-04-13T06:09:13.301386Z","end":"2026-04-13T06:09:13.444648Z","steps":["trace[1360309322] 'process raft request' (duration: 143.018299ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.444859Z","caller":"traceutil/trace.go:171","msg":"trace[275781072] transaction","detail":"{read_only:false; response_revision:3089; number_of_response:1; }","duration":"143.306156ms","start":"2026-04-13T06:09:13.301535Z","end":"2026-04-13T06:09:13.444841Z","steps":["trace[275781072] 'process raft request' (duration: 143.184613ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.444821Z","caller":"traceutil/trace.go:171","msg":"trace[1253096886] transaction","detail":"{read_only:false; response_revision:3084; number_of_response:1; }","duration":"145.272156ms","start":"2026-04-13T06:09:13.299472Z","end":"2026-04-13T06:09:13.444745Z","steps":["trace[1253096886] 'process raft request' (duration: 144.773394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.444962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.391878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:1 size:489"} {"level":"info","ts":"2026-04-13T06:09:13.444998Z","caller":"traceutil/trace.go:171","msg":"trace[1837577296] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:1; response_revision:3089; }","duration":"143.44929ms","start":"2026-04-13T06:09:13.301538Z","end":"2026-04-13T06:09:13.444987Z","steps":["trace[1837577296] 'agreement among raft nodes before linearized reading' (duration: 143.307396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.44503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.637915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-04-13T06:09:13.445078Z","caller":"traceutil/trace.go:171","msg":"trace[1305518065] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:3089; }","duration":"143.725877ms","start":"2026-04-13T06:09:13.301339Z","end":"2026-04-13T06:09:13.445065Z","steps":["trace[1305518065] 'agreement among raft nodes before linearized reading' (duration: 143.550573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.445107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.009504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-04-13T06:09:13.445125Z","caller":"traceutil/trace.go:171","msg":"trace[1243010225] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3089; }","duration":"146.038306ms","start":"2026-04-13T06:09:13.299081Z","end":"2026-04-13T06:09:13.44512Z","steps":["trace[1243010225] 'agreement among raft nodes before linearized reading' (duration: 145.976014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.445035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.562013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-04-13T06:09:13.445219Z","caller":"traceutil/trace.go:171","msg":"trace[1776283991] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3089; }","duration":"143.779698ms","start":"2026-04-13T06:09:13.301425Z","end":"2026-04-13T06:09:13.445205Z","steps":["trace[1776283991] 'agreement among raft nodes before linearized reading' (duration: 143.389869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.445305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.885041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-13T06:09:13.445387Z","caller":"traceutil/trace.go:171","msg":"trace[488027979] transaction","detail":"{read_only:false; response_revision:3088; number_of_response:1; }","duration":"143.948093ms","start":"2026-04-13T06:09:13.301432Z","end":"2026-04-13T06:09:13.44538Z","steps":["trace[488027979] 'process raft request' (duration: 143.131352ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.445383Z","caller":"traceutil/trace.go:171","msg":"trace[1821567746] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3089; }","duration":"143.978384ms","start":"2026-04-13T06:09:13.301391Z","end":"2026-04-13T06:09:13.445369Z","steps":["trace[1821567746] 'agreement among raft nodes before linearized reading' (duration: 143.660146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.445471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.757964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1449"} {"level":"warn","ts":"2026-04-13T06:09:13.445487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.432066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-13T06:09:13.445501Z","caller":"traceutil/trace.go:171","msg":"trace[1083032634] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:3089; }","duration":"146.816065ms","start":"2026-04-13T06:09:13.298675Z","end":"2026-04-13T06:09:13.445491Z","steps":["trace[1083032634] 'agreement among raft nodes before linearized reading' (duration: 146.682772ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.445531Z","caller":"traceutil/trace.go:171","msg":"trace[1649335231] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3089; }","duration":"146.524977ms","start":"2026-04-13T06:09:13.298992Z","end":"2026-04-13T06:09:13.445517Z","steps":["trace[1649335231] 'agreement among raft nodes before linearized reading' (duration: 146.369884ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.50143Z","caller":"traceutil/trace.go:171","msg":"trace[665712171] transaction","detail":"{read_only:false; response_revision:3090; number_of_response:1; }","duration":"143.171483ms","start":"2026-04-13T06:09:13.358236Z","end":"2026-04-13T06:09:13.501408Z","steps":["trace[665712171] 'process raft request' (duration: 142.989059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.501559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.823647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T06:09:13.501613Z","caller":"traceutil/trace.go:171","msg":"trace[1563671563] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:3090; }","duration":"116.913369ms","start":"2026-04-13T06:09:13.384684Z","end":"2026-04-13T06:09:13.501597Z","steps":["trace[1563671563] 'agreement among raft nodes before linearized reading' (duration: 116.730174ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.624156Z","caller":"traceutil/trace.go:171","msg":"trace[646939260] linearizableReadLoop","detail":"{readStateIndex:3212; appliedIndex:3211; }","duration":"117.986336ms","start":"2026-04-13T06:09:13.50613Z","end":"2026-04-13T06:09:13.624117Z","steps":["trace[646939260] 'read index received' (duration: 38.125025ms)","trace[646939260] 'applied index is now lower than readState.Index' (duration: 79.859671ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:09:13.624215Z","caller":"traceutil/trace.go:171","msg":"trace[267252085] transaction","detail":"{read_only:false; response_revision:3091; number_of_response:1; }","duration":"119.910434ms","start":"2026-04-13T06:09:13.504258Z","end":"2026-04-13T06:09:13.624168Z","steps":["trace[267252085] 'process raft request' (duration: 39.993492ms)","trace[267252085] 'compare' (duration: 79.672116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:09:13.624821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.415117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7462"} {"level":"info","ts":"2026-04-13T06:09:13.625379Z","caller":"traceutil/trace.go:171","msg":"trace[99817307] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:3091; }","duration":"119.017242ms","start":"2026-04-13T06:09:13.506333Z","end":"2026-04-13T06:09:13.62535Z","steps":["trace[99817307] 'agreement among raft nodes before linearized reading' (duration: 118.11402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.625038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.854868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-13T06:09:13.625724Z","caller":"traceutil/trace.go:171","msg":"trace[1358998981] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:3091; }","duration":"119.594446ms","start":"2026-04-13T06:09:13.506104Z","end":"2026-04-13T06:09:13.625698Z","steps":["trace[1358998981] 'agreement among raft nodes before linearized reading' (duration: 118.17723ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:10:31.920872Z","caller":"traceutil/trace.go:171","msg":"trace[629289136] transaction","detail":"{read_only:false; response_revision:3585; number_of_response:1; }","duration":"122.698358ms","start":"2026-04-13T06:10:31.798146Z","end":"2026-04-13T06:10:31.920844Z","steps":["trace[629289136] 'process raft request' (duration: 121.726656ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:10:31.92147Z","caller":"traceutil/trace.go:171","msg":"trace[848676652] linearizableReadLoop","detail":"{readStateIndex:3728; appliedIndex:3725; }","duration":"112.815157ms","start":"2026-04-13T06:10:31.808636Z","end":"2026-04-13T06:10:31.921452Z","steps":["trace[848676652] 'read index received' (duration: 111.406715ms)","trace[848676652] 'applied index is now lower than readState.Index' (duration: 1.407452ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:10:31.922092Z","caller":"traceutil/trace.go:171","msg":"trace[1584629047] transaction","detail":"{read_only:false; response_revision:3586; number_of_response:1; }","duration":"121.869219ms","start":"2026-04-13T06:10:31.800208Z","end":"2026-04-13T06:10:31.922078Z","steps":["trace[1584629047] 'process raft request' (duration: 121.099922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:10:31.92226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.595274ms","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-04-13T06:10:31.922327Z","caller":"traceutil/trace.go:171","msg":"trace[808680397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3587; }","duration":"113.748167ms","start":"2026-04-13T06:10:31.808562Z","end":"2026-04-13T06:10:31.922311Z","steps":["trace[808680397] 'agreement among raft nodes before linearized reading' (duration: 113.031111ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:10:31.922308Z","caller":"traceutil/trace.go:171","msg":"trace[942332442] transaction","detail":"{read_only:false; response_revision:3587; number_of_response:1; }","duration":"121.849519ms","start":"2026-04-13T06:10:31.800412Z","end":"2026-04-13T06:10:31.922262Z","steps":["trace[942332442] 'process raft request' (duration: 120.99053ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:11:55.927455Z","caller":"traceutil/trace.go:171","msg":"trace[1243200970] transaction","detail":"{read_only:false; response_revision:4466; number_of_response:1; }","duration":"160.24546ms","start":"2026-04-13T06:11:55.767172Z","end":"2026-04-13T06:11:55.927418Z","steps":["trace[1243200970] 'process raft request' (duration: 159.609967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:12:07.826457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.319111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5c92adbc-493a-4dc7-9789-37e150a11b8b\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-13T06:12:07.826607Z","caller":"traceutil/trace.go:171","msg":"trace[725200452] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5c92adbc-493a-4dc7-9789-37e150a11b8b; range_end:; response_count:1; response_revision:4756; }","duration":"267.532755ms","start":"2026-04-13T06:12:07.55904Z","end":"2026-04-13T06:12:07.826573Z","steps":["trace[725200452] 'range keys from in-memory index tree' (duration: 267.020165ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:12:24.806027Z","caller":"traceutil/trace.go:171","msg":"trace[1352767975] linearizableReadLoop","detail":"{readStateIndex:5161; appliedIndex:5160; }","duration":"117.817472ms","start":"2026-04-13T06:12:24.688183Z","end":"2026-04-13T06:12:24.806001Z","steps":["trace[1352767975] 'read index received' (duration: 117.587778ms)","trace[1352767975] 'applied index is now lower than readState.Index' (duration: 228.974µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:12:24.806149Z","caller":"traceutil/trace.go:171","msg":"trace[1412112053] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"120.863344ms","start":"2026-04-13T06:12:24.685277Z","end":"2026-04-13T06:12:24.80614Z","steps":["trace[1412112053] 'process raft request' (duration: 120.567808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:12:24.80642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.185709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T06:12:24.806456Z","caller":"traceutil/trace.go:171","msg":"trace[1407517270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4968; }","duration":"118.329233ms","start":"2026-04-13T06:12:24.688115Z","end":"2026-04-13T06:12:24.806444Z","steps":["trace[1407517270] 'agreement among raft nodes before linearized reading' (duration: 118.16406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:12:24.806726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.360562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T06:12:24.806987Z","caller":"traceutil/trace.go:171","msg":"trace[1483082064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4968; }","duration":"113.604316ms","start":"2026-04-13T06:12:24.693311Z","end":"2026-04-13T06:12:24.806915Z","steps":["trace[1483082064] 'agreement among raft nodes before linearized reading' (duration: 113.045725ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:12:25.321272Z","caller":"traceutil/trace.go:171","msg":"trace[150129757] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"509.274974ms","start":"2026-04-13T06:12:24.811936Z","end":"2026-04-13T06:12:25.321211Z","steps":["trace[150129757] 'process raft request' (duration: 417.939539ms)","trace[150129757] 'compare' (duration: 91.043479ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:12:25.32151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:12:24.811902Z","time spent":"509.495708ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:12:25.33516Z","caller":"traceutil/trace.go:171","msg":"trace[802680770] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"522.90317ms","start":"2026-04-13T06:12:24.812222Z","end":"2026-04-13T06:12:25.335125Z","steps":["trace[802680770] 'process raft request' (duration: 522.810128ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:12:25.335205Z","caller":"traceutil/trace.go:171","msg":"trace[345782185] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"523.074873ms","start":"2026-04-13T06:12:24.812078Z","end":"2026-04-13T06:12:25.335153Z","steps":["trace[345782185] 'process raft request' (duration: 522.778018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:12:25.335354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:12:24.81219Z","time spent":"523.063993ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:12:25.335397Z","caller":"traceutil/trace.go:171","msg":"trace[478525670] linearizableReadLoop","detail":"{readStateIndex:5165; appliedIndex:5161; }","duration":"491.338121ms","start":"2026-04-13T06:12:24.844026Z","end":"2026-04-13T06:12:25.335365Z","steps":["trace[478525670] 'read index received' (duration: 385.8691ms)","trace[478525670] 'applied index is now lower than readState.Index' (duration: 105.449811ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:12:25.335506Z","caller":"traceutil/trace.go:171","msg":"trace[609057680] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"523.154586ms","start":"2026-04-13T06:12:24.812339Z","end":"2026-04-13T06:12:25.335494Z","steps":["trace[609057680] 'process raft request' (duration: 522.746637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:12:25.33557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:12:24.812318Z","time spent":"523.221966ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T06:12:25.335614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.580146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:12:25.335674Z","caller":"traceutil/trace.go:171","msg":"trace[299428365] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:4972; }","duration":"491.684899ms","start":"2026-04-13T06:12:24.843964Z","end":"2026-04-13T06:12:25.335648Z","steps":["trace[299428365] 'agreement among raft nodes before linearized reading' (duration: 491.545727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:12:25.335718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:12:24.843938Z","time spent":"491.770241ms","remote":"127.0.0.1:48092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-13T06:12:25.335765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.616771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16550"} {"level":"info","ts":"2026-04-13T06:12:25.335809Z","caller":"traceutil/trace.go:171","msg":"trace[1782941005] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4972; }","duration":"282.707314ms","start":"2026-04-13T06:12:25.053088Z","end":"2026-04-13T06:12:25.335795Z","steps":["trace[1782941005] 'agreement among raft nodes before linearized reading' (duration: 282.568331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:12:25.336025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.047569ms","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-04-13T06:12:25.336256Z","caller":"traceutil/trace.go:171","msg":"trace[2111163067] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4972; }","duration":"184.196952ms","start":"2026-04-13T06:12:25.151905Z","end":"2026-04-13T06:12:25.336102Z","steps":["trace[2111163067] 'agreement among raft nodes before linearized reading' (duration: 183.918147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:12:25.336154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:12:24.812027Z","time spent":"523.263157ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:12:28.82546Z","caller":"traceutil/trace.go:171","msg":"trace[16676234] linearizableReadLoop","detail":"{readStateIndex:5187; appliedIndex:5183; }","duration":"207.675031ms","start":"2026-04-13T06:12:28.617759Z","end":"2026-04-13T06:12:28.825434Z","steps":["trace[16676234] 'read index received' (duration: 205.242031ms)","trace[16676234] 'applied index is now lower than readState.Index' (duration: 2.43106ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:12:28.826092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.320223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-13T06:12:28.826484Z","caller":"traceutil/trace.go:171","msg":"trace[1692388683] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"259.378333ms","start":"2026-04-13T06:12:28.567075Z","end":"2026-04-13T06:12:28.826454Z","steps":["trace[1692388683] 'process raft request' (duration: 258.160589ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:12:28.826599Z","caller":"traceutil/trace.go:171","msg":"trace[467503440] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"256.542776ms","start":"2026-04-13T06:12:28.569991Z","end":"2026-04-13T06:12:28.826533Z","steps":["trace[467503440] 'process raft request' (duration: 255.327252ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:12:28.826665Z","caller":"traceutil/trace.go:171","msg":"trace[533264904] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"483.453474ms","start":"2026-04-13T06:12:28.343196Z","end":"2026-04-13T06:12:28.82665Z","steps":["trace[533264904] 'process raft request' (duration: 479.704018ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:12:28.826678Z","caller":"traceutil/trace.go:171","msg":"trace[1157987694] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"363.891042ms","start":"2026-04-13T06:12:28.462768Z","end":"2026-04-13T06:12:28.826659Z","steps":["trace[1157987694] 'process raft request' (duration: 362.369372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:12:28.826498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.413033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-04-13T06:12:28.8266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.50659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T06:12:28.826793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:12:28.462746Z","time spent":"363.978984ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T06:12:28.8268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:12:28.343165Z","time spent":"483.546936ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:12:28.826804Z","caller":"traceutil/trace.go:171","msg":"trace[896061530] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4995; }","duration":"196.765999ms","start":"2026-04-13T06:12:28.630013Z","end":"2026-04-13T06:12:28.826779Z","steps":["trace[896061530] 'agreement among raft nodes before linearized reading' (duration: 196.255339ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:12:28.82693Z","caller":"traceutil/trace.go:171","msg":"trace[1614169910] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4995; }","duration":"175.776498ms","start":"2026-04-13T06:12:28.651047Z","end":"2026-04-13T06:12:28.826824Z","steps":["trace[1614169910] 'agreement among raft nodes before linearized reading' (duration: 175.495582ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:12:28.826175Z","caller":"traceutil/trace.go:171","msg":"trace[1121530042] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4995; }","duration":"208.465776ms","start":"2026-04-13T06:12:28.617691Z","end":"2026-04-13T06:12:28.826156Z","steps":["trace[1121530042] 'agreement among raft nodes before linearized reading' (duration: 208.119309ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:13:05.237444Z","caller":"traceutil/trace.go:171","msg":"trace[562709444] linearizableReadLoop","detail":"{readStateIndex:5507; appliedIndex:5504; }","duration":"233.722972ms","start":"2026-04-13T06:13:05.003691Z","end":"2026-04-13T06:13:05.237414Z","steps":["trace[562709444] 'read index received' (duration: 78.240891ms)","trace[562709444] 'applied index is now lower than readState.Index' (duration: 155.481191ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:13:05.23768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.976878ms","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-04-13T06:13:05.237714Z","caller":"traceutil/trace.go:171","msg":"trace[1445989460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5304; }","duration":"234.073318ms","start":"2026-04-13T06:13:05.003632Z","end":"2026-04-13T06:13:05.237705Z","steps":["trace[1445989460] 'agreement among raft nodes before linearized reading' (duration: 233.884164ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:13:05.237948Z","caller":"traceutil/trace.go:171","msg":"trace[531055760] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"239.880034ms","start":"2026-04-13T06:13:04.998017Z","end":"2026-04-13T06:13:05.237896Z","steps":["trace[531055760] 'process raft request' (duration: 239.185731ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:13:05.237957Z","caller":"traceutil/trace.go:171","msg":"trace[985506041] transaction","detail":"{read_only:false; response_revision:5303; number_of_response:1; }","duration":"238.547877ms","start":"2026-04-13T06:13:04.99937Z","end":"2026-04-13T06:13:05.237918Z","steps":["trace[985506041] 'process raft request' (duration: 237.989377ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:13:05.238954Z","caller":"traceutil/trace.go:171","msg":"trace[423183830] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"198.203229ms","start":"2026-04-13T06:13:05.039772Z","end":"2026-04-13T06:13:05.237975Z","steps":["trace[423183830] 'process raft request' (duration: 197.616007ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:13:26.486194Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2911} {"level":"info","ts":"2026-04-13T06:13:26.58702Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2911,"took":"99.054277ms","hash":2214012349,"current-db-size-bytes":53952512,"current-db-size":"54 MB","current-db-size-in-use-bytes":28835840,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-13T06:13:26.587095Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2214012349,"revision":2911,"compact-revision":-1} {"level":"warn","ts":"2026-04-13T06:13:37.930503Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566346912293766,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T06:13:37.976026Z","caller":"traceutil/trace.go:171","msg":"trace[1090450956] transaction","detail":"{read_only:false; response_revision:5553; number_of_response:1; }","duration":"615.712107ms","start":"2026-04-13T06:13:37.360282Z","end":"2026-04-13T06:13:37.975994Z","steps":["trace[1090450956] 'process raft request' (duration: 615.504463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:37.976333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:37.360256Z","time spent":"615.875931ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:13:38.8817Z","caller":"traceutil/trace.go:171","msg":"trace[1179230218] linearizableReadLoop","detail":"{readStateIndex:5767; appliedIndex:5765; }","duration":"1.452254574s","start":"2026-04-13T06:13:37.429426Z","end":"2026-04-13T06:13:38.881681Z","steps":["trace[1179230218] 'read index received' (duration: 546.558904ms)","trace[1179230218] 'applied index is now lower than readState.Index' (duration: 905.69415ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:13:38.881794Z","caller":"traceutil/trace.go:171","msg":"trace[828900028] transaction","detail":"{read_only:false; response_revision:5555; number_of_response:1; }","duration":"1.50672187s","start":"2026-04-13T06:13:37.37504Z","end":"2026-04-13T06:13:38.881762Z","steps":["trace[828900028] 'process raft request' (duration: 1.506580318s)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:13:38.881791Z","caller":"traceutil/trace.go:171","msg":"trace[130020438] transaction","detail":"{read_only:false; response_revision:5554; number_of_response:1; }","duration":"1.514502803s","start":"2026-04-13T06:13:37.367216Z","end":"2026-04-13T06:13:38.881719Z","steps":["trace[130020438] 'process raft request' (duration: 1.507383433s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.881932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:37.375004Z","time spent":"1.506853773s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T06:13:38.881948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.45249232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-13T06:13:38.881972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:37.367188Z","time spent":"1.514688425s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:13:38.882001Z","caller":"traceutil/trace.go:171","msg":"trace[2078906391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5555; }","duration":"1.45261969s","start":"2026-04-13T06:13:37.429354Z","end":"2026-04-13T06:13:38.881974Z","steps":["trace[2078906391] 'agreement among raft nodes before linearized reading' (duration: 1.452446778s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.882037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:37.429325Z","time spent":"1.452703033s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T06:13:38.884561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2677345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T06:13:38.884624Z","caller":"traceutil/trace.go:171","msg":"trace[1638854032] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:5556; }","duration":"1.267834912s","start":"2026-04-13T06:13:37.616769Z","end":"2026-04-13T06:13:38.884604Z","steps":["trace[1638854032] 'agreement among raft nodes before linearized reading' (duration: 1.26770258s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.88473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:37.616748Z","time spent":"1.267970926s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":2,"response size":30,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-04-13T06:13:38.884759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.477059ms","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-04-13T06:13:38.884844Z","caller":"traceutil/trace.go:171","msg":"trace[275476866] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5556; }","duration":"782.626311ms","start":"2026-04-13T06:13:38.102193Z","end":"2026-04-13T06:13:38.884819Z","steps":["trace[275476866] 'agreement among raft nodes before linearized reading' (duration: 782.471697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.884892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:38.102171Z","time spent":"782.709453ms","remote":"127.0.0.1:47150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-13T06:13:38.885037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086280448s","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-04-13T06:13:38.885067Z","caller":"traceutil/trace.go:171","msg":"trace[1839860925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5556; }","duration":"1.086358698s","start":"2026-04-13T06:13:37.798701Z","end":"2026-04-13T06:13:38.88506Z","steps":["trace[1839860925] 'agreement among raft nodes before linearized reading' (duration: 1.086215706s)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:13:38.885078Z","caller":"traceutil/trace.go:171","msg":"trace[7410954] transaction","detail":"{read_only:false; response_revision:5556; number_of_response:1; }","duration":"481.546437ms","start":"2026-04-13T06:13:38.403502Z","end":"2026-04-13T06:13:38.885048Z","steps":["trace[7410954] 'process raft request' (duration: 480.626509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.885132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095555349s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-13T06:13:38.885182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.037821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8314"} {"level":"info","ts":"2026-04-13T06:13:38.885192Z","caller":"traceutil/trace.go:171","msg":"trace[1215318138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5556; }","duration":"1.095665491s","start":"2026-04-13T06:13:37.789509Z","end":"2026-04-13T06:13:38.885174Z","steps":["trace[1215318138] 'agreement among raft nodes before linearized reading' (duration: 1.095520529s)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:13:38.885223Z","caller":"traceutil/trace.go:171","msg":"trace[1321996821] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:5556; }","duration":"493.115463ms","start":"2026-04-13T06:13:38.392098Z","end":"2026-04-13T06:13:38.885214Z","steps":["trace[1321996821] 'agreement among raft nodes before linearized reading' (duration: 492.97357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.885217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:38.403485Z","time spent":"481.6495ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T06:13:38.885087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:37.798677Z","time spent":"1.086404739s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T06:13:38.885252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:38.392075Z","time spent":"493.169725ms","remote":"127.0.0.1:36850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":8337,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-04-13T06:13:38.885251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:37.789483Z","time spent":"1.095759253s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T06:13:38.885772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046342915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T06:13:38.88584Z","caller":"traceutil/trace.go:171","msg":"trace[725603964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5556; }","duration":"1.04715607s","start":"2026-04-13T06:13:37.838667Z","end":"2026-04-13T06:13:38.885824Z","steps":["trace[725603964] 'agreement among raft nodes before linearized reading' (duration: 1.046187791s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.885876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:37.838649Z","time spent":"1.047215772s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T06:13:38.885986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04711849s","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-04-13T06:13:38.886677Z","caller":"traceutil/trace.go:171","msg":"trace[663690231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5556; }","duration":"1.047921076s","start":"2026-04-13T06:13:37.838737Z","end":"2026-04-13T06:13:38.886659Z","steps":["trace[663690231] 'agreement among raft nodes before linearized reading' (duration: 1.046379857s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.886841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:37.838716Z","time spent":"1.048109549s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T06:13:40.247214Z","caller":"traceutil/trace.go:171","msg":"trace[1353451652] transaction","detail":"{read_only:false; response_revision:5569; number_of_response:1; }","duration":"265.26491ms","start":"2026-04-13T06:13:39.981921Z","end":"2026-04-13T06:13:40.247186Z","steps":["trace[1353451652] 'process raft request' (duration: 265.093247ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:18:26.492385Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5468} {"level":"info","ts":"2026-04-13T06:18:26.581412Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5468,"took":"87.254818ms","hash":3588191217,"current-db-size-bytes":53952512,"current-db-size":"54 MB","current-db-size-in-use-bytes":16748544,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-04-13T06:18:26.581492Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3588191217,"revision":5468,"compact-revision":2911} {"level":"info","ts":"2026-04-13T06:21:57.74947Z","caller":"traceutil/trace.go:171","msg":"trace[88209203] linearizableReadLoop","detail":"{readStateIndex:8528; appliedIndex:8527; }","duration":"111.579324ms","start":"2026-04-13T06:21:57.637865Z","end":"2026-04-13T06:21:57.749444Z","steps":["trace[88209203] 'read index received' (duration: 54.441957ms)","trace[88209203] 'applied index is now lower than readState.Index' (duration: 57.135977ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:21:57.749582Z","caller":"traceutil/trace.go:171","msg":"trace[1327642957] transaction","detail":"{read_only:false; response_revision:8210; number_of_response:1; }","duration":"113.33026ms","start":"2026-04-13T06:21:57.636212Z","end":"2026-04-13T06:21:57.749542Z","steps":["trace[1327642957] 'process raft request' (duration: 56.11602ms)","trace[1327642957] 'compare' (duration: 56.990443ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:21:57.749727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.83643ms","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-04-13T06:21:57.749774Z","caller":"traceutil/trace.go:171","msg":"trace[1521613251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8210; }","duration":"111.940364ms","start":"2026-04-13T06:21:57.637823Z","end":"2026-04-13T06:21:57.749764Z","steps":["trace[1521613251] 'agreement among raft nodes before linearized reading' (duration: 111.77504ms)"],"step_count":1}