{"level":"warn","ts":"2026-04-20T10:12:29.452836Z","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-20T10:12:29.45299Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.87: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.19.213.87:2380","--initial-cluster=instance=https://199.19.213.87:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.87:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.87: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-20T10:12:29.453104Z","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-20T10:12:29.453123Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.87:2380"]} {"level":"info","ts":"2026-04-20T10:12:29.453157Z","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-20T10:12:29.454503Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.87:2379"]} {"level":"info","ts":"2026-04-20T10:12:29.454653Z","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.19.213.87:2380"],"listen-peer-urls":["https://199.19.213.87:2380"],"advertise-client-urls":["https://199.19.213.87:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.87:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.87: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-20T10:12:29.464325Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.318376ms"} {"level":"info","ts":"2026-04-20T10:12:29.474673Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bc3cb673a921c717","cluster-id":"f315f77360ac6549"} {"level":"info","ts":"2026-04-20T10:12:29.474756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T10:12:29.474787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became follower at term 0"} {"level":"info","ts":"2026-04-20T10:12:29.474802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bc3cb673a921c717 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T10:12:29.474827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became follower at term 1"} {"level":"info","ts":"2026-04-20T10:12:29.474879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=(13563916785608148759)"} {"level":"warn","ts":"2026-04-20T10:12:29.483386Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T10:12:29.484874Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T10:12:29.486091Z","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-20T10:12:29.487934Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bc3cb673a921c717","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T10:12:29.488147Z","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-20T10:12:29.489273Z","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-20T10:12:29.489335Z","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-20T10:12:29.488349Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bc3cb673a921c717","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T10:12:29.488883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=(13563916785608148759)"} {"level":"info","ts":"2026-04-20T10:12:29.490355Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f315f77360ac6549","local-member-id":"bc3cb673a921c717","added-peer-id":"bc3cb673a921c717","added-peer-peer-urls":["https://199.19.213.87:2380"]} {"level":"info","ts":"2026-04-20T10:12:29.498153Z","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-20T10:12:29.498366Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.87:2380"} {"level":"info","ts":"2026-04-20T10:12:29.498402Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.87:2380"} {"level":"info","ts":"2026-04-20T10:12:29.498483Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bc3cb673a921c717","initial-advertise-peer-urls":["https://199.19.213.87:2380"],"listen-peer-urls":["https://199.19.213.87:2380"],"advertise-client-urls":["https://199.19.213.87:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.87:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T10:12:29.498536Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T10:12:30.476251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T10:12:30.476408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T10:12:30.476655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 received MsgPreVoteResp from bc3cb673a921c717 at term 1"} {"level":"info","ts":"2026-04-20T10:12:30.476706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became candidate at term 2"} {"level":"info","ts":"2026-04-20T10:12:30.476725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 received MsgVoteResp from bc3cb673a921c717 at term 2"} {"level":"info","ts":"2026-04-20T10:12:30.476744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became leader at term 2"} {"level":"info","ts":"2026-04-20T10:12:30.476822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bc3cb673a921c717 elected leader bc3cb673a921c717 at term 2"} {"level":"info","ts":"2026-04-20T10:12:30.477943Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:12:30.479001Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:12:30.479072Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:12:30.479007Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bc3cb673a921c717","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.87:2379]}","request-path":"/0/members/bc3cb673a921c717/attributes","cluster-id":"f315f77360ac6549","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T10:12:30.479828Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f315f77360ac6549","local-member-id":"bc3cb673a921c717","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:12:30.479558Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T10:12:30.479907Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T10:12:30.479979Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:12:30.480026Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:12:30.483389Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T10:12:30.48359Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.87:2379"} {"level":"warn","ts":"2026-04-20T10:12:40.176015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.22723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:12:40.176155Z","caller":"traceutil/trace.go:171","msg":"trace[1078572144] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"300.834814ms","start":"2026-04-20T10:12:39.875303Z","end":"2026-04-20T10:12:40.176138Z","steps":["trace[1078572144] 'read index received' (duration: 78.683432ms)","trace[1078572144] 'applied index is now lower than readState.Index' (duration: 222.150042ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:12:40.17622Z","caller":"traceutil/trace.go:171","msg":"trace[245710196] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"370.096238ms","start":"2026-04-20T10:12:39.806088Z","end":"2026-04-20T10:12:40.176184Z","steps":["trace[245710196] 'process raft request' (duration: 147.865095ms)","trace[245710196] 'compare' (duration: 221.037078ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:12:40.176322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.017046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"warn","ts":"2026-04-20T10:12:40.176345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:12:39.806058Z","time spent":"370.22858ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:12:40.176414Z","caller":"traceutil/trace.go:171","msg":"trace[823809642] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:374; }","duration":"301.172189ms","start":"2026-04-20T10:12:39.87523Z","end":"2026-04-20T10:12:40.176402Z","steps":["trace[823809642] 'agreement among raft nodes before linearized reading' (duration: 301.009497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:40.176587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:12:39.875204Z","time spent":"301.367961ms","remote":"127.0.0.1:58356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":209,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-04-20T10:12:40.176714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.627752ms","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-20T10:12:40.176919Z","caller":"traceutil/trace.go:171","msg":"trace[2050037911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:374; }","duration":"189.958297ms","start":"2026-04-20T10:12:39.986933Z","end":"2026-04-20T10:12:40.176891Z","steps":["trace[2050037911] 'agreement among raft nodes before linearized reading' (duration: 189.534471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:40.635574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.218923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:12:40.635734Z","caller":"traceutil/trace.go:171","msg":"trace[1693282807] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"455.925442ms","start":"2026-04-20T10:12:40.179779Z","end":"2026-04-20T10:12:40.635705Z","steps":["trace[1693282807] 'process raft request' (duration: 238.495037ms)","trace[1693282807] 'compare' (duration: 217.064541ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:12:40.636044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:12:40.179748Z","time spent":"456.158686ms","remote":"127.0.0.1:58218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:12:40.675983Z","caller":"traceutil/trace.go:171","msg":"trace[597753426] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"495.358913ms","start":"2026-04-20T10:12:40.180562Z","end":"2026-04-20T10:12:40.675921Z","steps":["trace[597753426] 'process raft request' (duration: 495.083319ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:40.676049Z","caller":"traceutil/trace.go:171","msg":"trace[446468402] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:383; }","duration":"400.27968ms","start":"2026-04-20T10:12:40.275737Z","end":"2026-04-20T10:12:40.676017Z","steps":["trace[446468402] 'read index received' (duration: 142.681935ms)","trace[446468402] 'applied index is now lower than readState.Index' (duration: 257.596815ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:12:40.67617Z","caller":"traceutil/trace.go:171","msg":"trace[1884608985] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"494.594674ms","start":"2026-04-20T10:12:40.181561Z","end":"2026-04-20T10:12:40.676156Z","steps":["trace[1884608985] 'process raft request' (duration: 494.2717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:40.676276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:12:40.181544Z","time spent":"494.663074ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:12:40.67633Z","caller":"traceutil/trace.go:171","msg":"trace[1276553011] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"493.855535ms","start":"2026-04-20T10:12:40.182446Z","end":"2026-04-20T10:12:40.676302Z","steps":["trace[1276553011] 'process raft request' (duration: 493.438099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:40.67637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.637114ms","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-20T10:12:40.67641Z","caller":"traceutil/trace.go:171","msg":"trace[1486230858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:379; }","duration":"400.731256ms","start":"2026-04-20T10:12:40.275667Z","end":"2026-04-20T10:12:40.676398Z","steps":["trace[1486230858] 'agreement among raft nodes before linearized reading' (duration: 400.652095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:40.676439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:12:40.275636Z","time spent":"400.793626ms","remote":"127.0.0.1:58422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T10:12:40.676439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.774414ms","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-20T10:12:40.676496Z","caller":"traceutil/trace.go:171","msg":"trace[1672219188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:379; }","duration":"313.889106ms","start":"2026-04-20T10:12:40.362589Z","end":"2026-04-20T10:12:40.676478Z","steps":["trace[1672219188] 'agreement among raft nodes before linearized reading' (duration: 313.745244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:40.676529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:12:40.36256Z","time spent":"313.961806ms","remote":"127.0.0.1:58422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T10:12:40.676658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:12:40.182435Z","time spent":"493.940936ms","remote":"127.0.0.1:58422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:12:40.676714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.039798ms","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-20T10:12:40.676864Z","caller":"traceutil/trace.go:171","msg":"trace[865144270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:379; }","duration":"167.252221ms","start":"2026-04-20T10:12:40.509574Z","end":"2026-04-20T10:12:40.676826Z","steps":["trace[865144270] 'agreement among raft nodes before linearized reading' (duration: 166.895237ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:40.676256Z","caller":"traceutil/trace.go:171","msg":"trace[941117785] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"489.608802ms","start":"2026-04-20T10:12:40.186617Z","end":"2026-04-20T10:12:40.676226Z","steps":["trace[941117785] 'process raft request' (duration: 489.329918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:40.677004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:12:40.180532Z","time spent":"495.530996ms","remote":"127.0.0.1:58218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:12:40.67708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:12:40.186598Z","time spent":"490.427223ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:13:10.114615Z","caller":"traceutil/trace.go:171","msg":"trace[445080514] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"183.449101ms","start":"2026-04-20T10:13:09.931145Z","end":"2026-04-20T10:13:10.114594Z","steps":["trace[445080514] 'read index received' (duration: 182.700934ms)","trace[445080514] 'applied index is now lower than readState.Index' (duration: 746.947µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:13:10.114748Z","caller":"traceutil/trace.go:171","msg":"trace[816936733] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"187.994823ms","start":"2026-04-20T10:13:09.926746Z","end":"2026-04-20T10:13:10.114741Z","steps":["trace[816936733] 'process raft request' (duration: 187.344197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:10.115655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.40774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:13:10.115773Z","caller":"traceutil/trace.go:171","msg":"trace[1024009036] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:623; }","duration":"184.619482ms","start":"2026-04-20T10:13:09.93112Z","end":"2026-04-20T10:13:10.11574Z","steps":["trace[1024009036] 'agreement among raft nodes before linearized reading' (duration: 184.39908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:10.115964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.190135ms","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":"info","ts":"2026-04-20T10:13:10.116038Z","caller":"traceutil/trace.go:171","msg":"trace[689723681] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:623; }","duration":"161.284306ms","start":"2026-04-20T10:13:09.954719Z","end":"2026-04-20T10:13:10.116003Z","steps":["trace[689723681] 'agreement among raft nodes before linearized reading' (duration: 161.151445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:10.116068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.7395ms","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-20T10:13:10.116107Z","caller":"traceutil/trace.go:171","msg":"trace[1637959538] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:623; }","duration":"161.792671ms","start":"2026-04-20T10:13:09.954302Z","end":"2026-04-20T10:13:10.116095Z","steps":["trace[1637959538] 'agreement among raft nodes before linearized reading' (duration: 161.74053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:10.1162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.906582ms","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-20T10:13:10.116231Z","caller":"traceutil/trace.go:171","msg":"trace[55849009] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:623; }","duration":"161.962902ms","start":"2026-04-20T10:13:09.954259Z","end":"2026-04-20T10:13:10.116222Z","steps":["trace[55849009] 'agreement among raft nodes before linearized reading' (duration: 161.916952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:10.116309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.722935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:13:10.116341Z","caller":"traceutil/trace.go:171","msg":"trace[1522875051] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:623; }","duration":"182.802455ms","start":"2026-04-20T10:13:09.933531Z","end":"2026-04-20T10:13:10.116333Z","steps":["trace[1522875051] 'agreement among raft nodes before linearized reading' (duration: 182.758445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:10.116296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.137856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:13:10.116396Z","caller":"traceutil/trace.go:171","msg":"trace[1353565546] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:623; }","duration":"185.253357ms","start":"2026-04-20T10:13:09.931124Z","end":"2026-04-20T10:13:10.116377Z","steps":["trace[1353565546] 'agreement among raft nodes before linearized reading' (duration: 185.108086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:10.116662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.980987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:13:10.116688Z","caller":"traceutil/trace.go:171","msg":"trace[1855875856] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:623; }","duration":"129.020217ms","start":"2026-04-20T10:13:09.987659Z","end":"2026-04-20T10:13:10.116679Z","steps":["trace[1855875856] 'agreement among raft nodes before linearized reading' (duration: 128.758105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:10.116762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.113908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:13:10.117876Z","caller":"traceutil/trace.go:171","msg":"trace[1237249287] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:623; }","duration":"130.261628ms","start":"2026-04-20T10:13:09.987609Z","end":"2026-04-20T10:13:10.117871Z","steps":["trace[1237249287] 'agreement among raft nodes before linearized reading' (duration: 129.128968ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:13:10.350352Z","caller":"traceutil/trace.go:171","msg":"trace[298031925] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"106.900663ms","start":"2026-04-20T10:13:10.243419Z","end":"2026-04-20T10:13:10.350319Z","steps":["trace[298031925] 'read index received' (duration: 106.642231ms)","trace[298031925] 'applied index is now lower than readState.Index' (duration: 256.772µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:13:10.350515Z","caller":"traceutil/trace.go:171","msg":"trace[356698205] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"159.006151ms","start":"2026-04-20T10:13:10.191409Z","end":"2026-04-20T10:13:10.350415Z","steps":["trace[356698205] 'process raft request' (duration: 158.707578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:10.350638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.177516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:13:10.350693Z","caller":"traceutil/trace.go:171","msg":"trace[1367168091] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:624; }","duration":"107.287066ms","start":"2026-04-20T10:13:10.243391Z","end":"2026-04-20T10:13:10.350678Z","steps":["trace[1367168091] 'agreement among raft nodes before linearized reading' (duration: 107.096425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:10.350668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.854722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:13:10.351145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.56733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:13:10.351166Z","caller":"traceutil/trace.go:171","msg":"trace[1881993747] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:624; }","duration":"107.391837ms","start":"2026-04-20T10:13:10.243757Z","end":"2026-04-20T10:13:10.351149Z","steps":["trace[1881993747] 'agreement among raft nodes before linearized reading' (duration: 106.794371ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:13:10.351204Z","caller":"traceutil/trace.go:171","msg":"trace[1811083676] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:624; }","duration":"106.63061ms","start":"2026-04-20T10:13:10.244552Z","end":"2026-04-20T10:13:10.351183Z","steps":["trace[1811083676] 'agreement among raft nodes before linearized reading' (duration: 106.552739ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:13:10.799819Z","caller":"traceutil/trace.go:171","msg":"trace[1178109859] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"212.577874ms","start":"2026-04-20T10:13:10.58721Z","end":"2026-04-20T10:13:10.799788Z","steps":["trace[1178109859] 'process raft request' (duration: 212.326922ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:13:11.049862Z","caller":"traceutil/trace.go:171","msg":"trace[1712482279] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"181.786948ms","start":"2026-04-20T10:13:10.868042Z","end":"2026-04-20T10:13:11.049829Z","steps":["trace[1712482279] 'read index received' (duration: 179.424756ms)","trace[1712482279] 'applied index is now lower than readState.Index' (duration: 2.361282ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:13:11.049945Z","caller":"traceutil/trace.go:171","msg":"trace[1108922066] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"243.329543ms","start":"2026-04-20T10:13:10.806572Z","end":"2026-04-20T10:13:11.049901Z","steps":["trace[1108922066] 'process raft request' (duration: 242.408495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:11.050158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.09044ms","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-20T10:13:11.050211Z","caller":"traceutil/trace.go:171","msg":"trace[1006990046] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"182.216552ms","start":"2026-04-20T10:13:10.86798Z","end":"2026-04-20T10:13:11.050197Z","steps":["trace[1006990046] 'agreement among raft nodes before linearized reading' (duration: 182.02304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:11.050419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.372476ms","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-20T10:13:11.050485Z","caller":"traceutil/trace.go:171","msg":"trace[1919230312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"179.455326ms","start":"2026-04-20T10:13:10.871011Z","end":"2026-04-20T10:13:11.050466Z","steps":["trace[1919230312] 'agreement among raft nodes before linearized reading' (duration: 179.275995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:11.05061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.066032ms","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-20T10:13:11.050687Z","caller":"traceutil/trace.go:171","msg":"trace[23983453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"179.174343ms","start":"2026-04-20T10:13:10.871489Z","end":"2026-04-20T10:13:11.050663Z","steps":["trace[23983453] 'agreement among raft nodes before linearized reading' (duration: 178.890171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:11.05071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.108743ms","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-20T10:13:11.050896Z","caller":"traceutil/trace.go:171","msg":"trace[770222328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"179.367646ms","start":"2026-04-20T10:13:10.871477Z","end":"2026-04-20T10:13:11.050845Z","steps":["trace[770222328] 'agreement among raft nodes before linearized reading' (duration: 178.889132ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:13:26.526961Z","caller":"traceutil/trace.go:171","msg":"trace[247230035] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"178.938811ms","start":"2026-04-20T10:13:26.347985Z","end":"2026-04-20T10:13:26.526923Z","steps":["trace[247230035] 'read index received' (duration: 178.499617ms)","trace[247230035] 'applied index is now lower than readState.Index' (duration: 437.264µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:13:26.527054Z","caller":"traceutil/trace.go:171","msg":"trace[185468142] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"197.99184ms","start":"2026-04-20T10:13:26.329044Z","end":"2026-04-20T10:13:26.527035Z","steps":["trace[185468142] 'process raft request' (duration: 197.549076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:26.52732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.308654ms","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-20T10:13:26.52739Z","caller":"traceutil/trace.go:171","msg":"trace[154513429] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:790; }","duration":"179.443284ms","start":"2026-04-20T10:13:26.347918Z","end":"2026-04-20T10:13:26.527361Z","steps":["trace[154513429] 'agreement among raft nodes before linearized reading' (duration: 179.148352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:41.363622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.533964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c3c45df1-9b9c-468e-957a-c419deb7ed2f\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-20T10:14:41.363754Z","caller":"traceutil/trace.go:171","msg":"trace[1703132443] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c3c45df1-9b9c-468e-957a-c419deb7ed2f; range_end:; response_count:1; response_revision:1866; }","duration":"123.716689ms","start":"2026-04-20T10:14:41.240016Z","end":"2026-04-20T10:14:41.363733Z","steps":["trace[1703132443] 'range keys from in-memory index tree' (duration: 123.337168ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:41.767084Z","caller":"traceutil/trace.go:171","msg":"trace[191898090] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1933; }","duration":"248.680532ms","start":"2026-04-20T10:14:41.518341Z","end":"2026-04-20T10:14:41.767022Z","steps":["trace[191898090] 'read index received' (duration: 248.525257ms)","trace[191898090] 'applied index is now lower than readState.Index' (duration: 154.205µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:14:41.767116Z","caller":"traceutil/trace.go:171","msg":"trace[2039728801] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"282.820055ms","start":"2026-04-20T10:14:41.484263Z","end":"2026-04-20T10:14:41.767084Z","steps":["trace[2039728801] 'process raft request' (duration: 282.580429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:41.767304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.950719ms","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-20T10:14:41.767346Z","caller":"traceutil/trace.go:171","msg":"trace[1152375962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1867; }","duration":"249.045231ms","start":"2026-04-20T10:14:41.51829Z","end":"2026-04-20T10:14:41.767335Z","steps":["trace[1152375962] 'agreement among raft nodes before linearized reading' (duration: 248.884487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:41.787305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.367193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T10:14:41.787356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.186463ms","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-20T10:14:41.78738Z","caller":"traceutil/trace.go:171","msg":"trace[694443680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1868; }","duration":"265.500806ms","start":"2026-04-20T10:14:41.521864Z","end":"2026-04-20T10:14:41.787365Z","steps":["trace[694443680] 'agreement among raft nodes before linearized reading' (duration: 265.28179ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:41.787422Z","caller":"traceutil/trace.go:171","msg":"trace[1644260085] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1868; }","duration":"171.306097ms","start":"2026-04-20T10:14:41.616095Z","end":"2026-04-20T10:14:41.787401Z","steps":["trace[1644260085] 'agreement among raft nodes before linearized reading' (duration: 171.133242ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:41.787776Z","caller":"traceutil/trace.go:171","msg":"trace[1245492964] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"123.107432ms","start":"2026-04-20T10:14:41.664651Z","end":"2026-04-20T10:14:41.787759Z","steps":["trace[1245492964] 'process raft request' (duration: 122.333001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:41.787895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.120306ms","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-20T10:14:41.787941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.483626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:14:41.787976Z","caller":"traceutil/trace.go:171","msg":"trace[935079460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1868; }","duration":"265.234179ms","start":"2026-04-20T10:14:41.522726Z","end":"2026-04-20T10:14:41.78796Z","steps":["trace[935079460] 'agreement among raft nodes before linearized reading' (duration: 265.020913ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:41.788018Z","caller":"traceutil/trace.go:171","msg":"trace[929934567] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1868; }","duration":"190.63758ms","start":"2026-04-20T10:14:41.597359Z","end":"2026-04-20T10:14:41.787997Z","steps":["trace[929934567] 'agreement among raft nodes before linearized reading' (duration: 190.489036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:41.788075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.751499ms","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-20T10:14:41.788191Z","caller":"traceutil/trace.go:171","msg":"trace[415276560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1868; }","duration":"263.975015ms","start":"2026-04-20T10:14:41.524188Z","end":"2026-04-20T10:14:41.788163Z","steps":["trace[415276560] 'agreement among raft nodes before linearized reading' (duration: 263.511932ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:42.215219Z","caller":"traceutil/trace.go:171","msg":"trace[1930786245] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"118.563572ms","start":"2026-04-20T10:14:42.096627Z","end":"2026-04-20T10:14:42.215191Z","steps":["trace[1930786245] 'process raft request' (duration: 118.397018ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:09.626578Z","caller":"traceutil/trace.go:171","msg":"trace[1012088295] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"104.077216ms","start":"2026-04-20T10:15:09.522459Z","end":"2026-04-20T10:15:09.626536Z","steps":["trace[1012088295] 'process raft request' (duration: 103.781389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:16.272206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.897174ms","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-20T10:15:16.272453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.47915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:15:16.272502Z","caller":"traceutil/trace.go:171","msg":"trace[1306855510] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2106; }","duration":"170.571562ms","start":"2026-04-20T10:15:16.101918Z","end":"2026-04-20T10:15:16.272489Z","steps":["trace[1306855510] 'count revisions from in-memory index tree' (duration: 170.332747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:16.272614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.023828ms","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-20T10:15:16.272707Z","caller":"traceutil/trace.go:171","msg":"trace[1762561243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2106; }","duration":"246.169281ms","start":"2026-04-20T10:15:16.026514Z","end":"2026-04-20T10:15:16.272683Z","steps":["trace[1762561243] 'range keys from in-memory index tree' (duration: 245.819244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:16.272676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.696762ms","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-20T10:15:16.272773Z","caller":"traceutil/trace.go:171","msg":"trace[247462720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2106; }","duration":"289.840655ms","start":"2026-04-20T10:15:15.982907Z","end":"2026-04-20T10:15:16.272747Z","steps":["trace[247462720] 'range keys from in-memory index tree' (duration: 289.244933ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:16.273212Z","caller":"traceutil/trace.go:171","msg":"trace[1071121420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2106; }","duration":"245.034378ms","start":"2026-04-20T10:15:16.027248Z","end":"2026-04-20T10:15:16.272282Z","steps":["trace[1071121420] 'range keys from in-memory index tree' (duration: 244.613379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:16.273516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.123646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T10:15:16.273567Z","caller":"traceutil/trace.go:171","msg":"trace[23407803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2106; }","duration":"253.222178ms","start":"2026-04-20T10:15:16.020333Z","end":"2026-04-20T10:15:16.273555Z","steps":["trace[23407803] 'range keys from in-memory index tree' (duration: 252.461882ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:16.411943Z","caller":"traceutil/trace.go:171","msg":"trace[615636482] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"134.880476ms","start":"2026-04-20T10:15:16.277038Z","end":"2026-04-20T10:15:16.411918Z","steps":["trace[615636482] 'process raft request' (duration: 134.724883ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:16.609835Z","caller":"traceutil/trace.go:171","msg":"trace[1612593404] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"332.260339ms","start":"2026-04-20T10:15:16.277546Z","end":"2026-04-20T10:15:16.609806Z","steps":["trace[1612593404] 'process raft request' (duration: 332.150617ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:16.609925Z","caller":"traceutil/trace.go:171","msg":"trace[2147177016] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2182; }","duration":"141.438913ms","start":"2026-04-20T10:15:16.46847Z","end":"2026-04-20T10:15:16.609909Z","steps":["trace[2147177016] 'read index received' (duration: 58.362768ms)","trace[2147177016] 'applied index is now lower than readState.Index' (duration: 83.075105ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:15:16.609899Z","caller":"traceutil/trace.go:171","msg":"trace[799241523] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"332.697408ms","start":"2026-04-20T10:15:16.277147Z","end":"2026-04-20T10:15:16.609845Z","steps":["trace[799241523] 'process raft request' (duration: 249.595513ms)","trace[799241523] 'compare' (duration: 82.776138ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:15:16.610027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:16.277533Z","time spent":"332.402732ms","remote":"127.0.0.1:58422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:15:16.61007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.596658ms","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-20T10:15:16.6101Z","caller":"traceutil/trace.go:171","msg":"trace[1793666855] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2110; }","duration":"141.675409ms","start":"2026-04-20T10:15:16.468415Z","end":"2026-04-20T10:15:16.61009Z","steps":["trace[1793666855] 'agreement among raft nodes before linearized reading' (duration: 141.542146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:16.610129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:16.277123Z","time spent":"332.882041ms","remote":"127.0.0.1:58422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:15:16.610312Z","caller":"traceutil/trace.go:171","msg":"trace[1249273337] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"332.30279ms","start":"2026-04-20T10:15:16.277973Z","end":"2026-04-20T10:15:16.610276Z","steps":["trace[1249273337] 'process raft request' (duration: 331.784769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:16.610429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:16.277964Z","time spent":"332.417162ms","remote":"127.0.0.1:58422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:15:16.844893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.001919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:15:16.845021Z","caller":"traceutil/trace.go:171","msg":"trace[102341373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2111; }","duration":"134.203763ms","start":"2026-04-20T10:15:16.710789Z","end":"2026-04-20T10:15:16.844992Z","steps":["trace[102341373] 'range keys from in-memory index tree' (duration: 133.879056ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:11.757619Z","caller":"traceutil/trace.go:171","msg":"trace[473538206] transaction","detail":"{read_only:false; response_revision:2514; number_of_response:1; }","duration":"267.90588ms","start":"2026-04-20T10:16:11.489687Z","end":"2026-04-20T10:16:11.757593Z","steps":["trace[473538206] 'process raft request' (duration: 267.31737ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:16.786259Z","caller":"traceutil/trace.go:171","msg":"trace[146845511] transaction","detail":"{read_only:false; response_revision:2543; number_of_response:1; }","duration":"174.139502ms","start":"2026-04-20T10:16:16.612098Z","end":"2026-04-20T10:16:16.786238Z","steps":["trace[146845511] 'process raft request' (duration: 74.901999ms)","trace[146845511] 'compare' (duration: 99.08237ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:16.786508Z","caller":"traceutil/trace.go:171","msg":"trace[538260174] transaction","detail":"{read_only:false; response_revision:2544; number_of_response:1; }","duration":"173.734015ms","start":"2026-04-20T10:16:16.612764Z","end":"2026-04-20T10:16:16.786498Z","steps":["trace[538260174] 'process raft request' (duration: 173.43747ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:16.786629Z","caller":"traceutil/trace.go:171","msg":"trace[1624601776] transaction","detail":"{read_only:false; response_revision:2545; number_of_response:1; }","duration":"173.090045ms","start":"2026-04-20T10:16:16.613504Z","end":"2026-04-20T10:16:16.786594Z","steps":["trace[1624601776] 'process raft request' (duration: 172.902882ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:16.786659Z","caller":"traceutil/trace.go:171","msg":"trace[1599086857] transaction","detail":"{read_only:false; response_revision:2546; number_of_response:1; }","duration":"173.102495ms","start":"2026-04-20T10:16:16.613531Z","end":"2026-04-20T10:16:16.786633Z","steps":["trace[1599086857] 'process raft request' (duration: 172.975423ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:16.786986Z","caller":"traceutil/trace.go:171","msg":"trace[1064964352] transaction","detail":"{read_only:false; response_revision:2547; number_of_response:1; }","duration":"173.215927ms","start":"2026-04-20T10:16:16.61376Z","end":"2026-04-20T10:16:16.786976Z","steps":["trace[1064964352] 'process raft request' (duration: 172.795531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:17.036407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.880713ms","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-20T10:16:17.036484Z","caller":"traceutil/trace.go:171","msg":"trace[608897736] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2548; }","duration":"130.013355ms","start":"2026-04-20T10:16:16.906451Z","end":"2026-04-20T10:16:17.036465Z","steps":["trace[608897736] 'range keys from in-memory index tree' (duration: 129.7073ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:17.041267Z","caller":"traceutil/trace.go:171","msg":"trace[672963113] linearizableReadLoop","detail":"{readStateIndex:2638; appliedIndex:2637; }","duration":"132.820609ms","start":"2026-04-20T10:16:16.908431Z","end":"2026-04-20T10:16:17.041251Z","steps":["trace[672963113] 'read index received' (duration: 132.728988ms)","trace[672963113] 'applied index is now lower than readState.Index' (duration: 90.551µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:17.041436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.996152ms","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-20T10:16:17.041471Z","caller":"traceutil/trace.go:171","msg":"trace[993608840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2549; }","duration":"133.082803ms","start":"2026-04-20T10:16:16.908379Z","end":"2026-04-20T10:16:17.041461Z","steps":["trace[993608840] 'agreement among raft nodes before linearized reading' (duration: 132.956351ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:17.041548Z","caller":"traceutil/trace.go:171","msg":"trace[1651453476] transaction","detail":"{read_only:false; response_revision:2549; number_of_response:1; }","duration":"133.036732ms","start":"2026-04-20T10:16:16.908308Z","end":"2026-04-20T10:16:17.041345Z","steps":["trace[1651453476] 'process raft request' (duration: 132.802758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:17.046524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.850618ms","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-20T10:16:17.046581Z","caller":"traceutil/trace.go:171","msg":"trace[1964173602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2550; }","duration":"137.935409ms","start":"2026-04-20T10:16:16.908633Z","end":"2026-04-20T10:16:17.046569Z","steps":["trace[1964173602] 'agreement among raft nodes before linearized reading' (duration: 137.804117ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:20.26521Z","caller":"traceutil/trace.go:171","msg":"trace[1950857011] transaction","detail":"{read_only:false; response_revision:2567; number_of_response:1; }","duration":"101.949754ms","start":"2026-04-20T10:16:20.163238Z","end":"2026-04-20T10:16:20.265188Z","steps":["trace[1950857011] 'process raft request' (duration: 101.824622ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:23.457284Z","caller":"traceutil/trace.go:171","msg":"trace[798731170] linearizableReadLoop","detail":"{readStateIndex:2673; appliedIndex:2671; }","duration":"124.283135ms","start":"2026-04-20T10:16:23.332981Z","end":"2026-04-20T10:16:23.457264Z","steps":["trace[798731170] 'read index received' (duration: 102.870125ms)","trace[798731170] 'applied index is now lower than readState.Index' (duration: 21.412249ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:23.457388Z","caller":"traceutil/trace.go:171","msg":"trace[1089142191] transaction","detail":"{read_only:false; response_revision:2583; number_of_response:1; }","duration":"133.359554ms","start":"2026-04-20T10:16:23.32402Z","end":"2026-04-20T10:16:23.457379Z","steps":["trace[1089142191] 'process raft request' (duration: 133.157521ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:23.45738Z","caller":"traceutil/trace.go:171","msg":"trace[1333422009] transaction","detail":"{read_only:false; response_revision:2582; number_of_response:1; }","duration":"133.841132ms","start":"2026-04-20T10:16:23.323493Z","end":"2026-04-20T10:16:23.457335Z","steps":["trace[1333422009] 'process raft request' (duration: 112.424082ms)","trace[1333422009] 'compare' (duration: 21.073044ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:23.457776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.775912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:8695"} {"level":"info","ts":"2026-04-20T10:16:23.45782Z","caller":"traceutil/trace.go:171","msg":"trace[253044007] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2583; }","duration":"124.846123ms","start":"2026-04-20T10:16:23.332963Z","end":"2026-04-20T10:16:23.457809Z","steps":["trace[253044007] 'agreement among raft nodes before linearized reading' (duration: 124.67506ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:24.53204Z","caller":"traceutil/trace.go:171","msg":"trace[1347689028] linearizableReadLoop","detail":"{readStateIndex:2679; appliedIndex:2677; }","duration":"176.777205ms","start":"2026-04-20T10:16:24.355226Z","end":"2026-04-20T10:16:24.532003Z","steps":["trace[1347689028] 'read index received' (duration: 4.927846ms)","trace[1347689028] 'applied index is now lower than readState.Index' (duration: 171.847719ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:24.532201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.955197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:24.532243Z","caller":"traceutil/trace.go:171","msg":"trace[1105445603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2588; }","duration":"177.054899ms","start":"2026-04-20T10:16:24.355177Z","end":"2026-04-20T10:16:24.532232Z","steps":["trace[1105445603] 'agreement among raft nodes before linearized reading' (duration: 176.956497ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:24.532355Z","caller":"traceutil/trace.go:171","msg":"trace[323801118] transaction","detail":"{read_only:false; response_revision:2588; number_of_response:1; }","duration":"187.611601ms","start":"2026-04-20T10:16:24.34473Z","end":"2026-04-20T10:16:24.532341Z","steps":["trace[323801118] 'process raft request' (duration: 168.942415ms)","trace[323801118] 'compare' (duration: 18.158558ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:25.631818Z","caller":"traceutil/trace.go:171","msg":"trace[1718872354] transaction","detail":"{read_only:false; response_revision:2594; number_of_response:1; }","duration":"142.728656ms","start":"2026-04-20T10:16:25.489046Z","end":"2026-04-20T10:16:25.631775Z","steps":["trace[1718872354] 'process raft request' (duration: 110.738316ms)","trace[1718872354] 'compare' (duration: 31.806707ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:25.6321Z","caller":"traceutil/trace.go:171","msg":"trace[1410417530] transaction","detail":"{read_only:false; response_revision:2595; number_of_response:1; }","duration":"134.285106ms","start":"2026-04-20T10:16:25.497708Z","end":"2026-04-20T10:16:25.631993Z","steps":["trace[1410417530] 'process raft request' (duration: 134.002902ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:27.835324Z","caller":"traceutil/trace.go:171","msg":"trace[786027240] linearizableReadLoop","detail":"{readStateIndex:2696; appliedIndex:2695; }","duration":"125.790875ms","start":"2026-04-20T10:16:27.7095Z","end":"2026-04-20T10:16:27.835291Z","steps":["trace[786027240] 'read index received' (duration: 99.513345ms)","trace[786027240] 'applied index is now lower than readState.Index' (duration: 26.27611ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:27.83539Z","caller":"traceutil/trace.go:171","msg":"trace[188949844] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"195.621418ms","start":"2026-04-20T10:16:27.639739Z","end":"2026-04-20T10:16:27.835361Z","steps":["trace[188949844] 'process raft request' (duration: 169.43664ms)","trace[188949844] 'compare' (duration: 25.856113ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:27.835478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.958197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:27.835719Z","caller":"traceutil/trace.go:171","msg":"trace[903914825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2605; }","duration":"126.040378ms","start":"2026-04-20T10:16:27.709461Z","end":"2026-04-20T10:16:27.835502Z","steps":["trace[903914825] 'agreement among raft nodes before linearized reading' (duration: 125.939627ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:28.090661Z","caller":"traceutil/trace.go:171","msg":"trace[1106135537] linearizableReadLoop","detail":"{readStateIndex:2697; appliedIndex:2696; }","duration":"133.837034ms","start":"2026-04-20T10:16:27.956795Z","end":"2026-04-20T10:16:28.090632Z","steps":["trace[1106135537] 'read index received' (duration: 36.608867ms)","trace[1106135537] 'applied index is now lower than readState.Index' (duration: 97.227007ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:28.090736Z","caller":"traceutil/trace.go:171","msg":"trace[1983244438] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"136.86238ms","start":"2026-04-20T10:16:27.95384Z","end":"2026-04-20T10:16:28.090703Z","steps":["trace[1983244438] 'process raft request' (duration: 39.646704ms)","trace[1983244438] 'compare' (duration: 97.022343ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:28.090997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.185789ms","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-20T10:16:28.09109Z","caller":"traceutil/trace.go:171","msg":"trace[1770801213] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2606; }","duration":"134.334671ms","start":"2026-04-20T10:16:27.95674Z","end":"2026-04-20T10:16:28.091075Z","steps":["trace[1770801213] 'agreement among raft nodes before linearized reading' (duration: 134.078977ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:28.591609Z","caller":"traceutil/trace.go:171","msg":"trace[98717598] transaction","detail":"{read_only:false; response_revision:2608; number_of_response:1; }","duration":"196.633014ms","start":"2026-04-20T10:16:28.394943Z","end":"2026-04-20T10:16:28.591576Z","steps":["trace[98717598] 'process raft request' (duration: 196.421591ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:28.810789Z","caller":"traceutil/trace.go:171","msg":"trace[514683148] linearizableReadLoop","detail":"{readStateIndex:2700; appliedIndex:2699; }","duration":"215.123005ms","start":"2026-04-20T10:16:28.595639Z","end":"2026-04-20T10:16:28.810762Z","steps":["trace[514683148] 'read index received' (duration: 156.717159ms)","trace[514683148] 'applied index is now lower than readState.Index' (duration: 58.404626ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:28.810848Z","caller":"traceutil/trace.go:171","msg":"trace[1133239924] transaction","detail":"{read_only:false; response_revision:2609; number_of_response:1; }","duration":"261.262657ms","start":"2026-04-20T10:16:28.549544Z","end":"2026-04-20T10:16:28.810807Z","steps":["trace[1133239924] 'process raft request' (duration: 202.83823ms)","trace[1133239924] 'compare' (duration: 58.256134ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:28.811089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.424201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-04-20T10:16:28.811165Z","caller":"traceutil/trace.go:171","msg":"trace[492070717] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2609; }","duration":"215.554772ms","start":"2026-04-20T10:16:28.595592Z","end":"2026-04-20T10:16:28.811147Z","steps":["trace[492070717] 'agreement among raft nodes before linearized reading' (duration: 215.279408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:28.814044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.398179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:28.814107Z","caller":"traceutil/trace.go:171","msg":"trace[273584541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2609; }","duration":"103.534522ms","start":"2026-04-20T10:16:28.710557Z","end":"2026-04-20T10:16:28.814092Z","steps":["trace[273584541] 'agreement among raft nodes before linearized reading' (duration: 103.40488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:28.814483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.035327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:28.814529Z","caller":"traceutil/trace.go:171","msg":"trace[194141716] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2609; }","duration":"175.084438ms","start":"2026-04-20T10:16:28.63943Z","end":"2026-04-20T10:16:28.814515Z","steps":["trace[194141716] 'agreement among raft nodes before linearized reading' (duration: 175.014027ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:29.841991Z","caller":"traceutil/trace.go:171","msg":"trace[1759320074] transaction","detail":"{read_only:false; response_revision:2615; number_of_response:1; }","duration":"120.946271ms","start":"2026-04-20T10:16:29.720986Z","end":"2026-04-20T10:16:29.841932Z","steps":["trace[1759320074] 'process raft request' (duration: 72.128592ms)","trace[1759320074] 'compare' (duration: 48.505704ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:33.388579Z","caller":"traceutil/trace.go:171","msg":"trace[1053939785] transaction","detail":"{read_only:false; response_revision:2632; number_of_response:1; }","duration":"103.932636ms","start":"2026-04-20T10:16:33.284609Z","end":"2026-04-20T10:16:33.388542Z","steps":["trace[1053939785] 'process raft request' (duration: 103.816064ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:33.411385Z","caller":"traceutil/trace.go:171","msg":"trace[1469067789] transaction","detail":"{read_only:false; response_revision:2633; number_of_response:1; }","duration":"124.362903ms","start":"2026-04-20T10:16:33.287002Z","end":"2026-04-20T10:16:33.411365Z","steps":["trace[1469067789] 'process raft request' (duration: 123.591111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:34.107701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.889451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:34.107797Z","caller":"traceutil/trace.go:171","msg":"trace[364254659] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2637; }","duration":"150.031903ms","start":"2026-04-20T10:16:33.957744Z","end":"2026-04-20T10:16:34.107776Z","steps":["trace[364254659] 'range keys from in-memory index tree' (duration: 149.81697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:34.107841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.662687ms","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-20T10:16:34.108214Z","caller":"traceutil/trace.go:171","msg":"trace[1578815129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2637; }","duration":"151.881571ms","start":"2026-04-20T10:16:33.956076Z","end":"2026-04-20T10:16:34.107958Z","steps":["trace[1578815129] 'range keys from in-memory index tree' (duration: 151.386423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:44.575383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.990676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:44.575484Z","caller":"traceutil/trace.go:171","msg":"trace[1951305123] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2691; }","duration":"235.143748ms","start":"2026-04-20T10:16:44.340317Z","end":"2026-04-20T10:16:44.57546Z","steps":["trace[1951305123] 'range keys from in-memory index tree' (duration: 234.915965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:44.57555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.653796ms","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-20T10:16:44.575819Z","caller":"traceutil/trace.go:171","msg":"trace[10611916] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2691; }","duration":"198.809348ms","start":"2026-04-20T10:16:44.376814Z","end":"2026-04-20T10:16:44.575624Z","steps":["trace[10611916] 'range keys from in-memory index tree' (duration: 198.456803ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:49.749795Z","caller":"traceutil/trace.go:171","msg":"trace[1631343813] linearizableReadLoop","detail":"{readStateIndex:2816; appliedIndex:2815; }","duration":"126.848888ms","start":"2026-04-20T10:16:49.622922Z","end":"2026-04-20T10:16:49.749771Z","steps":["trace[1631343813] 'read index received' (duration: 126.625055ms)","trace[1631343813] 'applied index is now lower than readState.Index' (duration: 222.873µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:49.749962Z","caller":"traceutil/trace.go:171","msg":"trace[1322425476] transaction","detail":"{read_only:false; response_revision:2719; number_of_response:1; }","duration":"131.421084ms","start":"2026-04-20T10:16:49.618418Z","end":"2026-04-20T10:16:49.749839Z","steps":["trace[1322425476] 'process raft request' (duration: 131.186121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:49.750182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.239594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-04-20T10:16:49.750243Z","caller":"traceutil/trace.go:171","msg":"trace[722092668] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2719; }","duration":"127.339036ms","start":"2026-04-20T10:16:49.622889Z","end":"2026-04-20T10:16:49.750228Z","steps":["trace[722092668] 'agreement among raft nodes before linearized reading' (duration: 127.000751ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:17:33.269632Z","caller":"traceutil/trace.go:171","msg":"trace[681516750] transaction","detail":"{read_only:false; response_revision:2948; number_of_response:1; }","duration":"126.213858ms","start":"2026-04-20T10:17:33.143392Z","end":"2026-04-20T10:17:33.269606Z","steps":["trace[681516750] 'process raft request' (duration: 126.058235ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:48.637129Z","caller":"traceutil/trace.go:171","msg":"trace[1266705487] linearizableReadLoop","detail":"{readStateIndex:3926; appliedIndex:3925; }","duration":"111.063963ms","start":"2026-04-20T10:19:48.526031Z","end":"2026-04-20T10:19:48.637095Z","steps":["trace[1266705487] 'read index received' (duration: 72.5334ms)","trace[1266705487] 'applied index is now lower than readState.Index' (duration: 38.528903ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:48.637192Z","caller":"traceutil/trace.go:171","msg":"trace[1960502182] transaction","detail":"{read_only:false; response_revision:3786; number_of_response:1; }","duration":"124.064223ms","start":"2026-04-20T10:19:48.513092Z","end":"2026-04-20T10:19:48.637156Z","steps":["trace[1960502182] 'process raft request' (duration: 85.575522ms)","trace[1960502182] 'compare' (duration: 37.929227ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:19:48.637569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.224746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:19:48.637596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.505854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:19:48.637622Z","caller":"traceutil/trace.go:171","msg":"trace[1677240015] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com; range_end:; response_count:0; response_revision:3786; }","duration":"111.302439ms","start":"2026-04-20T10:19:48.526306Z","end":"2026-04-20T10:19:48.637608Z","steps":["trace[1677240015] 'agreement among raft nodes before linearized reading' (duration: 111.213097ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:48.637639Z","caller":"traceutil/trace.go:171","msg":"trace[1769918227] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:3786; }","duration":"111.643027ms","start":"2026-04-20T10:19:48.525984Z","end":"2026-04-20T10:19:48.637627Z","steps":["trace[1769918227] 'agreement among raft nodes before linearized reading' (duration: 111.446282ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:48.843038Z","caller":"traceutil/trace.go:171","msg":"trace[1116245298] transaction","detail":"{read_only:false; response_revision:3791; number_of_response:1; }","duration":"112.981821ms","start":"2026-04-20T10:19:48.730031Z","end":"2026-04-20T10:19:48.843013Z","steps":["trace[1116245298] 'process raft request' (duration: 80.381749ms)","trace[1116245298] 'compare' (duration: 31.497714ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:20:47.577565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.702551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:20:47.577693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.391989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T10:20:47.577795Z","caller":"traceutil/trace.go:171","msg":"trace[1859067094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4680; }","duration":"125.530932ms","start":"2026-04-20T10:20:47.452237Z","end":"2026-04-20T10:20:47.577768Z","steps":["trace[1859067094] 'range keys from in-memory index tree' (duration: 125.152263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:47.577691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.919298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-20T10:20:47.577887Z","caller":"traceutil/trace.go:171","msg":"trace[753067831] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4680; }","duration":"125.156763ms","start":"2026-04-20T10:20:47.452717Z","end":"2026-04-20T10:20:47.577874Z","steps":["trace[753067831] 'range keys from in-memory index tree' (duration: 124.722634ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:47.577708Z","caller":"traceutil/trace.go:171","msg":"trace[1108852952] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:4680; }","duration":"128.918216ms","start":"2026-04-20T10:20:47.448752Z","end":"2026-04-20T10:20:47.57767Z","steps":["trace[1108852952] 'count revisions from in-memory index tree' (duration: 128.615219ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:47.754126Z","caller":"traceutil/trace.go:171","msg":"trace[82935962] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"101.717942ms","start":"2026-04-20T10:20:47.652376Z","end":"2026-04-20T10:20:47.754094Z","steps":["trace[82935962] 'process raft request' (duration: 31.287063ms)","trace[82935962] 'compare' (duration: 70.212494ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:20:48.062917Z","caller":"traceutil/trace.go:171","msg":"trace[166986462] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"110.017399ms","start":"2026-04-20T10:20:47.952876Z","end":"2026-04-20T10:20:48.062893Z","steps":["trace[166986462] 'process raft request' (duration: 109.868976ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:48.095841Z","caller":"traceutil/trace.go:171","msg":"trace[1511853507] linearizableReadLoop","detail":"{readStateIndex:4860; appliedIndex:4859; }","duration":"105.219733ms","start":"2026-04-20T10:20:47.990595Z","end":"2026-04-20T10:20:48.095814Z","steps":["trace[1511853507] 'read index received' (duration: 72.349596ms)","trace[1511853507] 'applied index is now lower than readState.Index' (duration: 32.869166ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:20:48.09588Z","caller":"traceutil/trace.go:171","msg":"trace[97729197] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"136.982666ms","start":"2026-04-20T10:20:47.958874Z","end":"2026-04-20T10:20:48.095856Z","steps":["trace[97729197] 'process raft request' (duration: 136.780942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:48.096083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.469419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-20T10:20:48.096129Z","caller":"traceutil/trace.go:171","msg":"trace[1185552236] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4686; }","duration":"105.587331ms","start":"2026-04-20T10:20:47.990528Z","end":"2026-04-20T10:20:48.096115Z","steps":["trace[1185552236] 'agreement among raft nodes before linearized reading' (duration: 105.432108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:48.119733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.12361ms","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-20T10:20:48.119796Z","caller":"traceutil/trace.go:171","msg":"trace[1556459301] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4687; }","duration":"127.266794ms","start":"2026-04-20T10:20:47.992516Z","end":"2026-04-20T10:20:48.119783Z","steps":["trace[1556459301] 'agreement among raft nodes before linearized reading' (duration: 127.08099ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:48.353024Z","caller":"traceutil/trace.go:171","msg":"trace[13724130] transaction","detail":"{read_only:false; response_revision:4696; number_of_response:1; }","duration":"116.54677ms","start":"2026-04-20T10:20:48.236458Z","end":"2026-04-20T10:20:48.353005Z","steps":["trace[13724130] 'process raft request' (duration: 116.452297ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:48.352999Z","caller":"traceutil/trace.go:171","msg":"trace[787773024] transaction","detail":"{read_only:false; response_revision:4695; number_of_response:1; }","duration":"117.391408ms","start":"2026-04-20T10:20:48.235576Z","end":"2026-04-20T10:20:48.352967Z","steps":["trace[787773024] 'process raft request' (duration: 95.783017ms)","trace[787773024] 'compare' (duration: 21.410306ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:20:48.353469Z","caller":"traceutil/trace.go:171","msg":"trace[594227890] transaction","detail":"{read_only:false; response_revision:4697; number_of_response:1; }","duration":"116.814275ms","start":"2026-04-20T10:20:48.236633Z","end":"2026-04-20T10:20:48.353447Z","steps":["trace[594227890] 'process raft request' (duration: 116.324934ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:48.353552Z","caller":"traceutil/trace.go:171","msg":"trace[739258906] linearizableReadLoop","detail":"{readStateIndex:4871; appliedIndex:4868; }","duration":"103.376582ms","start":"2026-04-20T10:20:48.250131Z","end":"2026-04-20T10:20:48.353508Z","steps":["trace[739258906] 'read index received' (duration: 81.24401ms)","trace[739258906] 'applied index is now lower than readState.Index' (duration: 22.131282ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:20:48.353734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.586447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:20:48.353798Z","caller":"traceutil/trace.go:171","msg":"trace[238779949] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:4698; }","duration":"103.692439ms","start":"2026-04-20T10:20:48.25008Z","end":"2026-04-20T10:20:48.353772Z","steps":["trace[238779949] 'agreement among raft nodes before linearized reading' (duration: 103.592527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:51.167126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.609851ms","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-20T10:20:51.167212Z","caller":"traceutil/trace.go:171","msg":"trace[75361500] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4723; }","duration":"102.746024ms","start":"2026-04-20T10:20:51.064447Z","end":"2026-04-20T10:20:51.167193Z","steps":["trace[75361500] 'range keys from in-memory index tree' (duration: 102.380866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:13.737293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.421823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.87\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-20T10:21:13.737377Z","caller":"traceutil/trace.go:171","msg":"trace[1699181632] range","detail":"{range_begin:/registry/masterleases/199.19.213.87; range_end:; response_count:1; response_revision:4969; }","duration":"122.554685ms","start":"2026-04-20T10:21:13.614804Z","end":"2026-04-20T10:21:13.737359Z","steps":["trace[1699181632] 'range keys from in-memory index tree' (duration: 122.246829ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:13.847525Z","caller":"traceutil/trace.go:171","msg":"trace[910892641] linearizableReadLoop","detail":"{readStateIndex:5151; appliedIndex:5151; }","duration":"108.832232ms","start":"2026-04-20T10:21:13.738664Z","end":"2026-04-20T10:21:13.847496Z","steps":["trace[910892641] 'read index received' (duration: 108.821682ms)","trace[910892641] 'applied index is now lower than readState.Index' (duration: 8.58µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:21:13.8477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.021886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:21:13.847786Z","caller":"traceutil/trace.go:171","msg":"trace[796932076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4969; }","duration":"109.133538ms","start":"2026-04-20T10:21:13.738631Z","end":"2026-04-20T10:21:13.847764Z","steps":["trace[796932076] 'agreement among raft nodes before linearized reading' (duration: 108.974765ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:14.119796Z","caller":"traceutil/trace.go:171","msg":"trace[169422685] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"378.257153ms","start":"2026-04-20T10:21:13.741513Z","end":"2026-04-20T10:21:14.11977Z","steps":["trace[169422685] 'process raft request' (duration: 378.030368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:14.119994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:21:13.741483Z","time spent":"378.456976ms","remote":"127.0.0.1:58422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:21:14.120175Z","caller":"traceutil/trace.go:171","msg":"trace[615406383] linearizableReadLoop","detail":"{readStateIndex:5153; appliedIndex:5151; }","duration":"272.548966ms","start":"2026-04-20T10:21:13.847597Z","end":"2026-04-20T10:21:14.120146Z","steps":["trace[615406383] 'read index received' (duration: 271.951183ms)","trace[615406383] 'applied index is now lower than readState.Index' (duration: 596.102µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:21:14.120407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.645331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-db1aaffc-89d5-4106-a8cf-c346f1062b98\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-20T10:21:14.120486Z","caller":"traceutil/trace.go:171","msg":"trace[1821418959] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-db1aaffc-89d5-4106-a8cf-c346f1062b98; range_end:; response_count:1; response_revision:4971; }","duration":"308.771494ms","start":"2026-04-20T10:21:13.811703Z","end":"2026-04-20T10:21:14.120474Z","steps":["trace[1821418959] 'agreement among raft nodes before linearized reading' (duration: 308.58322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:14.120549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:21:13.811683Z","time spent":"308.858007ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-db1aaffc-89d5-4106-a8cf-c346f1062b98\" "} {"level":"info","ts":"2026-04-20T10:21:14.120756Z","caller":"traceutil/trace.go:171","msg":"trace[409715042] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"274.71011ms","start":"2026-04-20T10:21:13.846035Z","end":"2026-04-20T10:21:14.120745Z","steps":["trace[409715042] 'process raft request' (duration: 273.682589ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:14.121591Z","caller":"traceutil/trace.go:171","msg":"trace[403819814] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"272.693278ms","start":"2026-04-20T10:21:13.848885Z","end":"2026-04-20T10:21:14.121578Z","steps":["trace[403819814] 'process raft request' (duration: 272.595386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:25.494406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.656824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:21:25.494512Z","caller":"traceutil/trace.go:171","msg":"trace[1435808509] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"252.02551ms","start":"2026-04-20T10:21:25.242467Z","end":"2026-04-20T10:21:25.494493Z","steps":["trace[1435808509] 'process raft request' (duration: 80.209973ms)","trace[1435808509] 'compare' (duration: 171.211765ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:21:25.513555Z","caller":"traceutil/trace.go:171","msg":"trace[1578748447] linearizableReadLoop","detail":"{readStateIndex:5243; appliedIndex:5239; }","duration":"234.569898ms","start":"2026-04-20T10:21:25.27896Z","end":"2026-04-20T10:21:25.51353Z","steps":["trace[1578748447] 'read index received' (duration: 43.730715ms)","trace[1578748447] 'applied index is now lower than readState.Index' (duration: 190.838053ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:21:25.513675Z","caller":"traceutil/trace.go:171","msg":"trace[559453024] transaction","detail":"{read_only:false; response_revision:5059; number_of_response:1; }","duration":"266.86038ms","start":"2026-04-20T10:21:25.246803Z","end":"2026-04-20T10:21:25.513664Z","steps":["trace[559453024] 'process raft request' (duration: 266.686087ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:25.513864Z","caller":"traceutil/trace.go:171","msg":"trace[268713586] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"270.988454ms","start":"2026-04-20T10:21:25.242836Z","end":"2026-04-20T10:21:25.513825Z","steps":["trace[268713586] 'process raft request' (duration: 270.453093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:25.514044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.076427ms","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":"warn","ts":"2026-04-20T10:21:25.514064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.697699ms","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-20T10:21:25.514154Z","caller":"traceutil/trace.go:171","msg":"trace[1956487885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5059; }","duration":"193.845663ms","start":"2026-04-20T10:21:25.320292Z","end":"2026-04-20T10:21:25.514138Z","steps":["trace[1956487885] 'agreement among raft nodes before linearized reading' (duration: 193.615978ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:25.514163Z","caller":"traceutil/trace.go:171","msg":"trace[798008227] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5059; }","duration":"235.220931ms","start":"2026-04-20T10:21:25.278903Z","end":"2026-04-20T10:21:25.514124Z","steps":["trace[798008227] 'agreement among raft nodes before linearized reading' (duration: 234.988606ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:25.514358Z","caller":"traceutil/trace.go:171","msg":"trace[667026222] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"268.058095ms","start":"2026-04-20T10:21:25.246273Z","end":"2026-04-20T10:21:25.514331Z","steps":["trace[667026222] 'process raft request' (duration: 267.153887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:25.514486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.238681ms","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-20T10:21:25.514536Z","caller":"traceutil/trace.go:171","msg":"trace[251887858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5059; }","duration":"194.501136ms","start":"2026-04-20T10:21:25.320025Z","end":"2026-04-20T10:21:25.514526Z","steps":["trace[251887858] 'agreement among raft nodes before linearized reading' (duration: 193.72936ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:25.680975Z","caller":"traceutil/trace.go:171","msg":"trace[1267634808] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"160.658321ms","start":"2026-04-20T10:21:25.520004Z","end":"2026-04-20T10:21:25.680662Z","steps":["trace[1267634808] 'process raft request' (duration: 97.740688ms)","trace[1267634808] 'compare' (duration: 62.77988ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:22:30.503869Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2934} {"level":"info","ts":"2026-04-20T10:22:30.591857Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2934,"took":"86.504981ms","hash":2933224098,"current-db-size-bytes":52686848,"current-db-size":"53 MB","current-db-size-in-use-bytes":28938240,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-20T10:22:30.591926Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2933224098,"revision":2934,"compact-revision":-1} {"level":"info","ts":"2026-04-20T10:27:30.509618Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5578} {"level":"info","ts":"2026-04-20T10:27:30.584584Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5578,"took":"73.482557ms","hash":285370722,"current-db-size-bytes":52686848,"current-db-size":"53 MB","current-db-size-in-use-bytes":16793600,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-04-20T10:27:30.584729Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":285370722,"revision":5578,"compact-revision":2934}