{"level":"warn","ts":"2026-04-13T21:05:29.88494Z","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-13T21:05:29.885026Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.192: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.192:2380","--initial-cluster=instance=https://199.19.213.192:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.192:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.192: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-13T21:05:29.885087Z","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-13T21:05:29.8851Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.192:2380"]} {"level":"info","ts":"2026-04-13T21:05:29.88512Z","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-13T21:05:29.885727Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"]} {"level":"info","ts":"2026-04-13T21:05:29.885802Z","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.192:2380"],"listen-peer-urls":["https://199.19.213.192:2380"],"advertise-client-urls":["https://199.19.213.192:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.192: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-13T21:05:29.889716Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.69991ms"} {"level":"info","ts":"2026-04-13T21:05:29.896124Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"341a3703c39c1f5b","cluster-id":"65341987fa712ba2"} {"level":"info","ts":"2026-04-13T21:05:29.896231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T21:05:29.896255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became follower at term 0"} {"level":"info","ts":"2026-04-13T21:05:29.896265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 341a3703c39c1f5b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T21:05:29.896278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became follower at term 1"} {"level":"info","ts":"2026-04-13T21:05:29.896324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=(3754373728672948059)"} {"level":"warn","ts":"2026-04-13T21:05:29.900862Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T21:05:29.902734Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T21:05:29.904242Z","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-13T21:05:29.906197Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"341a3703c39c1f5b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T21:05:29.909222Z","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-13T21:05:29.909505Z","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-13T21:05:29.909639Z","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-13T21:05:29.909765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=(3754373728672948059)"} {"level":"info","ts":"2026-04-13T21:05:29.909991Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65341987fa712ba2","local-member-id":"341a3703c39c1f5b","added-peer-id":"341a3703c39c1f5b","added-peer-peer-urls":["https://199.19.213.192:2380"]} {"level":"info","ts":"2026-04-13T21:05:29.90905Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"341a3703c39c1f5b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T21:05:29.910905Z","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-13T21:05:29.911263Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"341a3703c39c1f5b","initial-advertise-peer-urls":["https://199.19.213.192:2380"],"listen-peer-urls":["https://199.19.213.192:2380"],"advertise-client-urls":["https://199.19.213.192:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T21:05:29.911302Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T21:05:29.911393Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.192:2380"} {"level":"info","ts":"2026-04-13T21:05:29.911674Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.192:2380"} {"level":"info","ts":"2026-04-13T21:05:30.497156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T21:05:30.497224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T21:05:30.497247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b received MsgPreVoteResp from 341a3703c39c1f5b at term 1"} {"level":"info","ts":"2026-04-13T21:05:30.497266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became candidate at term 2"} {"level":"info","ts":"2026-04-13T21:05:30.497282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b received MsgVoteResp from 341a3703c39c1f5b at term 2"} {"level":"info","ts":"2026-04-13T21:05:30.497303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became leader at term 2"} {"level":"info","ts":"2026-04-13T21:05:30.497333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 341a3703c39c1f5b elected leader 341a3703c39c1f5b at term 2"} {"level":"info","ts":"2026-04-13T21:05:30.49827Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:05:30.499205Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T21:05:30.499201Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"341a3703c39c1f5b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.192:2379]}","request-path":"/0/members/341a3703c39c1f5b/attributes","cluster-id":"65341987fa712ba2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T21:05:30.499251Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T21:05:30.500397Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T21:05:30.50044Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T21:05:30.501461Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T21:05:30.503132Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65341987fa712ba2","local-member-id":"341a3703c39c1f5b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:05:30.503215Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:05:30.503239Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:05:30.51414Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.192:2379"} {"level":"warn","ts":"2026-04-13T21:05:34.915855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010747652s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:05:34.915946Z","caller":"traceutil/trace.go:171","msg":"trace[906518175] linearizableReadLoop","detail":"{readStateIndex:178; appliedIndex:177; }","duration":"1.03783492s","start":"2026-04-13T21:05:33.878098Z","end":"2026-04-13T21:05:34.915932Z","steps":["trace[906518175] 'read index received' (duration: 26.586631ms)","trace[906518175] 'applied index is now lower than readState.Index' (duration: 1.011247409s)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:05:34.916147Z","caller":"traceutil/trace.go:171","msg":"trace[100032562] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"1.379031427s","start":"2026-04-13T21:05:33.537107Z","end":"2026-04-13T21:05:34.916139Z","steps":["trace[100032562] 'process raft request' (duration: 367.625516ms)","trace[100032562] 'compare' (duration: 1.010640021s)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:05:34.916188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:05:33.537091Z","time spent":"1.379079378s","remote":"127.0.0.1:34890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":748,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:05:34.916385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038291086s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:05:34.916416Z","caller":"traceutil/trace.go:171","msg":"trace[600094676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:174; }","duration":"1.038357296s","start":"2026-04-13T21:05:33.878049Z","end":"2026-04-13T21:05:34.916406Z","steps":["trace[600094676] 'agreement among raft nodes before linearized reading' (duration: 1.038305955s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:05:34.916437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:05:33.878027Z","time spent":"1.038405218s","remote":"127.0.0.1:34584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:05:34.916361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.98661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:05:34.916576Z","caller":"traceutil/trace.go:171","msg":"trace[1874461804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:174; }","duration":"488.253443ms","start":"2026-04-13T21:05:34.428289Z","end":"2026-04-13T21:05:34.916543Z","steps":["trace[1874461804] 'agreement among raft nodes before linearized reading' (duration: 487.841948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:05:34.916695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:05:34.428266Z","time spent":"488.410695ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T21:05:35.662706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.818832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:05:35.662835Z","caller":"traceutil/trace.go:171","msg":"trace[542889371] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"742.248403ms","start":"2026-04-13T21:05:34.920561Z","end":"2026-04-13T21:05:35.662809Z","steps":["trace[542889371] 'process raft request' (duration: 385.255859ms)","trace[542889371] 'compare' (duration: 356.69814ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:05:35.663172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:05:34.92053Z","time spent":"742.513287ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:05:35.672137Z","caller":"traceutil/trace.go:171","msg":"trace[153638002] linearizableReadLoop","detail":"{readStateIndex:180; appliedIndex:178; }","duration":"693.606675ms","start":"2026-04-13T21:05:34.978513Z","end":"2026-04-13T21:05:35.67212Z","steps":["trace[153638002] 'read index received' (duration: 327.314254ms)","trace[153638002] 'applied index is now lower than readState.Index' (duration: 366.291491ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:05:35.672204Z","caller":"traceutil/trace.go:171","msg":"trace[932979661] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"750.644079ms","start":"2026-04-13T21:05:34.921543Z","end":"2026-04-13T21:05:35.672187Z","steps":["trace[932979661] 'process raft request' (duration: 750.266115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:05:35.672309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:05:34.921521Z","time spent":"750.73032ms","remote":"127.0.0.1:34890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":760,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:05:35.672532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.020151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:05:35.672583Z","caller":"traceutil/trace.go:171","msg":"trace[78971924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:177; }","duration":"694.119562ms","start":"2026-04-13T21:05:34.978451Z","end":"2026-04-13T21:05:35.672571Z","steps":["trace[78971924] 'agreement among raft nodes before linearized reading' (duration: 694.041951ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:05:35.672593Z","caller":"traceutil/trace.go:171","msg":"trace[192853472] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"590.766094ms","start":"2026-04-13T21:05:35.08182Z","end":"2026-04-13T21:05:35.672586Z","steps":["trace[192853472] 'process raft request' (duration: 590.244218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:05:35.672614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:05:34.978428Z","time spent":"694.178983ms","remote":"127.0.0.1:34584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:05:35.672642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:05:35.081791Z","time spent":"590.818666ms","remote":"127.0.0.1:34736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:06:02.409285Z","caller":"traceutil/trace.go:171","msg":"trace[429350538] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"285.831875ms","start":"2026-04-13T21:06:02.123419Z","end":"2026-04-13T21:06:02.409251Z","steps":["trace[429350538] 'process raft request' (duration: 285.603832ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:02.519617Z","caller":"traceutil/trace.go:171","msg":"trace[1386163123] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"389.004497ms","start":"2026-04-13T21:06:02.130575Z","end":"2026-04-13T21:06:02.519579Z","steps":["trace[1386163123] 'process raft request' (duration: 388.630982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:02.519739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:02.130556Z","time spent":"389.124858ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:06:02.519778Z","caller":"traceutil/trace.go:171","msg":"trace[648825847] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"385.112764ms","start":"2026-04-13T21:06:02.134641Z","end":"2026-04-13T21:06:02.519753Z","steps":["trace[648825847] 'process raft request' (duration: 384.820441ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:02.519854Z","caller":"traceutil/trace.go:171","msg":"trace[1793665922] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"389.182929ms","start":"2026-04-13T21:06:02.130639Z","end":"2026-04-13T21:06:02.519822Z","steps":["trace[1793665922] 'process raft request' (duration: 388.670443ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:02.519929Z","caller":"traceutil/trace.go:171","msg":"trace[1955308946] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"379.013827ms","start":"2026-04-13T21:06:02.140856Z","end":"2026-04-13T21:06:02.51987Z","steps":["trace[1955308946] 'process raft request' (duration: 378.641992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:02.51998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:02.130632Z","time spent":"389.293903ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:06:02.519965Z","caller":"traceutil/trace.go:171","msg":"trace[699618430] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"378.969468ms","start":"2026-04-13T21:06:02.140926Z","end":"2026-04-13T21:06:02.519896Z","steps":["trace[699618430] 'process raft request' (duration: 378.607603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:02.520096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:02.140837Z","time spent":"379.18079ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:06:02.52012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:02.140921Z","time spent":"379.161991ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:06:02.519964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:02.13463Z","time spent":"385.260016ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:06:02.52041Z","caller":"traceutil/trace.go:171","msg":"trace[1084190882] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"360.622127ms","start":"2026-04-13T21:06:02.159771Z","end":"2026-04-13T21:06:02.520393Z","steps":["trace[1084190882] 'process raft request' (duration: 359.903766ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:02.520477Z","caller":"traceutil/trace.go:171","msg":"trace[806463720] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"372.996782ms","start":"2026-04-13T21:06:02.147451Z","end":"2026-04-13T21:06:02.520447Z","steps":["trace[806463720] 'process raft request' (duration: 372.168311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:02.520488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:02.159753Z","time spent":"360.706337ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:06:02.520529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:02.147429Z","time spent":"373.077224ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:06:02.521031Z","caller":"traceutil/trace.go:171","msg":"trace[705663122] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"232.981166ms","start":"2026-04-13T21:06:02.288037Z","end":"2026-04-13T21:06:02.521018Z","steps":["trace[705663122] 'process raft request' (duration: 232.919875ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:02.521129Z","caller":"traceutil/trace.go:171","msg":"trace[1288831983] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"356.369052ms","start":"2026-04-13T21:06:02.164751Z","end":"2026-04-13T21:06:02.52112Z","steps":["trace[1288831983] 'process raft request' (duration: 355.411799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:02.521174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:02.164731Z","time spent":"356.420422ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:06:02.521254Z","caller":"traceutil/trace.go:171","msg":"trace[443335644] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"349.045477ms","start":"2026-04-13T21:06:02.1722Z","end":"2026-04-13T21:06:02.521246Z","steps":["trace[443335644] 'process raft request' (duration: 348.611232ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:02.521447Z","caller":"traceutil/trace.go:171","msg":"trace[757109445] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"336.863565ms","start":"2026-04-13T21:06:02.184574Z","end":"2026-04-13T21:06:02.521438Z","steps":["trace[757109445] 'process raft request' (duration: 336.301477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:02.521531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:02.184563Z","time spent":"336.945626ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:06:02.521294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:02.172186Z","time spent":"349.089958ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:06:02.528193Z","caller":"traceutil/trace.go:171","msg":"trace[2023276647] transaction","detail":"{read_only:false; number_of_response:1; response_revision:527; }","duration":"111.00871ms","start":"2026-04-13T21:06:02.417172Z","end":"2026-04-13T21:06:02.528181Z","steps":["trace[2023276647] 'process raft request' (duration: 110.978359ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:02.528534Z","caller":"traceutil/trace.go:171","msg":"trace[466299430] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"112.697824ms","start":"2026-04-13T21:06:02.415827Z","end":"2026-04-13T21:06:02.528525Z","steps":["trace[466299430] 'process raft request' (duration: 112.224467ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:02.528613Z","caller":"traceutil/trace.go:171","msg":"trace[513943699] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"111.742931ms","start":"2026-04-13T21:06:02.416865Z","end":"2026-04-13T21:06:02.528608Z","steps":["trace[513943699] 'process raft request' (duration: 111.246304ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:09.674857Z","caller":"traceutil/trace.go:171","msg":"trace[2110550012] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"240.358651ms","start":"2026-04-13T21:06:09.434468Z","end":"2026-04-13T21:06:09.674827Z","steps":["trace[2110550012] 'process raft request' (duration: 239.839923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:10.381387Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259572848348179738,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T21:06:10.882017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259572848348179738,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T21:06:11.116483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.093478ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:06:11.116575Z","caller":"traceutil/trace.go:171","msg":"trace[1597152751] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:624; }","duration":"452.199421ms","start":"2026-04-13T21:06:10.664359Z","end":"2026-04-13T21:06:11.116558Z","steps":["trace[1597152751] 'range keys from in-memory index tree' (duration: 452.080037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:11.149296Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.413618991s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T21:06:11.149625Z","caller":"traceutil/trace.go:171","msg":"trace[628491231] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.414008206s","start":"2026-04-13T21:06:09.735596Z","end":"2026-04-13T21:06:11.149604Z","steps":["trace[628491231] 'process raft request' (duration: 1.413887212s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:11.149732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:09.735584Z","time spent":"1.414098048s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:06:11.188768Z","caller":"traceutil/trace.go:171","msg":"trace[1099820892] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.440274862s","start":"2026-04-13T21:06:09.748466Z","end":"2026-04-13T21:06:11.188741Z","steps":["trace[1099820892] 'process raft request' (duration: 1.440043073s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:11.18908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:09.74843Z","time spent":"1.440392856s","remote":"127.0.0.1:34736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:06:11.202394Z","caller":"traceutil/trace.go:171","msg":"trace[1497371670] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"1.321500577s","start":"2026-04-13T21:06:09.880865Z","end":"2026-04-13T21:06:11.202365Z","steps":["trace[1497371670] 'read index received' (duration: 1.268637593s)","trace[1497371670] 'applied index is now lower than readState.Index' (duration: 52.861534ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:06:11.202711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316609023s","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-13T21:06:11.202765Z","caller":"traceutil/trace.go:171","msg":"trace[509311917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"1.316689016s","start":"2026-04-13T21:06:09.886065Z","end":"2026-04-13T21:06:11.202754Z","steps":["trace[509311917] 'agreement among raft nodes before linearized reading' (duration: 1.316574432s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:11.202793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:09.886056Z","time spent":"1.316731127s","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T21:06:11.203085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.480354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:06:11.203109Z","caller":"traceutil/trace.go:171","msg":"trace[1515823846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"556.505015ms","start":"2026-04-13T21:06:10.646597Z","end":"2026-04-13T21:06:11.203102Z","steps":["trace[1515823846] 'agreement among raft nodes before linearized reading' (duration: 556.444673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:11.203164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:10.646484Z","time spent":"556.673901ms","remote":"127.0.0.1:34584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:06:11.203268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.951623ms","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-13T21:06:11.20329Z","caller":"traceutil/trace.go:171","msg":"trace[1082930065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"609.012974ms","start":"2026-04-13T21:06:10.594269Z","end":"2026-04-13T21:06:11.203282Z","steps":["trace[1082930065] 'agreement among raft nodes before linearized reading' (duration: 608.966662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:11.203303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:10.594239Z","time spent":"609.060496ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T21:06:11.203404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317112281s","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-13T21:06:11.203427Z","caller":"traceutil/trace.go:171","msg":"trace[186795048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"1.317189743s","start":"2026-04-13T21:06:09.886233Z","end":"2026-04-13T21:06:11.203423Z","steps":["trace[186795048] 'agreement among raft nodes before linearized reading' (duration: 1.317132721s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:11.203453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:09.886211Z","time spent":"1.317237525s","remote":"127.0.0.1:34796","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-13T21:06:11.202718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.321825808s","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-13T21:06:11.20353Z","caller":"traceutil/trace.go:171","msg":"trace[1074099437] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"1.322700568s","start":"2026-04-13T21:06:09.880825Z","end":"2026-04-13T21:06:11.203525Z","steps":["trace[1074099437] 'agreement among raft nodes before linearized reading' (duration: 1.321728265s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:11.203547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:09.880805Z","time spent":"1.32273653s","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-13T21:06:13.887888Z","caller":"traceutil/trace.go:171","msg":"trace[933951483] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"103.928465ms","start":"2026-04-13T21:06:13.783925Z","end":"2026-04-13T21:06:13.887854Z","steps":["trace[933951483] 'process raft request' (duration: 103.737638ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:14.074497Z","caller":"traceutil/trace.go:171","msg":"trace[1342070649] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"123.306764ms","start":"2026-04-13T21:06:13.951169Z","end":"2026-04-13T21:06:14.074476Z","steps":["trace[1342070649] 'process raft request' (duration: 123.184689ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:14.210858Z","caller":"traceutil/trace.go:171","msg":"trace[1554331984] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"124.611961ms","start":"2026-04-13T21:06:14.086217Z","end":"2026-04-13T21:06:14.210829Z","steps":["trace[1554331984] 'process raft request' (duration: 121.693772ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:21.442605Z","caller":"traceutil/trace.go:171","msg":"trace[1971475120] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"105.925926ms","start":"2026-04-13T21:06:21.336649Z","end":"2026-04-13T21:06:21.442575Z","steps":["trace[1971475120] 'process raft request' (duration: 105.640088ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:21.605785Z","caller":"traceutil/trace.go:171","msg":"trace[351564888] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"160.417653ms","start":"2026-04-13T21:06:21.445327Z","end":"2026-04-13T21:06:21.605745Z","steps":["trace[351564888] 'process raft request' (duration: 65.730093ms)","trace[351564888] 'compare' (duration: 94.542855ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:06:27.60384Z","caller":"traceutil/trace.go:171","msg":"trace[728460481] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"894.342472ms","start":"2026-04-13T21:06:26.709476Z","end":"2026-04-13T21:06:27.603819Z","steps":["trace[728460481] 'process raft request' (duration: 894.232328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:27.603971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:26.709452Z","time spent":"894.459394ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:06:27.608296Z","caller":"traceutil/trace.go:171","msg":"trace[1886780766] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:808; }","duration":"180.351461ms","start":"2026-04-13T21:06:27.427928Z","end":"2026-04-13T21:06:27.60828Z","steps":["trace[1886780766] 'read index received' (duration: 175.841256ms)","trace[1886780766] 'applied index is now lower than readState.Index' (duration: 4.509475ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:06:27.608331Z","caller":"traceutil/trace.go:171","msg":"trace[527286439] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"750.753122ms","start":"2026-04-13T21:06:26.857562Z","end":"2026-04-13T21:06:27.608316Z","steps":["trace[527286439] 'process raft request' (duration: 750.623049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:27.608403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:26.857534Z","time spent":"750.828485ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:06:27.6085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.556397ms","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-13T21:06:27.608531Z","caller":"traceutil/trace.go:171","msg":"trace[1890262413] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"180.639939ms","start":"2026-04-13T21:06:27.427886Z","end":"2026-04-13T21:06:27.608526Z","steps":["trace[1890262413] 'agreement among raft nodes before linearized reading' (duration: 180.489565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:27.608565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.650149ms","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-13T21:06:27.608588Z","caller":"traceutil/trace.go:171","msg":"trace[315129729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:785; }","duration":"143.696771ms","start":"2026-04-13T21:06:27.464885Z","end":"2026-04-13T21:06:27.608581Z","steps":["trace[315129729] 'agreement among raft nodes before linearized reading' (duration: 143.595668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:27.608684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.748162ms","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-13T21:06:27.608749Z","caller":"traceutil/trace.go:171","msg":"trace[2015670865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:785; }","duration":"138.842066ms","start":"2026-04-13T21:06:27.46989Z","end":"2026-04-13T21:06:27.608732Z","steps":["trace[2015670865] 'agreement among raft nodes before linearized reading' (duration: 138.6705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:56.295547Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259572848348180819,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T21:06:56.589275Z","caller":"traceutil/trace.go:171","msg":"trace[370117197] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"843.54652ms","start":"2026-04-13T21:06:55.745703Z","end":"2026-04-13T21:06:56.58925Z","steps":["trace[370117197] 'process raft request' (duration: 843.328764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:56.589688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:55.745685Z","time spent":"843.689543ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:06:56.61723Z","caller":"traceutil/trace.go:171","msg":"trace[701472504] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1213; }","duration":"822.46413ms","start":"2026-04-13T21:06:55.794742Z","end":"2026-04-13T21:06:56.617206Z","steps":["trace[701472504] 'read index received' (duration: 794.26586ms)","trace[701472504] 'applied index is now lower than readState.Index' (duration: 28.19738ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:06:56.617452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.691985ms","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-13T21:06:56.61749Z","caller":"traceutil/trace.go:171","msg":"trace[833453473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1184; }","duration":"822.787098ms","start":"2026-04-13T21:06:55.794694Z","end":"2026-04-13T21:06:56.617481Z","steps":["trace[833453473] 'agreement among raft nodes before linearized reading' (duration: 822.635334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:56.617526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:55.794674Z","time spent":"822.844728ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T21:06:56.617353Z","caller":"traceutil/trace.go:171","msg":"trace[336525956] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"616.964312ms","start":"2026-04-13T21:06:56.00035Z","end":"2026-04-13T21:06:56.617314Z","steps":["trace[336525956] 'process raft request' (duration: 616.727316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:56.617779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:56.000331Z","time spent":"617.305819ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:06:56.617861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.883104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.192\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T21:06:56.618027Z","caller":"traceutil/trace.go:171","msg":"trace[1900535038] range","detail":"{range_begin:/registry/masterleases/199.19.213.192; range_end:; response_count:1; response_revision:1184; }","duration":"735.103999ms","start":"2026-04-13T21:06:55.882903Z","end":"2026-04-13T21:06:56.618007Z","steps":["trace[1900535038] 'agreement among raft nodes before linearized reading' (duration: 734.679529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:56.618633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.410267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-13T21:06:56.618676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:55.882879Z","time spent":"735.747884ms","remote":"127.0.0.1:34594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.192\" "} {"level":"info","ts":"2026-04-13T21:06:56.618721Z","caller":"traceutil/trace.go:171","msg":"trace[182712697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1184; }","duration":"819.50198ms","start":"2026-04-13T21:06:55.799194Z","end":"2026-04-13T21:06:56.618696Z","steps":["trace[182712697] 'agreement among raft nodes before linearized reading' (duration: 818.28254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:56.618781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:55.799185Z","time spent":"819.584341ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T21:06:56.617932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.656404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-13T21:06:56.618456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.225916ms","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-13T21:06:56.619192Z","caller":"traceutil/trace.go:171","msg":"trace[1960245675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1184; }","duration":"811.032855ms","start":"2026-04-13T21:06:55.808142Z","end":"2026-04-13T21:06:56.619175Z","steps":["trace[1960245675] 'agreement among raft nodes before linearized reading' (duration: 810.017142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:56.619302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:55.808118Z","time spent":"811.16727ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-13T21:06:56.619398Z","caller":"traceutil/trace.go:171","msg":"trace[1959582429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1184; }","duration":"750.1556ms","start":"2026-04-13T21:06:55.869221Z","end":"2026-04-13T21:06:56.619377Z","steps":["trace[1959582429] 'agreement among raft nodes before linearized reading' (duration: 748.321636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:56.619501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:55.869175Z","time spent":"750.308593ms","remote":"127.0.0.1:34796","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":"info","ts":"2026-04-13T21:07:02.155711Z","caller":"traceutil/trace.go:171","msg":"trace[1089107346] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1257; }","duration":"119.884964ms","start":"2026-04-13T21:07:02.0358Z","end":"2026-04-13T21:07:02.155685Z","steps":["trace[1089107346] 'read index received' (duration: 119.72529ms)","trace[1089107346] 'applied index is now lower than readState.Index' (duration: 158.474µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:07:02.15585Z","caller":"traceutil/trace.go:171","msg":"trace[1549383751] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"163.304473ms","start":"2026-04-13T21:07:01.992501Z","end":"2026-04-13T21:07:02.155806Z","steps":["trace[1549383751] 'process raft request' (duration: 163.034808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:02.156089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.270732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-13T21:07:02.156146Z","caller":"traceutil/trace.go:171","msg":"trace[312574466] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1224; }","duration":"120.384574ms","start":"2026-04-13T21:07:02.035747Z","end":"2026-04-13T21:07:02.156132Z","steps":["trace[312574466] 'agreement among raft nodes before linearized reading' (duration: 120.120429ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:02.429392Z","caller":"traceutil/trace.go:171","msg":"trace[1630222809] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"126.559345ms","start":"2026-04-13T21:07:02.302801Z","end":"2026-04-13T21:07:02.429361Z","steps":["trace[1630222809] 'process raft request' (duration: 125.763517ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:02.456864Z","caller":"traceutil/trace.go:171","msg":"trace[678321572] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"139.305385ms","start":"2026-04-13T21:07:02.317526Z","end":"2026-04-13T21:07:02.456831Z","steps":["trace[678321572] 'process raft request' (duration: 139.05511ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:02.626165Z","caller":"traceutil/trace.go:171","msg":"trace[1869292573] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"123.390973ms","start":"2026-04-13T21:07:02.502738Z","end":"2026-04-13T21:07:02.626129Z","steps":["trace[1869292573] 'process raft request' (duration: 123.314971ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:02.626197Z","caller":"traceutil/trace.go:171","msg":"trace[1883423548] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"123.34542ms","start":"2026-04-13T21:07:02.502818Z","end":"2026-04-13T21:07:02.626163Z","steps":["trace[1883423548] 'process raft request' (duration: 123.272869ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:02.626258Z","caller":"traceutil/trace.go:171","msg":"trace[1416841480] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"123.112566ms","start":"2026-04-13T21:07:02.50313Z","end":"2026-04-13T21:07:02.626243Z","steps":["trace[1416841480] 'process raft request' (duration: 122.986533ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:02.626258Z","caller":"traceutil/trace.go:171","msg":"trace[1014626500] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"123.506085ms","start":"2026-04-13T21:07:02.502698Z","end":"2026-04-13T21:07:02.626204Z","steps":["trace[1014626500] 'process raft request' (duration: 102.633479ms)","trace[1014626500] 'compare' (duration: 20.6301ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:07:02.626335Z","caller":"traceutil/trace.go:171","msg":"trace[1388527599] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"120.340503ms","start":"2026-04-13T21:07:02.50599Z","end":"2026-04-13T21:07:02.62633Z","steps":["trace[1388527599] 'process raft request' (duration: 120.140379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:02.626311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.290699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:07:02.626405Z","caller":"traceutil/trace.go:171","msg":"trace[251796121] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"123.533415ms","start":"2026-04-13T21:07:02.502861Z","end":"2026-04-13T21:07:02.626394Z","steps":["trace[251796121] 'process raft request' (duration: 123.241529ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:02.626419Z","caller":"traceutil/trace.go:171","msg":"trace[1335164250] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1245; }","duration":"123.458043ms","start":"2026-04-13T21:07:02.502945Z","end":"2026-04-13T21:07:02.626403Z","steps":["trace[1335164250] 'agreement among raft nodes before linearized reading' (duration: 123.27554ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:02.626183Z","caller":"traceutil/trace.go:171","msg":"trace[1335735041] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"123.409563ms","start":"2026-04-13T21:07:02.502754Z","end":"2026-04-13T21:07:02.626164Z","steps":["trace[1335735041] 'process raft request' (duration: 123.324312ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:02.626185Z","caller":"traceutil/trace.go:171","msg":"trace[554901332] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1274; }","duration":"123.193667ms","start":"2026-04-13T21:07:02.502975Z","end":"2026-04-13T21:07:02.626168Z","steps":["trace[554901332] 'read index received' (duration: 5.587677ms)","trace[554901332] 'applied index is now lower than readState.Index' (duration: 117.60533ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:07:02.765214Z","caller":"traceutil/trace.go:171","msg":"trace[788007043] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"134.003243ms","start":"2026-04-13T21:07:02.631191Z","end":"2026-04-13T21:07:02.765194Z","steps":["trace[788007043] 'process raft request' (duration: 133.626305ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:02.765434Z","caller":"traceutil/trace.go:171","msg":"trace[352550151] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"133.223827ms","start":"2026-04-13T21:07:02.632189Z","end":"2026-04-13T21:07:02.765413Z","steps":["trace[352550151] 'process raft request' (duration: 132.970811ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:02.765513Z","caller":"traceutil/trace.go:171","msg":"trace[445886947] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1282; }","duration":"127.978657ms","start":"2026-04-13T21:07:02.637513Z","end":"2026-04-13T21:07:02.765492Z","steps":["trace[445886947] 'read index received' (duration: 39.04269ms)","trace[445886947] 'applied index is now lower than readState.Index' (duration: 88.935057ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:07:02.765548Z","caller":"traceutil/trace.go:171","msg":"trace[128831155] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"131.788173ms","start":"2026-04-13T21:07:02.633727Z","end":"2026-04-13T21:07:02.765515Z","steps":["trace[128831155] 'process raft request' (duration: 131.61624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:02.766098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.650812ms","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-13T21:07:02.766136Z","caller":"traceutil/trace.go:171","msg":"trace[411644460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1250; }","duration":"103.746805ms","start":"2026-04-13T21:07:02.66238Z","end":"2026-04-13T21:07:02.766126Z","steps":["trace[411644460] 'agreement among raft nodes before linearized reading' (duration: 103.483409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:02.765785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.255593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:07:02.766247Z","caller":"traceutil/trace.go:171","msg":"trace[348653714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1250; }","duration":"128.751035ms","start":"2026-04-13T21:07:02.63749Z","end":"2026-04-13T21:07:02.766241Z","steps":["trace[348653714] 'agreement among raft nodes before linearized reading' (duration: 128.239113ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:02.765818Z","caller":"traceutil/trace.go:171","msg":"trace[1887580965] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"129.291186ms","start":"2026-04-13T21:07:02.636518Z","end":"2026-04-13T21:07:02.765809Z","steps":["trace[1887580965] 'process raft request' (duration: 128.928439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:02.765941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.118661ms","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-13T21:07:02.76643Z","caller":"traceutil/trace.go:171","msg":"trace[1201351180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1250; }","duration":"107.607462ms","start":"2026-04-13T21:07:02.658802Z","end":"2026-04-13T21:07:02.766409Z","steps":["trace[1201351180] 'agreement among raft nodes before linearized reading' (duration: 107.038889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:02.766052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.300198ms","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-13T21:07:02.766491Z","caller":"traceutil/trace.go:171","msg":"trace[138765817] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1250; }","duration":"108.83102ms","start":"2026-04-13T21:07:02.657645Z","end":"2026-04-13T21:07:02.766476Z","steps":["trace[138765817] 'agreement among raft nodes before linearized reading' (duration: 108.176765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:02.766074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.310966ms","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-13T21:07:02.766561Z","caller":"traceutil/trace.go:171","msg":"trace[2086439717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1250; }","duration":"107.804857ms","start":"2026-04-13T21:07:02.658746Z","end":"2026-04-13T21:07:02.76655Z","steps":["trace[2086439717] 'agreement among raft nodes before linearized reading' (duration: 107.097111ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:02.964931Z","caller":"traceutil/trace.go:171","msg":"trace[443942565] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"100.740856ms","start":"2026-04-13T21:07:02.864149Z","end":"2026-04-13T21:07:02.96489Z","steps":["trace[443942565] 'process raft request' (duration: 42.337775ms)","trace[443942565] 'compare' (duration: 58.033262ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:07:03.113803Z","caller":"traceutil/trace.go:171","msg":"trace[1847513458] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1257; }","duration":"135.93887ms","start":"2026-04-13T21:07:02.977828Z","end":"2026-04-13T21:07:03.113767Z","steps":["trace[1847513458] 'process raft request' (duration: 133.184318ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:16.622312Z","caller":"traceutil/trace.go:171","msg":"trace[2076332084] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"163.240255ms","start":"2026-04-13T21:07:16.459037Z","end":"2026-04-13T21:07:16.622277Z","steps":["trace[2076332084] 'process raft request' (duration: 163.107122ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:36.115269Z","caller":"traceutil/trace.go:171","msg":"trace[1809881443] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"119.717093ms","start":"2026-04-13T21:07:35.995521Z","end":"2026-04-13T21:07:36.115238Z","steps":["trace[1809881443] 'process raft request' (duration: 68.703557ms)","trace[1809881443] 'compare' (duration: 50.811752ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:07:42.64251Z","caller":"traceutil/trace.go:171","msg":"trace[876056346] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"409.795192ms","start":"2026-04-13T21:07:42.232691Z","end":"2026-04-13T21:07:42.642486Z","steps":["trace[876056346] 'read index received' (duration: 409.592977ms)","trace[876056346] 'applied index is now lower than readState.Index' (duration: 201.245µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:07:42.642588Z","caller":"traceutil/trace.go:171","msg":"trace[242956356] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"440.237186ms","start":"2026-04-13T21:07:42.202306Z","end":"2026-04-13T21:07:42.642543Z","steps":["trace[242956356] 'process raft request' (duration: 440.021991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:42.64271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.006855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:07:42.642805Z","caller":"traceutil/trace.go:171","msg":"trace[1228104289] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1843; }","duration":"410.159998ms","start":"2026-04-13T21:07:42.232633Z","end":"2026-04-13T21:07:42.642793Z","steps":["trace[1228104289] 'agreement among raft nodes before linearized reading' (duration: 410.004485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:42.64288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:42.202295Z","time spent":"440.451299ms","remote":"127.0.0.1:34736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3726,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:07:42.643077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.422221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e4ff0a4e-ac34-47dc-8e9c-aa22439fd17d\" ","response":"range_response_count:1 size:3741"} {"level":"warn","ts":"2026-04-13T21:07:42.642876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:42.232611Z","time spent":"410.25274ms","remote":"127.0.0.1:34932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-13T21:07:42.643159Z","caller":"traceutil/trace.go:171","msg":"trace[1947519492] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e4ff0a4e-ac34-47dc-8e9c-aa22439fd17d; range_end:; response_count:1; response_revision:1843; }","duration":"299.574604ms","start":"2026-04-13T21:07:42.343565Z","end":"2026-04-13T21:07:42.643139Z","steps":["trace[1947519492] 'agreement among raft nodes before linearized reading' (duration: 299.411701ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:43.284168Z","caller":"traceutil/trace.go:171","msg":"trace[2066692930] linearizableReadLoop","detail":"{readStateIndex:1915; appliedIndex:1914; }","duration":"207.45476ms","start":"2026-04-13T21:07:43.076685Z","end":"2026-04-13T21:07:43.284139Z","steps":["trace[2066692930] 'read index received' (duration: 207.247817ms)","trace[2066692930] 'applied index is now lower than readState.Index' (duration: 205.643µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:07:43.28428Z","caller":"traceutil/trace.go:171","msg":"trace[1709695347] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"213.327867ms","start":"2026-04-13T21:07:43.070905Z","end":"2026-04-13T21:07:43.284232Z","steps":["trace[1709695347] 'process raft request' (duration: 213.062082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:43.284396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.699574ms","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-13T21:07:43.284448Z","caller":"traceutil/trace.go:171","msg":"trace[233862691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1846; }","duration":"207.794857ms","start":"2026-04-13T21:07:43.07664Z","end":"2026-04-13T21:07:43.284435Z","steps":["trace[233862691] 'agreement among raft nodes before linearized reading' (duration: 207.641873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:43.284491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.808183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T21:07:43.284535Z","caller":"traceutil/trace.go:171","msg":"trace[466919767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1846; }","duration":"199.879054ms","start":"2026-04-13T21:07:43.084645Z","end":"2026-04-13T21:07:43.284524Z","steps":["trace[466919767] 'agreement among raft nodes before linearized reading' (duration: 199.788112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:43.284818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.085385ms","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-13T21:07:43.285011Z","caller":"traceutil/trace.go:171","msg":"trace[1647500000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1846; }","duration":"201.339559ms","start":"2026-04-13T21:07:43.083645Z","end":"2026-04-13T21:07:43.284985Z","steps":["trace[1647500000] 'agreement among raft nodes before linearized reading' (duration: 200.853681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:43.285145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.670062ms","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-13T21:07:43.285344Z","caller":"traceutil/trace.go:171","msg":"trace[215028857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1846; }","duration":"203.881356ms","start":"2026-04-13T21:07:43.081442Z","end":"2026-04-13T21:07:43.285323Z","steps":["trace[215028857] 'agreement among raft nodes before linearized reading' (duration: 202.960428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:43.285242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.97068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-13T21:07:43.285598Z","caller":"traceutil/trace.go:171","msg":"trace[1283844965] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1846; }","duration":"149.638263ms","start":"2026-04-13T21:07:43.135936Z","end":"2026-04-13T21:07:43.285575Z","steps":["trace[1283844965] 'agreement among raft nodes before linearized reading' (duration: 148.539584ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:17.27226Z","caller":"traceutil/trace.go:171","msg":"trace[229631279] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"142.940726ms","start":"2026-04-13T21:08:17.129293Z","end":"2026-04-13T21:08:17.272233Z","steps":["trace[229631279] 'process raft request' (duration: 142.395238ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:17.605387Z","caller":"traceutil/trace.go:171","msg":"trace[1831484772] linearizableReadLoop","detail":"{readStateIndex:2166; appliedIndex:2165; }","duration":"137.372057ms","start":"2026-04-13T21:08:17.467991Z","end":"2026-04-13T21:08:17.605363Z","steps":["trace[1831484772] 'read index received' (duration: 131.441143ms)","trace[1831484772] 'applied index is now lower than readState.Index' (duration: 5.929854ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:08:17.605742Z","caller":"traceutil/trace.go:171","msg":"trace[1912053251] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"184.002674ms","start":"2026-04-13T21:08:17.421726Z","end":"2026-04-13T21:08:17.605729Z","steps":["trace[1912053251] 'process raft request' (duration: 177.764044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:17.605888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.2463ms","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-13T21:08:17.606091Z","caller":"traceutil/trace.go:171","msg":"trace[1650324299] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2090; }","duration":"136.355051ms","start":"2026-04-13T21:08:17.469577Z","end":"2026-04-13T21:08:17.605932Z","steps":["trace[1650324299] 'agreement among raft nodes before linearized reading' (duration: 136.166798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:17.606282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.297253ms","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-13T21:08:17.606314Z","caller":"traceutil/trace.go:171","msg":"trace[915486273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2090; }","duration":"138.369843ms","start":"2026-04-13T21:08:17.467935Z","end":"2026-04-13T21:08:17.606305Z","steps":["trace[915486273] 'agreement among raft nodes before linearized reading' (duration: 138.279971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:17.606574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.637383ms","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-13T21:08:17.606615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.813733ms","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-13T21:08:17.606711Z","caller":"traceutil/trace.go:171","msg":"trace[222388614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2090; }","duration":"135.855323ms","start":"2026-04-13T21:08:17.470775Z","end":"2026-04-13T21:08:17.606631Z","steps":["trace[222388614] 'agreement among raft nodes before linearized reading' (duration: 135.792462ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:17.606638Z","caller":"traceutil/trace.go:171","msg":"trace[257265182] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2090; }","duration":"134.707654ms","start":"2026-04-13T21:08:17.47191Z","end":"2026-04-13T21:08:17.606617Z","steps":["trace[257265182] 'agreement among raft nodes before linearized reading' (duration: 134.570212ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:17.753547Z","caller":"traceutil/trace.go:171","msg":"trace[1935318882] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"142.354678ms","start":"2026-04-13T21:08:17.611143Z","end":"2026-04-13T21:08:17.753498Z","steps":["trace[1935318882] 'process raft request' (duration: 131.990882ms)","trace[1935318882] 'compare' (duration: 10.161583ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:08:17.753709Z","caller":"traceutil/trace.go:171","msg":"trace[996299825] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"142.113783ms","start":"2026-04-13T21:08:17.61158Z","end":"2026-04-13T21:08:17.753694Z","steps":["trace[996299825] 'process raft request' (duration: 141.826569ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:17.753921Z","caller":"traceutil/trace.go:171","msg":"trace[1976718383] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"142.197744ms","start":"2026-04-13T21:08:17.61171Z","end":"2026-04-13T21:08:17.753907Z","steps":["trace[1976718383] 'process raft request' (duration: 141.757508ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:17.753973Z","caller":"traceutil/trace.go:171","msg":"trace[2131538406] linearizableReadLoop","detail":"{readStateIndex:2171; appliedIndex:2167; }","duration":"117.128213ms","start":"2026-04-13T21:08:17.63683Z","end":"2026-04-13T21:08:17.753959Z","steps":["trace[2131538406] 'read index received' (duration: 106.318071ms)","trace[2131538406] 'applied index is now lower than readState.Index' (duration: 10.808512ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:08:17.754073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.235225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:08:17.754103Z","caller":"traceutil/trace.go:171","msg":"trace[1724445113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2096; }","duration":"117.314417ms","start":"2026-04-13T21:08:17.63678Z","end":"2026-04-13T21:08:17.754094Z","steps":["trace[1724445113] 'agreement among raft nodes before linearized reading' (duration: 117.226126ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:17.754157Z","caller":"traceutil/trace.go:171","msg":"trace[1636105520] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"142.285946ms","start":"2026-04-13T21:08:17.611861Z","end":"2026-04-13T21:08:17.754147Z","steps":["trace[1636105520] 'process raft request' (duration: 141.638695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:18.508103Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259572848348182728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T21:08:18.820612Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.024070802s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T21:08:18.891285Z","caller":"traceutil/trace.go:171","msg":"trace[355198762] linearizableReadLoop","detail":"{readStateIndex:2173; appliedIndex:2172; }","duration":"883.446729ms","start":"2026-04-13T21:08:18.007811Z","end":"2026-04-13T21:08:18.891258Z","steps":["trace[355198762] 'read index received' (duration: 813.131169ms)","trace[355198762] 'applied index is now lower than readState.Index' (duration: 70.31418ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:08:18.891605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.821202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:08:18.891649Z","caller":"traceutil/trace.go:171","msg":"trace[1655745360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2097; }","duration":"252.918724ms","start":"2026-04-13T21:08:18.638719Z","end":"2026-04-13T21:08:18.891638Z","steps":["trace[1655745360] 'agreement among raft nodes before linearized reading' (duration: 252.833542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:18.891845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.424514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-13T21:08:18.891918Z","caller":"traceutil/trace.go:171","msg":"trace[372304534] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2097; }","duration":"277.537637ms","start":"2026-04-13T21:08:18.61436Z","end":"2026-04-13T21:08:18.891898Z","steps":["trace[372304534] 'agreement among raft nodes before linearized reading' (duration: 277.185531ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:18.891999Z","caller":"traceutil/trace.go:171","msg":"trace[2078878050] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"1.095659193s","start":"2026-04-13T21:08:17.796326Z","end":"2026-04-13T21:08:18.891985Z","steps":["trace[2078878050] 'process raft request' (duration: 1.024670371s)","trace[2078878050] 'compare' (duration: 69.714011ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:08:18.892134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:17.796302Z","time spent":"1.095753724s","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:08:18.89205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.914637ms","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-13T21:08:18.893155Z","caller":"traceutil/trace.go:171","msg":"trace[242543198] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2097; }","duration":"885.38335ms","start":"2026-04-13T21:08:18.00775Z","end":"2026-04-13T21:08:18.893133Z","steps":["trace[242543198] 'agreement among raft nodes before linearized reading' (duration: 883.711902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:18.893247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:18.007727Z","time spent":"885.502302ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-13T21:08:19.525652Z","caller":"traceutil/trace.go:171","msg":"trace[309519258] linearizableReadLoop","detail":"{readStateIndex:2175; appliedIndex:2174; }","duration":"226.169967ms","start":"2026-04-13T21:08:19.299458Z","end":"2026-04-13T21:08:19.525628Z","steps":["trace[309519258] 'read index received' (duration: 225.939674ms)","trace[309519258] 'applied index is now lower than readState.Index' (duration: 229.523µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:08:19.525918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.40819ms","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-13T21:08:19.525696Z","caller":"traceutil/trace.go:171","msg":"trace[282502904] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"249.146352ms","start":"2026-04-13T21:08:19.276531Z","end":"2026-04-13T21:08:19.525677Z","steps":["trace[282502904] 'process raft request' (duration: 248.814237ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:19.526041Z","caller":"traceutil/trace.go:171","msg":"trace[772337005] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2099; }","duration":"226.627294ms","start":"2026-04-13T21:08:19.299372Z","end":"2026-04-13T21:08:19.526Z","steps":["trace[772337005] 'agreement among raft nodes before linearized reading' (duration: 226.348979ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:19.86516Z","caller":"traceutil/trace.go:171","msg":"trace[748728237] linearizableReadLoop","detail":"{readStateIndex:2177; appliedIndex:2175; }","duration":"241.108784ms","start":"2026-04-13T21:08:19.624027Z","end":"2026-04-13T21:08:19.865136Z","steps":["trace[748728237] 'read index received' (duration: 239.132572ms)","trace[748728237] 'applied index is now lower than readState.Index' (duration: 1.975132ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:08:19.865208Z","caller":"traceutil/trace.go:171","msg":"trace[1870793418] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"333.96255ms","start":"2026-04-13T21:08:19.531224Z","end":"2026-04-13T21:08:19.865187Z","steps":["trace[1870793418] 'process raft request' (duration: 331.876487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:19.865359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.322607ms","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-13T21:08:19.865434Z","caller":"traceutil/trace.go:171","msg":"trace[700170031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2101; }","duration":"241.434478ms","start":"2026-04-13T21:08:19.623981Z","end":"2026-04-13T21:08:19.865416Z","steps":["trace[700170031] 'agreement among raft nodes before linearized reading' (duration: 241.274265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:19.865611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:19.531205Z","time spent":"334.046551ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:08:19.865524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.158694ms","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-13T21:08:19.865759Z","caller":"traceutil/trace.go:171","msg":"trace[1590819530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2101; }","duration":"107.428958ms","start":"2026-04-13T21:08:19.758315Z","end":"2026-04-13T21:08:19.865744Z","steps":["trace[1590819530] 'agreement among raft nodes before linearized reading' (duration: 106.9962ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:19.86604Z","caller":"traceutil/trace.go:171","msg":"trace[1519566990] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"254.099501ms","start":"2026-04-13T21:08:19.611923Z","end":"2026-04-13T21:08:19.866023Z","steps":["trace[1519566990] 'process raft request' (duration: 253.162796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:19.86605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.654791ms","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-13T21:08:19.866096Z","caller":"traceutil/trace.go:171","msg":"trace[719686949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2101; }","duration":"107.739562ms","start":"2026-04-13T21:08:19.758345Z","end":"2026-04-13T21:08:19.866085Z","steps":["trace[719686949] 'agreement among raft nodes before linearized reading' (duration: 107.618831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:19.866163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.681552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-13T21:08:19.866241Z","caller":"traceutil/trace.go:171","msg":"trace[1417246182] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2101; }","duration":"107.816894ms","start":"2026-04-13T21:08:19.758406Z","end":"2026-04-13T21:08:19.866223Z","steps":["trace[1417246182] 'agreement among raft nodes before linearized reading' (duration: 107.55627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:19.866189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.065652ms","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-13T21:08:19.86635Z","caller":"traceutil/trace.go:171","msg":"trace[1587234072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2101; }","duration":"107.347218ms","start":"2026-04-13T21:08:19.758983Z","end":"2026-04-13T21:08:19.86633Z","steps":["trace[1587234072] 'agreement among raft nodes before linearized reading' (duration: 106.719228ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:14.248941Z","caller":"traceutil/trace.go:171","msg":"trace[1118106156] linearizableReadLoop","detail":"{readStateIndex:2535; appliedIndex:2534; }","duration":"112.54325ms","start":"2026-04-13T21:09:14.136362Z","end":"2026-04-13T21:09:14.248905Z","steps":["trace[1118106156] 'read index received' (duration: 80.344773ms)","trace[1118106156] 'applied index is now lower than readState.Index' (duration: 32.197217ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:09:14.249523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.682026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-13T21:09:14.249522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.794346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T21:09:14.249569Z","caller":"traceutil/trace.go:171","msg":"trace[1992651434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2446; }","duration":"106.765098ms","start":"2026-04-13T21:09:14.142796Z","end":"2026-04-13T21:09:14.249562Z","steps":["trace[1992651434] 'agreement among raft nodes before linearized reading' (duration: 106.549964ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:14.249579Z","caller":"traceutil/trace.go:171","msg":"trace[1614805951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2446; }","duration":"108.883738ms","start":"2026-04-13T21:09:14.140683Z","end":"2026-04-13T21:09:14.249567Z","steps":["trace[1614805951] 'agreement among raft nodes before linearized reading' (duration: 108.621583ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:14.249687Z","caller":"traceutil/trace.go:171","msg":"trace[1762291307] transaction","detail":"{read_only:false; response_revision:2446; number_of_response:1; }","duration":"114.168633ms","start":"2026-04-13T21:09:14.135091Z","end":"2026-04-13T21:09:14.249259Z","steps":["trace[1762291307] 'process raft request' (duration: 81.638001ms)","trace[1762291307] 'compare' (duration: 32.007424ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:09:14.24982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.090596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-13T21:09:14.249943Z","caller":"traceutil/trace.go:171","msg":"trace[609435472] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2446; }","duration":"103.312279ms","start":"2026-04-13T21:09:14.146604Z","end":"2026-04-13T21:09:14.249916Z","steps":["trace[609435472] 'agreement among raft nodes before linearized reading' (duration: 102.736851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:09:14.250451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.090192ms","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-13T21:09:14.250514Z","caller":"traceutil/trace.go:171","msg":"trace[336917780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2446; }","duration":"114.154773ms","start":"2026-04-13T21:09:14.13635Z","end":"2026-04-13T21:09:14.250505Z","steps":["trace[336917780] 'agreement among raft nodes before linearized reading' (duration: 112.906575ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:14.370887Z","caller":"traceutil/trace.go:171","msg":"trace[897526271] transaction","detail":"{read_only:false; response_revision:2447; number_of_response:1; }","duration":"117.565901ms","start":"2026-04-13T21:09:14.253301Z","end":"2026-04-13T21:09:14.370867Z","steps":["trace[897526271] 'process raft request' (duration: 117.121715ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:14.431711Z","caller":"traceutil/trace.go:171","msg":"trace[1267053644] transaction","detail":"{read_only:false; response_revision:2450; number_of_response:1; }","duration":"177.358351ms","start":"2026-04-13T21:09:14.254329Z","end":"2026-04-13T21:09:14.431688Z","steps":["trace[1267053644] 'process raft request' (duration: 177.319211ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:14.431764Z","caller":"traceutil/trace.go:171","msg":"trace[1799920375] transaction","detail":"{read_only:false; response_revision:2448; number_of_response:1; }","duration":"177.559024ms","start":"2026-04-13T21:09:14.254172Z","end":"2026-04-13T21:09:14.431731Z","steps":["trace[1799920375] 'process raft request' (duration: 177.31077ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:14.431893Z","caller":"traceutil/trace.go:171","msg":"trace[625198176] transaction","detail":"{read_only:false; response_revision:2449; number_of_response:1; }","duration":"177.657625ms","start":"2026-04-13T21:09:14.254205Z","end":"2026-04-13T21:09:14.431863Z","steps":["trace[625198176] 'process raft request' (duration: 177.401031ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:22.022789Z","caller":"traceutil/trace.go:171","msg":"trace[2014273500] transaction","detail":"{read_only:false; response_revision:2537; number_of_response:1; }","duration":"278.723285ms","start":"2026-04-13T21:09:21.744044Z","end":"2026-04-13T21:09:22.022767Z","steps":["trace[2014273500] 'process raft request' (duration: 278.280278ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:49.110147Z","caller":"traceutil/trace.go:171","msg":"trace[2069319493] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"111.643738ms","start":"2026-04-13T21:09:48.998481Z","end":"2026-04-13T21:09:49.110124Z","steps":["trace[2069319493] 'process raft request' (duration: 111.467676ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:54.74894Z","caller":"traceutil/trace.go:171","msg":"trace[324585454] linearizableReadLoop","detail":"{readStateIndex:2815; appliedIndex:2814; }","duration":"435.132326ms","start":"2026-04-13T21:09:54.313784Z","end":"2026-04-13T21:09:54.748916Z","steps":["trace[324585454] 'read index received' (duration: 434.939792ms)","trace[324585454] 'applied index is now lower than readState.Index' (duration: 191.774µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:09:54.748986Z","caller":"traceutil/trace.go:171","msg":"trace[1024983451] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"436.707796ms","start":"2026-04-13T21:09:54.312264Z","end":"2026-04-13T21:09:54.748972Z","steps":["trace[1024983451] 'process raft request' (duration: 436.524863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:09:54.749175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.217243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:09:54.749239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.075732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-13T21:09:54.749284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.826172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-13T21:09:54.749313Z","caller":"traceutil/trace.go:171","msg":"trace[1459347299] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2717; }","duration":"124.886923ms","start":"2026-04-13T21:09:54.624416Z","end":"2026-04-13T21:09:54.749303Z","steps":["trace[1459347299] 'agreement among raft nodes before linearized reading' (duration: 124.802062ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:54.749308Z","caller":"traceutil/trace.go:171","msg":"trace[956478871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2717; }","duration":"322.247665ms","start":"2026-04-13T21:09:54.427045Z","end":"2026-04-13T21:09:54.749293Z","steps":["trace[956478871] 'agreement among raft nodes before linearized reading' (duration: 322.077153ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:54.749356Z","caller":"traceutil/trace.go:171","msg":"trace[141339488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2717; }","duration":"110.351775ms","start":"2026-04-13T21:09:54.638909Z","end":"2026-04-13T21:09:54.74926Z","steps":["trace[141339488] 'agreement among raft nodes before linearized reading' (duration: 110.228023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:09:54.749322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.819289ms","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":"warn","ts":"2026-04-13T21:09:54.749494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.018958ms","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-13T21:09:54.749481Z","caller":"traceutil/trace.go:171","msg":"trace[778698512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2717; }","duration":"124.105572ms","start":"2026-04-13T21:09:54.625357Z","end":"2026-04-13T21:09:54.749463Z","steps":["trace[778698512] 'agreement among raft nodes before linearized reading' (duration: 123.768908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:09:54.749492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.247247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-13T21:09:54.74917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:09:54.312252Z","time spent":"436.856898ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:09:54.749549Z","caller":"traceutil/trace.go:171","msg":"trace[1741087515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2717; }","duration":"189.1027ms","start":"2026-04-13T21:09:54.560433Z","end":"2026-04-13T21:09:54.749535Z","steps":["trace[1741087515] 'agreement among raft nodes before linearized reading' (duration: 188.958378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:09:54.749153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.312478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:09:54.749585Z","caller":"traceutil/trace.go:171","msg":"trace[156880588] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2717; }","duration":"119.366809ms","start":"2026-04-13T21:09:54.630199Z","end":"2026-04-13T21:09:54.749566Z","steps":["trace[156880588] 'agreement among raft nodes before linearized reading' (duration: 119.007343ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:54.74962Z","caller":"traceutil/trace.go:171","msg":"trace[1741021938] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2717; }","duration":"435.841665ms","start":"2026-04-13T21:09:54.313762Z","end":"2026-04-13T21:09:54.749604Z","steps":["trace[1741021938] 'agreement among raft nodes before linearized reading' (duration: 435.248747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:09:54.749363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:09:54.427021Z","time spent":"322.323816ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T21:09:54.749721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:09:54.313753Z","time spent":"435.930576ms","remote":"127.0.0.1:34744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-04-13T21:09:54.917397Z","caller":"traceutil/trace.go:171","msg":"trace[2081958576] linearizableReadLoop","detail":"{readStateIndex:2816; appliedIndex:2815; }","duration":"162.800729ms","start":"2026-04-13T21:09:54.75458Z","end":"2026-04-13T21:09:54.91738Z","steps":["trace[2081958576] 'read index received' (duration: 115.001028ms)","trace[2081958576] 'applied index is now lower than readState.Index' (duration: 47.799031ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:09:54.917423Z","caller":"traceutil/trace.go:171","msg":"trace[830460599] transaction","detail":"{read_only:false; response_revision:2718; number_of_response:1; }","duration":"162.932622ms","start":"2026-04-13T21:09:54.754457Z","end":"2026-04-13T21:09:54.917389Z","steps":["trace[830460599] 'process raft request' (duration: 115.12441ms)","trace[830460599] 'compare' (duration: 47.497138ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:09:54.917896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.283776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-13T21:09:54.917967Z","caller":"traceutil/trace.go:171","msg":"trace[1200839223] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2719; }","duration":"163.397227ms","start":"2026-04-13T21:09:54.754552Z","end":"2026-04-13T21:09:54.91795Z","steps":["trace[1200839223] 'agreement among raft nodes before linearized reading' (duration: 162.90468ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:54.918553Z","caller":"traceutil/trace.go:171","msg":"trace[1786962613] transaction","detail":"{read_only:false; response_revision:2719; number_of_response:1; }","duration":"163.793374ms","start":"2026-04-13T21:09:54.754749Z","end":"2026-04-13T21:09:54.918542Z","steps":["trace[1786962613] 'process raft request' (duration: 162.564917ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:54.918725Z","caller":"traceutil/trace.go:171","msg":"trace[1636462156] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"163.640672ms","start":"2026-04-13T21:09:54.755017Z","end":"2026-04-13T21:09:54.918657Z","steps":["trace[1636462156] 'process raft request' (duration: 163.588331ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:54.918823Z","caller":"traceutil/trace.go:171","msg":"trace[1611258493] transaction","detail":"{read_only:false; response_revision:2720; number_of_response:1; }","duration":"164.068507ms","start":"2026-04-13T21:09:54.754745Z","end":"2026-04-13T21:09:54.918814Z","steps":["trace[1611258493] 'process raft request' (duration: 163.717223ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:54.918987Z","caller":"traceutil/trace.go:171","msg":"trace[881265618] transaction","detail":"{read_only:false; response_revision:2721; number_of_response:1; }","duration":"164.20885ms","start":"2026-04-13T21:09:54.754771Z","end":"2026-04-13T21:09:54.91898Z","steps":["trace[881265618] 'process raft request' (duration: 163.787344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:09:55.766231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.602155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:09:55.766518Z","caller":"traceutil/trace.go:171","msg":"trace[1765064168] linearizableReadLoop","detail":"{readStateIndex:2822; appliedIndex:2820; }","duration":"274.39446ms","start":"2026-04-13T21:09:55.492109Z","end":"2026-04-13T21:09:55.766503Z","steps":["trace[1765064168] 'read index received' (duration: 64.148155ms)","trace[1765064168] 'applied index is now lower than readState.Index' (duration: 210.245325ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:09:55.766732Z","caller":"traceutil/trace.go:171","msg":"trace[1370683959] transaction","detail":"{read_only:false; response_revision:2724; number_of_response:1; }","duration":"509.591425ms","start":"2026-04-13T21:09:55.257128Z","end":"2026-04-13T21:09:55.766719Z","steps":["trace[1370683959] 'process raft request' (duration: 509.171199ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:55.766813Z","caller":"traceutil/trace.go:171","msg":"trace[1152231535] transaction","detail":"{read_only:false; response_revision:2723; number_of_response:1; }","duration":"764.720013ms","start":"2026-04-13T21:09:55.00206Z","end":"2026-04-13T21:09:55.76678Z","steps":["trace[1152231535] 'process raft request' (duration: 293.51513ms)","trace[1152231535] 'compare' (duration: 470.200519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:09:55.766965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:09:55.25709Z","time spent":"509.678296ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:09:55.767007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.881407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T21:09:55.76708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:09:55.002032Z","time spent":"764.898985ms","remote":"127.0.0.1:34642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:09:55.768591Z","caller":"traceutil/trace.go:171","msg":"trace[728371043] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2724; }","duration":"275.012338ms","start":"2026-04-13T21:09:55.492071Z","end":"2026-04-13T21:09:55.767084Z","steps":["trace[728371043] 'agreement among raft nodes before linearized reading' (duration: 274.723634ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:25.955342Z","caller":"traceutil/trace.go:171","msg":"trace[624926995] transaction","detail":"{read_only:false; response_revision:4428; number_of_response:1; }","duration":"122.398285ms","start":"2026-04-13T21:13:25.832926Z","end":"2026-04-13T21:13:25.955324Z","steps":["trace[624926995] 'process raft request' (duration: 122.280702ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:28.166417Z","caller":"traceutil/trace.go:171","msg":"trace[1611230501] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4460; }","duration":"138.083145ms","start":"2026-04-13T21:13:28.028302Z","end":"2026-04-13T21:13:28.166385Z","steps":["trace[1611230501] 'process raft request' (duration: 107.229955ms)","trace[1611230501] 'compare' (duration: 30.679936ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:28.166595Z","caller":"traceutil/trace.go:171","msg":"trace[1316664528] linearizableReadLoop","detail":"{readStateIndex:4628; appliedIndex:4626; }","duration":"136.57184ms","start":"2026-04-13T21:13:28.030001Z","end":"2026-04-13T21:13:28.166572Z","steps":["trace[1316664528] 'read index received' (duration: 105.604217ms)","trace[1316664528] 'applied index is now lower than readState.Index' (duration: 30.966673ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:28.166669Z","caller":"traceutil/trace.go:171","msg":"trace[428098918] transaction","detail":"{read_only:false; response_revision:4461; number_of_response:1; }","duration":"138.051396ms","start":"2026-04-13T21:13:28.028595Z","end":"2026-04-13T21:13:28.166646Z","steps":["trace[428098918] 'process raft request' (duration: 137.740198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:28.166752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.733144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-13T21:13:28.167303Z","caller":"traceutil/trace.go:171","msg":"trace[978305513] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4461; }","duration":"137.320768ms","start":"2026-04-13T21:13:28.029967Z","end":"2026-04-13T21:13:28.167288Z","steps":["trace[978305513] 'agreement among raft nodes before linearized reading' (duration: 136.678953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:28.167412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.586914ms","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-13T21:13:28.167472Z","caller":"traceutil/trace.go:171","msg":"trace[168095149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4461; }","duration":"129.672796ms","start":"2026-04-13T21:13:28.037783Z","end":"2026-04-13T21:13:28.167455Z","steps":["trace[168095149] 'agreement among raft nodes before linearized reading' (duration: 129.476921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:28.167654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.619536ms","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-13T21:13:28.167688Z","caller":"traceutil/trace.go:171","msg":"trace[1271746187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4461; }","duration":"121.678188ms","start":"2026-04-13T21:13:28.045999Z","end":"2026-04-13T21:13:28.167678Z","steps":["trace[1271746187] 'agreement among raft nodes before linearized reading' (duration: 121.582856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:37.451816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.774265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:13:37.452436Z","caller":"traceutil/trace.go:171","msg":"trace[1075151536] transaction","detail":"{read_only:false; response_revision:4610; number_of_response:1; }","duration":"504.078517ms","start":"2026-04-13T21:13:36.948327Z","end":"2026-04-13T21:13:37.452406Z","steps":["trace[1075151536] 'process raft request' (duration: 399.615076ms)","trace[1075151536] 'compare' (duration: 103.659593ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:37.452544Z","caller":"traceutil/trace.go:171","msg":"trace[655002066] transaction","detail":"{read_only:false; response_revision:4611; number_of_response:1; }","duration":"504.19472ms","start":"2026-04-13T21:13:36.94832Z","end":"2026-04-13T21:13:37.452514Z","steps":["trace[655002066] 'process raft request' (duration: 503.602307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:37.453239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:36.948297Z","time spent":"504.897027ms","remote":"127.0.0.1:34808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:13:37.453219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:36.948316Z","time spent":"504.759873ms","remote":"127.0.0.1:34716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:13:37.514097Z","caller":"traceutil/trace.go:171","msg":"trace[88696806] transaction","detail":"{read_only:false; response_revision:4612; number_of_response:1; }","duration":"565.690476ms","start":"2026-04-13T21:13:36.948385Z","end":"2026-04-13T21:13:37.514076Z","steps":["trace[88696806] 'process raft request' (duration: 565.500702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:37.514263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:36.948377Z","time spent":"565.792008ms","remote":"127.0.0.1:34808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:13:37.514597Z","caller":"traceutil/trace.go:171","msg":"trace[72089421] transaction","detail":"{read_only:false; response_revision:4613; number_of_response:1; }","duration":"565.01486ms","start":"2026-04-13T21:13:36.949562Z","end":"2026-04-13T21:13:37.514576Z","steps":["trace[72089421] 'process raft request' (duration: 564.450956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:37.514722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:36.949547Z","time spent":"565.098521ms","remote":"127.0.0.1:34716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:13:37.51515Z","caller":"traceutil/trace.go:171","msg":"trace[786524252] transaction","detail":"{read_only:false; response_revision:4614; number_of_response:1; }","duration":"560.817582ms","start":"2026-04-13T21:13:36.954323Z","end":"2026-04-13T21:13:37.51514Z","steps":["trace[786524252] 'process raft request' (duration: 560.081865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:37.515445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:36.954307Z","time spent":"560.863664ms","remote":"127.0.0.1:34736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16542,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:13:37.515565Z","caller":"traceutil/trace.go:171","msg":"trace[1770733047] linearizableReadLoop","detail":"{readStateIndex:4785; appliedIndex:4782; }","duration":"566.996525ms","start":"2026-04-13T21:13:36.948553Z","end":"2026-04-13T21:13:37.515549Z","steps":["trace[1770733047] 'read index received' (duration: 399.546904ms)","trace[1770733047] 'applied index is now lower than readState.Index' (duration: 167.448251ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:37.51564Z","caller":"traceutil/trace.go:171","msg":"trace[1955923003] transaction","detail":"{read_only:false; response_revision:4615; number_of_response:1; }","duration":"442.586279ms","start":"2026-04-13T21:13:37.073037Z","end":"2026-04-13T21:13:37.515623Z","steps":["trace[1955923003] 'process raft request' (duration: 441.878603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:37.515736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:37.073018Z","time spent":"442.672802ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:13:37.515795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.233541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-56ea91a5-8af0-43dc-83b2-be9262966771\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-13T21:13:37.51589Z","caller":"traceutil/trace.go:171","msg":"trace[2078757605] range","detail":"{range_begin:/registry/persistentvolumes/pvc-56ea91a5-8af0-43dc-83b2-be9262966771; range_end:; response_count:1; response_revision:4615; }","duration":"567.349954ms","start":"2026-04-13T21:13:36.948528Z","end":"2026-04-13T21:13:37.515878Z","steps":["trace[2078757605] 'agreement among raft nodes before linearized reading' (duration: 567.142419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:37.515929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:36.948521Z","time spent":"567.397495ms","remote":"127.0.0.1:34710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1457,"request content":"key:\"/registry/persistentvolumes/pvc-56ea91a5-8af0-43dc-83b2-be9262966771\" "} {"level":"info","ts":"2026-04-13T21:13:37.524654Z","caller":"traceutil/trace.go:171","msg":"trace[1821184944] transaction","detail":"{read_only:false; response_revision:4616; number_of_response:1; }","duration":"171.288289ms","start":"2026-04-13T21:13:37.353339Z","end":"2026-04-13T21:13:37.524627Z","steps":["trace[1821184944] 'process raft request' (duration: 171.118756ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:45.178033Z","caller":"traceutil/trace.go:171","msg":"trace[1428429842] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"210.96446ms","start":"2026-04-13T21:13:44.967047Z","end":"2026-04-13T21:13:45.178011Z","steps":["trace[1428429842] 'process raft request' (duration: 210.816846ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:45.190601Z","caller":"traceutil/trace.go:171","msg":"trace[1997342788] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"162.302749ms","start":"2026-04-13T21:13:45.028269Z","end":"2026-04-13T21:13:45.190572Z","steps":["trace[1997342788] 'process raft request' (duration: 161.960951ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:45.335226Z","caller":"traceutil/trace.go:171","msg":"trace[234865519] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"109.157718ms","start":"2026-04-13T21:13:45.226042Z","end":"2026-04-13T21:13:45.3352Z","steps":["trace[234865519] 'process raft request' (duration: 108.921213ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:34.67215Z","caller":"traceutil/trace.go:171","msg":"trace[1854601505] linearizableReadLoop","detail":"{readStateIndex:5397; appliedIndex:5396; }","duration":"135.732763ms","start":"2026-04-13T21:14:34.536402Z","end":"2026-04-13T21:14:34.672135Z","steps":["trace[1854601505] 'read index received' (duration: 135.57998ms)","trace[1854601505] 'applied index is now lower than readState.Index' (duration: 151.913µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:14:34.672314Z","caller":"traceutil/trace.go:171","msg":"trace[1004674138] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"140.183416ms","start":"2026-04-13T21:14:34.532064Z","end":"2026-04-13T21:14:34.672248Z","steps":["trace[1004674138] 'process raft request' (duration: 139.938641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:34.672459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.003158ms","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-13T21:14:34.672504Z","caller":"traceutil/trace.go:171","msg":"trace[1867402743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5207; }","duration":"136.068601ms","start":"2026-04-13T21:14:34.536425Z","end":"2026-04-13T21:14:34.672494Z","steps":["trace[1867402743] 'agreement among raft nodes before linearized reading' (duration: 135.941617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:34.672678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.227142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-04-13T21:14:34.672661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.696888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:14:34.672718Z","caller":"traceutil/trace.go:171","msg":"trace[456820650] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5207; }","duration":"135.279263ms","start":"2026-04-13T21:14:34.537429Z","end":"2026-04-13T21:14:34.672708Z","steps":["trace[456820650] 'agreement among raft nodes before linearized reading' (duration: 135.164181ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:34.672752Z","caller":"traceutil/trace.go:171","msg":"trace[23062159] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:5207; }","duration":"130.81963ms","start":"2026-04-13T21:14:34.541909Z","end":"2026-04-13T21:14:34.672729Z","steps":["trace[23062159] 'agreement among raft nodes before linearized reading' (duration: 130.634887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:34.672764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.353906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-13T21:14:34.672831Z","caller":"traceutil/trace.go:171","msg":"trace[1166970430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5207; }","duration":"136.409337ms","start":"2026-04-13T21:14:34.536386Z","end":"2026-04-13T21:14:34.672795Z","steps":["trace[1166970430] 'agreement among raft nodes before linearized reading' (duration: 136.212283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:34.672843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.665368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T21:14:34.672872Z","caller":"traceutil/trace.go:171","msg":"trace[1834450522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5207; }","duration":"132.714389ms","start":"2026-04-13T21:14:34.540149Z","end":"2026-04-13T21:14:34.672864Z","steps":["trace[1834450522] 'agreement among raft nodes before linearized reading' (duration: 132.620587ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:15:30.577706Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2907} {"level":"info","ts":"2026-04-13T21:15:30.653769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2907,"took":"73.822323ms","hash":3499386508,"current-db-size-bytes":53063680,"current-db-size":"53 MB","current-db-size-in-use-bytes":29474816,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-13T21:15:30.653828Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3499386508,"revision":2907,"compact-revision":-1} {"level":"info","ts":"2026-04-13T21:20:30.584318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5630} {"level":"info","ts":"2026-04-13T21:20:30.658407Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5630,"took":"72.881748ms","hash":2680311548,"current-db-size-bytes":53063680,"current-db-size":"53 MB","current-db-size-in-use-bytes":18358272,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-13T21:20:30.658464Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2680311548,"revision":5630,"compact-revision":2907}