{"level":"warn","ts":"2026-06-05T04:32:16.834632Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T04:32:16.834738Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-06-05T04:32:16.834807Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T04:32:16.834819Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-06-05T04:32:16.834834Z","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-06-05T04:32:16.835424Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-06-05T04:32:16.835493Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-06-05T04:32:16.84075Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.056931ms"} {"level":"info","ts":"2026-06-05T04:32:16.852779Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-06-05T04:32:16.852921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-06-05T04:32:16.852961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-06-05T04:32:16.85298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-05T04:32:16.852989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-06-05T04:32:16.853055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-06-05T04:32:16.86222Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-05T04:32:16.86755Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-05T04:32:16.869617Z","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-06-05T04:32:16.872847Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-05T04:32:16.873119Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-05T04:32:16.875046Z","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-06-05T04:32:16.875191Z","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-06-05T04:32:16.875238Z","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-06-05T04:32:16.876252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-06-05T04:32:16.87686Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-06-05T04:32:16.878948Z","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-06-05T04:32:16.879117Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-06-05T04:32:16.879244Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-06-05T04:32:16.879404Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-05T04:32:16.879484Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-05T04:32:17.35337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-06-05T04:32:17.353416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-05T04:32:17.353445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-06-05T04:32:17.353457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-06-05T04:32:17.353462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-06-05T04:32:17.353474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-06-05T04:32:17.353482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-06-05T04:32:17.354967Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-06-05T04:32:17.355033Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T04:32:17.355144Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T04:32:17.355583Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T04:32:17.355765Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-05T04:32:17.355784Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-05T04:32:17.356166Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T04:32:17.356299Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T04:32:17.356334Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T04:32:17.357479Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-06-05T04:32:17.358914Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-05T04:32:19.195106Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521226639054984569,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T04:32:19.404807Z","caller":"traceutil/trace.go:171","msg":"trace[1989351180] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"721.806859ms","start":"2026-06-05T04:32:18.682958Z","end":"2026-06-05T04:32:19.404764Z","steps":["trace[1989351180] 'process raft request' (duration: 721.501156ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:32:19.405742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.682938Z","time spent":"722.010101ms","remote":"127.0.0.1:53458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:32:19.612459Z","caller":"traceutil/trace.go:171","msg":"trace[1700992626] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"929.284386ms","start":"2026-06-05T04:32:18.683108Z","end":"2026-06-05T04:32:19.612392Z","steps":["trace[1700992626] 'process raft request' (duration: 928.873922ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:32:19.612583Z","caller":"traceutil/trace.go:171","msg":"trace[1588111986] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"865.804871ms","start":"2026-06-05T04:32:18.746764Z","end":"2026-06-05T04:32:19.612569Z","steps":["trace[1588111986] 'process raft request' (duration: 865.514609ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:32:19.612743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.683071Z","time spent":"929.536309ms","remote":"127.0.0.1:53450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T04:32:19.612822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.746755Z","time spent":"865.874012ms","remote":"127.0.0.1:53964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T04:32:19.612512Z","caller":"traceutil/trace.go:171","msg":"trace[701827163] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"865.884483ms","start":"2026-06-05T04:32:18.746584Z","end":"2026-06-05T04:32:19.612469Z","steps":["trace[701827163] 'process raft request' (duration: 865.66058ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:32:19.612506Z","caller":"traceutil/trace.go:171","msg":"trace[750021225] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"865.463299ms","start":"2026-06-05T04:32:18.747013Z","end":"2026-06-05T04:32:19.612476Z","steps":["trace[750021225] 'process raft request' (duration: 865.320647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:32:19.613133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.747003Z","time spent":"866.100004ms","remote":"127.0.0.1:53964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T04:32:19.613091Z","caller":"traceutil/trace.go:171","msg":"trace[827760333] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"849.458481ms","start":"2026-06-05T04:32:18.763565Z","end":"2026-06-05T04:32:19.613024Z","steps":["trace[827760333] 'process raft request' (duration: 848.799406ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:32:19.613215Z","caller":"traceutil/trace.go:171","msg":"trace[1761042059] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"866.296436ms","start":"2026-06-05T04:32:18.7469Z","end":"2026-06-05T04:32:19.613197Z","steps":["trace[1761042059] 'process raft request' (duration: 865.411928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:32:19.613266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.74688Z","time spent":"866.361277ms","remote":"127.0.0.1:53964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T04:32:19.612846Z","caller":"traceutil/trace.go:171","msg":"trace[1467743112] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"866.359628ms","start":"2026-06-05T04:32:18.746474Z","end":"2026-06-05T04:32:19.612833Z","steps":["trace[1467743112] 'process raft request' (duration: 865.726982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:32:19.61337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.763543Z","time spent":"849.667254ms","remote":"127.0.0.1:53574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T04:32:19.613407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.746443Z","time spent":"866.936584ms","remote":"127.0.0.1:53964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T04:32:19.613146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.74657Z","time spent":"866.512808ms","remote":"127.0.0.1:53964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T04:32:19.61253Z","caller":"traceutil/trace.go:171","msg":"trace[1681271747] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"866.185527ms","start":"2026-06-05T04:32:18.746301Z","end":"2026-06-05T04:32:19.612486Z","steps":["trace[1681271747] 'process raft request' (duration: 865.791223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:32:19.613545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.746277Z","time spent":"867.244427ms","remote":"127.0.0.1:53856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T04:32:19.613954Z","caller":"traceutil/trace.go:171","msg":"trace[961280480] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:4; }","duration":"918.970684ms","start":"2026-06-05T04:32:18.694963Z","end":"2026-06-05T04:32:19.613933Z","steps":["trace[961280480] 'read index received' (duration: 709.569367ms)","trace[961280480] 'applied index is now lower than readState.Index' (duration: 209.400347ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:32:19.613996Z","caller":"traceutil/trace.go:171","msg":"trace[264706004] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"845.810636ms","start":"2026-06-05T04:32:18.768166Z","end":"2026-06-05T04:32:19.613977Z","steps":["trace[264706004] 'process raft request' (duration: 844.232471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:32:19.614075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.768142Z","time spent":"845.890007ms","remote":"127.0.0.1:53642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T04:32:19.614215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.248276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-05T04:32:19.614292Z","caller":"traceutil/trace.go:171","msg":"trace[1296386021] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"919.382618ms","start":"2026-06-05T04:32:18.694899Z","end":"2026-06-05T04:32:19.614282Z","steps":["trace[1296386021] 'agreement among raft nodes before linearized reading' (duration: 919.108605ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:32:19.61435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.547658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-05T04:32:19.614405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.268267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-05T04:32:19.614466Z","caller":"traceutil/trace.go:171","msg":"trace[466359664] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"863.362177ms","start":"2026-06-05T04:32:18.751085Z","end":"2026-06-05T04:32:19.614447Z","steps":["trace[466359664] 'agreement among raft nodes before linearized reading' (duration: 863.247376ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:32:19.614506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.751076Z","time spent":"863.423338ms","remote":"127.0.0.1:53478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-06-05T04:32:19.614518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.173504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-05T04:32:19.614549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.694879Z","time spent":"919.60544ms","remote":"127.0.0.1:53794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-06-05T04:32:19.614566Z","caller":"traceutil/trace.go:171","msg":"trace[1033955009] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:0; response_revision:11; }","duration":"873.266894ms","start":"2026-06-05T04:32:18.741287Z","end":"2026-06-05T04:32:19.614554Z","steps":["trace[1033955009] 'agreement among raft nodes before linearized reading' (duration: 873.173403ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:32:19.614648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.741268Z","time spent":"873.372927ms","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.204.45.223\" "} {"level":"warn","ts":"2026-06-05T04:32:19.614685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.967728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-05T04:32:19.614807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.961341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-05T04:32:19.614874Z","caller":"traceutil/trace.go:171","msg":"trace[641836959] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"878.045392ms","start":"2026-06-05T04:32:18.736819Z","end":"2026-06-05T04:32:19.614864Z","steps":["trace[641836959] 'agreement among raft nodes before linearized reading' (duration: 877.956331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:32:19.615053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.73681Z","time spent":"878.229124ms","remote":"127.0.0.1:53508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-05T04:32:19.614961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.959374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-05T04:32:19.615582Z","caller":"traceutil/trace.go:171","msg":"trace[1452178924] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"816.614659ms","start":"2026-06-05T04:32:18.798952Z","end":"2026-06-05T04:32:19.615567Z","steps":["trace[1452178924] 'agreement among raft nodes before linearized reading' (duration: 815.988104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:32:19.615721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.798936Z","time spent":"816.768731ms","remote":"127.0.0.1:53508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-05T04:32:19.614813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.083265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-05T04:32:19.615934Z","caller":"traceutil/trace.go:171","msg":"trace[66551620] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"872.240025ms","start":"2026-06-05T04:32:18.743681Z","end":"2026-06-05T04:32:19.615921Z","steps":["trace[66551620] 'agreement among raft nodes before linearized reading' (duration: 871.109304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:32:19.61607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.743629Z","time spent":"872.405418ms","remote":"127.0.0.1:53508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-06-05T04:32:19.614594Z","caller":"traceutil/trace.go:171","msg":"trace[1054620128] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"877.761839ms","start":"2026-06-05T04:32:18.736673Z","end":"2026-06-05T04:32:19.614435Z","steps":["trace[1054620128] 'agreement among raft nodes before linearized reading' (duration: 877.538637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:32:19.616253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.736645Z","time spent":"879.572818ms","remote":"127.0.0.1:53508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-06-05T04:32:19.6169Z","caller":"traceutil/trace.go:171","msg":"trace[1388146843] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"795.209929ms","start":"2026-06-05T04:32:18.821674Z","end":"2026-06-05T04:32:19.616884Z","steps":["trace[1388146843] 'agreement among raft nodes before linearized reading' (duration: 792.982418ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:32:19.616937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:32:18.821632Z","time spent":"795.296321ms","remote":"127.0.0.1:53508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-06-05T04:32:24.68072Z","caller":"traceutil/trace.go:171","msg":"trace[1986856605] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"109.437942ms","start":"2026-06-05T04:32:24.571252Z","end":"2026-06-05T04:32:24.68069Z","steps":["trace[1986856605] 'process raft request' (duration: 100.217479ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:32:59.761951Z","caller":"traceutil/trace.go:171","msg":"trace[264066815] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"106.330407ms","start":"2026-06-05T04:32:59.655586Z","end":"2026-06-05T04:32:59.761916Z","steps":["trace[264066815] 'process raft request' (duration: 106.120606ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:32:59.820205Z","caller":"traceutil/trace.go:171","msg":"trace[1142419545] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"163.881738ms","start":"2026-06-05T04:32:59.656262Z","end":"2026-06-05T04:32:59.820143Z","steps":["trace[1142419545] 'process raft request' (duration: 163.639607ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:32:59.820255Z","caller":"traceutil/trace.go:171","msg":"trace[1809370405] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"156.211279ms","start":"2026-06-05T04:32:59.663995Z","end":"2026-06-05T04:32:59.820206Z","steps":["trace[1809370405] 'process raft request' (duration: 156.028438ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:32:59.820447Z","caller":"traceutil/trace.go:171","msg":"trace[607211249] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"138.76068ms","start":"2026-06-05T04:32:59.681674Z","end":"2026-06-05T04:32:59.820435Z","steps":["trace[607211249] 'process raft request' (duration: 138.489658ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:32:59.820511Z","caller":"traceutil/trace.go:171","msg":"trace[1859732007] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"149.994382ms","start":"2026-06-05T04:32:59.6705Z","end":"2026-06-05T04:32:59.820494Z","steps":["trace[1859732007] 'process raft request' (duration: 149.569018ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:32:59.820486Z","caller":"traceutil/trace.go:171","msg":"trace[457529189] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:552; }","duration":"138.62314ms","start":"2026-06-05T04:32:59.681838Z","end":"2026-06-05T04:32:59.820461Z","steps":["trace[457529189] 'read index received' (duration: 79.905701ms)","trace[457529189] 'applied index is now lower than readState.Index' (duration: 58.716399ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:32:59.820625Z","caller":"traceutil/trace.go:171","msg":"trace[1277725843] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"111.381308ms","start":"2026-06-05T04:32:59.709231Z","end":"2026-06-05T04:32:59.820612Z","steps":["trace[1277725843] 'process raft request' (duration: 111.081196ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:32:59.820676Z","caller":"traceutil/trace.go:171","msg":"trace[1122763610] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"150.062041ms","start":"2026-06-05T04:32:59.670602Z","end":"2026-06-05T04:32:59.820664Z","steps":["trace[1122763610] 'process raft request' (duration: 149.510937ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:32:59.820717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.855951ms","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-06-05T04:32:59.820725Z","caller":"traceutil/trace.go:171","msg":"trace[1641782033] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"102.536703ms","start":"2026-06-05T04:32:59.718166Z","end":"2026-06-05T04:32:59.820703Z","steps":["trace[1641782033] 'process raft request' (duration: 102.18881ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:32:59.820755Z","caller":"traceutil/trace.go:171","msg":"trace[2105566886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:544; }","duration":"138.950581ms","start":"2026-06-05T04:32:59.681793Z","end":"2026-06-05T04:32:59.820744Z","steps":["trace[2105566886] 'agreement among raft nodes before linearized reading' (duration: 138.7733ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:32:59.820794Z","caller":"traceutil/trace.go:171","msg":"trace[1561716731] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"124.770722ms","start":"2026-06-05T04:32:59.696013Z","end":"2026-06-05T04:32:59.820783Z","steps":["trace[1561716731] 'process raft request' (duration: 124.185648ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:32:59.820873Z","caller":"traceutil/trace.go:171","msg":"trace[1734869405] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"114.975821ms","start":"2026-06-05T04:32:59.705889Z","end":"2026-06-05T04:32:59.820865Z","steps":["trace[1734869405] 'process raft request' (duration: 114.372607ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:33:06.125431Z","caller":"traceutil/trace.go:171","msg":"trace[761086781] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"221.252153ms","start":"2026-06-05T04:33:05.90413Z","end":"2026-06-05T04:33:06.125382Z","steps":["trace[761086781] 'process raft request' (duration: 218.378695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:33:06.495588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.615857ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-05T04:33:06.495923Z","caller":"traceutil/trace.go:171","msg":"trace[1134904668] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:661; }","duration":"568.229017ms","start":"2026-06-05T04:33:05.927631Z","end":"2026-06-05T04:33:06.49586Z","steps":["trace[1134904668] 'read index received' (duration: 194.025939ms)","trace[1134904668] 'applied index is now lower than readState.Index' (duration: 374.201408ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:33:06.496174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.52358ms","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-06-05T04:33:06.496206Z","caller":"traceutil/trace.go:171","msg":"trace[1085530741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"568.59593ms","start":"2026-06-05T04:33:05.927598Z","end":"2026-06-05T04:33:06.496194Z","steps":["trace[1085530741] 'agreement among raft nodes before linearized reading' (duration: 568.425648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:33:06.496247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:33:05.927583Z","time spent":"568.65416ms","remote":"127.0.0.1:53644","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-06-05T04:33:06.496286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.579093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T04:33:06.496339Z","caller":"traceutil/trace.go:171","msg":"trace[1893097068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"118.699434ms","start":"2026-06-05T04:33:06.377622Z","end":"2026-06-05T04:33:06.496322Z","steps":["trace[1893097068] 'agreement among raft nodes before linearized reading' (duration: 118.545303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:33:06.496408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.512975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-c5qj2\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-05T04:33:06.4965Z","caller":"traceutil/trace.go:171","msg":"trace[1025530433] range","detail":"{range_begin:/registry/pods/kube-system/cilium-c5qj2; range_end:; response_count:1; response_revision:640; }","duration":"388.699507ms","start":"2026-06-05T04:33:06.107778Z","end":"2026-06-05T04:33:06.496477Z","steps":["trace[1025530433] 'agreement among raft nodes before linearized reading' (duration: 388.357515ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:33:06.496468Z","caller":"traceutil/trace.go:171","msg":"trace[1312920747] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"314.579781ms","start":"2026-06-05T04:33:06.181824Z","end":"2026-06-05T04:33:06.496404Z","steps":["trace[1312920747] 'process raft request' (duration: 313.971027ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:33:06.49657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:33:06.107749Z","time spent":"388.800527ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-c5qj2\" "} {"level":"warn","ts":"2026-06-05T04:33:06.496673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:33:06.181802Z","time spent":"314.757202ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T04:33:06.496702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.009553ms","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-06-05T04:33:06.496741Z","caller":"traceutil/trace.go:171","msg":"trace[1201234579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"230.093993ms","start":"2026-06-05T04:33:06.266635Z","end":"2026-06-05T04:33:06.496729Z","steps":["trace[1201234579] 'agreement among raft nodes before linearized reading' (duration: 229.975842ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:33:07.510095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.061528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T04:33:07.510291Z","caller":"traceutil/trace.go:171","msg":"trace[1912254331] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"1.001279419s","start":"2026-06-05T04:33:06.508994Z","end":"2026-06-05T04:33:07.510274Z","steps":["trace[1912254331] 'read index received' (duration: 7.441974ms)","trace[1912254331] 'applied index is now lower than readState.Index' (duration: 993.836495ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:33:07.510413Z","caller":"traceutil/trace.go:171","msg":"trace[1308245687] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"998.295271ms","start":"2026-06-05T04:33:06.512021Z","end":"2026-06-05T04:33:07.510316Z","steps":["trace[1308245687] 'process raft request' (duration: 998.18359ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:33:07.510556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00152695s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T04:33:07.510849Z","caller":"traceutil/trace.go:171","msg":"trace[1220807370] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:643; }","duration":"1.001879252s","start":"2026-06-05T04:33:06.508946Z","end":"2026-06-05T04:33:07.510826Z","steps":["trace[1220807370] 'agreement among raft nodes before linearized reading' (duration: 1.00143127s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:33:07.510875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.866393ms","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-06-05T04:33:07.510896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:33:06.50892Z","time spent":"1.001966613s","remote":"127.0.0.1:53944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"info","ts":"2026-06-05T04:33:07.510916Z","caller":"traceutil/trace.go:171","msg":"trace[639317142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:643; }","duration":"758.965993ms","start":"2026-06-05T04:33:06.751938Z","end":"2026-06-05T04:33:07.510904Z","steps":["trace[639317142] 'agreement among raft nodes before linearized reading' (duration: 758.46492ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:33:07.510922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:33:06.511996Z","time spent":"998.602132ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T04:33:07.510928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.821454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-05T04:33:07.510953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:33:06.751886Z","time spent":"759.058164ms","remote":"127.0.0.1:53644","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-06-05T04:33:07.510998Z","caller":"traceutil/trace.go:171","msg":"trace[1234251207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"164.932514ms","start":"2026-06-05T04:33:07.346039Z","end":"2026-06-05T04:33:07.510972Z","steps":["trace[1234251207] 'agreement among raft nodes before linearized reading' (duration: 164.485732ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:33:07.51107Z","caller":"traceutil/trace.go:171","msg":"trace[1633839245] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.006481991s","start":"2026-06-05T04:33:06.503797Z","end":"2026-06-05T04:33:07.510279Z","steps":["trace[1633839245] 'process raft request' (duration: 55.129321ms)","trace[1633839245] 'compare' (duration: 950.900517ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:33:07.511332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:33:06.503767Z","time spent":"1.007403415s","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:33:23.531013Z","caller":"traceutil/trace.go:171","msg":"trace[1340442219] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"350.966842ms","start":"2026-06-05T04:33:23.180021Z","end":"2026-06-05T04:33:23.530988Z","steps":["trace[1340442219] 'read index received' (duration: 350.837941ms)","trace[1340442219] 'applied index is now lower than readState.Index' (duration: 127.561µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:33:23.531093Z","caller":"traceutil/trace.go:171","msg":"trace[1679060854] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"474.983781ms","start":"2026-06-05T04:33:23.056076Z","end":"2026-06-05T04:33:23.53106Z","steps":["trace[1679060854] 'process raft request' (duration: 474.76423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:33:23.531176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.138812ms","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-06-05T04:33:23.531214Z","caller":"traceutil/trace.go:171","msg":"trace[922731187] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:810; }","duration":"351.243353ms","start":"2026-06-05T04:33:23.179961Z","end":"2026-06-05T04:33:23.531205Z","steps":["trace[922731187] 'agreement among raft nodes before linearized reading' (duration: 351.152042ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:33:23.531344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:33:23.179935Z","time spent":"351.395584ms","remote":"127.0.0.1:53826","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":"warn","ts":"2026-06-05T04:33:23.531562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:33:23.056054Z","time spent":"475.127291ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:33:23.694339Z","caller":"traceutil/trace.go:171","msg":"trace[1100600042] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"163.230039ms","start":"2026-06-05T04:33:23.531092Z","end":"2026-06-05T04:33:23.694322Z","steps":["trace[1100600042] 'read index received' (duration: 163.061038ms)","trace[1100600042] 'applied index is now lower than readState.Index' (duration: 168.321µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:33:23.694417Z","caller":"traceutil/trace.go:171","msg":"trace[927553006] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"276.8115ms","start":"2026-06-05T04:33:23.417564Z","end":"2026-06-05T04:33:23.694375Z","steps":["trace[927553006] 'process raft request' (duration: 276.646109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:33:23.694469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.802463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T04:33:23.694631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.826241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q85zs\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-06-05T04:33:23.694632Z","caller":"traceutil/trace.go:171","msg":"trace[1166057783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:811; }","duration":"319.003634ms","start":"2026-06-05T04:33:23.375612Z","end":"2026-06-05T04:33:23.694616Z","steps":["trace[1166057783] 'agreement among raft nodes before linearized reading' (duration: 318.786153ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:33:23.694662Z","caller":"traceutil/trace.go:171","msg":"trace[1783133117] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q85zs; range_end:; response_count:1; response_revision:811; }","duration":"465.936461ms","start":"2026-06-05T04:33:23.228716Z","end":"2026-06-05T04:33:23.694653Z","steps":["trace[1783133117] 'agreement among raft nodes before linearized reading' (duration: 465.7082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:33:23.694683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:33:23.375598Z","time spent":"319.072185ms","remote":"127.0.0.1:52052","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-06-05T04:33:23.69469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:33:23.228688Z","time spent":"465.997063ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q85zs\" "} {"level":"warn","ts":"2026-06-05T04:33:53.175335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.176885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T04:33:53.17552Z","caller":"traceutil/trace.go:171","msg":"trace[734737835] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1206; }","duration":"197.440491ms","start":"2026-06-05T04:33:52.978057Z","end":"2026-06-05T04:33:53.175498Z","steps":["trace[734737835] 'count revisions from in-memory index tree' (duration: 197.083762ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:34:11.725644Z","caller":"traceutil/trace.go:171","msg":"trace[1003532339] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"102.524793ms","start":"2026-06-05T04:34:11.623061Z","end":"2026-06-05T04:34:11.725586Z","steps":["trace[1003532339] 'process raft request' (duration: 102.228007ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:34:18.799761Z","caller":"traceutil/trace.go:171","msg":"trace[469382675] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"197.042621ms","start":"2026-06-05T04:34:18.602688Z","end":"2026-06-05T04:34:18.799731Z","steps":["trace[469382675] 'process raft request' (duration: 196.869758ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:34:25.766824Z","caller":"traceutil/trace.go:171","msg":"trace[690234130] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1579; }","duration":"134.501863ms","start":"2026-06-05T04:34:25.632298Z","end":"2026-06-05T04:34:25.7668Z","steps":["trace[690234130] 'process raft request' (duration: 134.410492ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:34:26.129841Z","caller":"traceutil/trace.go:171","msg":"trace[1788746466] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1582; }","duration":"101.720772ms","start":"2026-06-05T04:34:26.028082Z","end":"2026-06-05T04:34:26.129803Z","steps":["trace[1788746466] 'process raft request' (duration: 87.577205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:34:46.442914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.649383ms","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-06-05T04:34:46.443015Z","caller":"traceutil/trace.go:171","msg":"trace[334169036] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1905; }","duration":"421.816086ms","start":"2026-06-05T04:34:46.021172Z","end":"2026-06-05T04:34:46.442988Z","steps":["trace[334169036] 'range keys from in-memory index tree' (duration: 421.333049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:34:46.443061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:34:46.021089Z","time spent":"421.962237ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-05T04:34:46.443211Z","caller":"traceutil/trace.go:171","msg":"trace[2123664483] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"134.886946ms","start":"2026-06-05T04:34:46.308305Z","end":"2026-06-05T04:34:46.443192Z","steps":["trace[2123664483] 'process raft request' (duration: 134.833316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:34:46.443454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.966516ms","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-06-05T04:34:46.443487Z","caller":"traceutil/trace.go:171","msg":"trace[304560915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1905; }","duration":"276.044546ms","start":"2026-06-05T04:34:46.167431Z","end":"2026-06-05T04:34:46.443476Z","steps":["trace[304560915] 'range keys from in-memory index tree' (duration: 275.167493ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:34:46.443524Z","caller":"traceutil/trace.go:171","msg":"trace[1312834827] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1979; }","duration":"201.065022ms","start":"2026-06-05T04:34:46.242436Z","end":"2026-06-05T04:34:46.443501Z","steps":["trace[1312834827] 'read index received' (duration: 124.539553ms)","trace[1312834827] 'applied index is now lower than readState.Index' (duration: 76.522939ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:34:46.44381Z","caller":"traceutil/trace.go:171","msg":"trace[894385489] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"228.50518ms","start":"2026-06-05T04:34:46.21529Z","end":"2026-06-05T04:34:46.443795Z","steps":["trace[894385489] 'process raft request' (duration: 151.597755ms)","trace[894385489] 'compare' (duration: 76.049452ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:34:46.443981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.553168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-05T04:34:46.444021Z","caller":"traceutil/trace.go:171","msg":"trace[1236377008] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1907; }","duration":"201.64802ms","start":"2026-06-05T04:34:46.24236Z","end":"2026-06-05T04:34:46.444008Z","steps":["trace[1236377008] 'agreement among raft nodes before linearized reading' (duration: 201.538998ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:34:46.444192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.220753ms","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-06-05T04:34:46.444318Z","caller":"traceutil/trace.go:171","msg":"trace[1077594265] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1905; }","duration":"250.389826ms","start":"2026-06-05T04:34:46.193903Z","end":"2026-06-05T04:34:46.444293Z","steps":["trace[1077594265] 'range keys from in-memory index tree' (duration: 248.770212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:34:46.444287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.459435ms","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-06-05T04:34:46.444391Z","caller":"traceutil/trace.go:171","msg":"trace[1155020841] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1907; }","duration":"129.615398ms","start":"2026-06-05T04:34:46.314749Z","end":"2026-06-05T04:34:46.444364Z","steps":["trace[1155020841] 'agreement among raft nodes before linearized reading' (duration: 129.325824ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:34:46.981006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.212995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T04:34:46.981463Z","caller":"traceutil/trace.go:171","msg":"trace[85766093] linearizableReadLoop","detail":"{readStateIndex:1985; appliedIndex:1982; }","duration":"267.705262ms","start":"2026-06-05T04:34:46.713731Z","end":"2026-06-05T04:34:46.981436Z","steps":["trace[85766093] 'read index received' (duration: 45.171µs)","trace[85766093] 'applied index is now lower than readState.Index' (duration: 267.658511ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:34:46.981607Z","caller":"traceutil/trace.go:171","msg":"trace[333683646] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"530.788947ms","start":"2026-06-05T04:34:46.450804Z","end":"2026-06-05T04:34:46.981593Z","steps":["trace[333683646] 'process raft request' (duration: 530.515123ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:34:46.9816Z","caller":"traceutil/trace.go:171","msg":"trace[779634117] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"530.827987ms","start":"2026-06-05T04:34:46.450737Z","end":"2026-06-05T04:34:46.981565Z","steps":["trace[779634117] 'process raft request' (duration: 530.513523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:34:46.981748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:34:46.450773Z","time spent":"530.899578ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T04:34:46.981825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:34:46.450709Z","time spent":"530.988139ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:34:46.981733Z","caller":"traceutil/trace.go:171","msg":"trace[612974366] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"531.995415ms","start":"2026-06-05T04:34:46.44971Z","end":"2026-06-05T04:34:46.981705Z","steps":["trace[612974366] 'process raft request' (duration: 109.938337ms)","trace[612974366] 'compare' (duration: 421.086544ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:34:46.982203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.447613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f222b622-b35e-44cc-ab89-06bdc4daf89f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-05T04:34:46.982282Z","caller":"traceutil/trace.go:171","msg":"trace[1708793414] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f222b622-b35e-44cc-ab89-06bdc4daf89f; range_end:; response_count:1; response_revision:1911; }","duration":"268.584295ms","start":"2026-06-05T04:34:46.713677Z","end":"2026-06-05T04:34:46.982261Z","steps":["trace[1708793414] 'agreement among raft nodes before linearized reading' (duration: 268.070578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:34:46.982315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:34:46.449696Z","time spent":"532.563793ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T04:34:46.995231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.438467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f222b622-b35e-44cc-ab89-06bdc4daf89f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-05T04:34:46.995301Z","caller":"traceutil/trace.go:171","msg":"trace[643804163] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f222b622-b35e-44cc-ab89-06bdc4daf89f; range_end:; response_count:1; response_revision:1913; }","duration":"281.545579ms","start":"2026-06-05T04:34:46.713723Z","end":"2026-06-05T04:34:46.995268Z","steps":["trace[643804163] 'agreement among raft nodes before linearized reading' (duration: 281.350956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:34:46.995556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.914178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-05T04:34:46.995601Z","caller":"traceutil/trace.go:171","msg":"trace[1478298130] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1913; }","duration":"179.979658ms","start":"2026-06-05T04:34:46.815608Z","end":"2026-06-05T04:34:46.995587Z","steps":["trace[1478298130] 'agreement among raft nodes before linearized reading' (duration: 179.830606ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:34:59.97931Z","caller":"traceutil/trace.go:171","msg":"trace[2088893541] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"508.498049ms","start":"2026-06-05T04:34:59.470786Z","end":"2026-06-05T04:34:59.979284Z","steps":["trace[2088893541] 'process raft request' (duration: 508.294287ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:34:59.979494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:34:59.47075Z","time spent":"508.640151ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5915,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:35:00.416575Z","caller":"traceutil/trace.go:171","msg":"trace[1829233058] linearizableReadLoop","detail":"{readStateIndex:2098; appliedIndex:2096; }","duration":"865.089186ms","start":"2026-06-05T04:34:59.551452Z","end":"2026-06-05T04:35:00.416542Z","steps":["trace[1829233058] 'read index received' (duration: 427.698169ms)","trace[1829233058] 'applied index is now lower than readState.Index' (duration: 437.389417ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:35:00.416753Z","caller":"traceutil/trace.go:171","msg":"trace[769214871] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"902.596616ms","start":"2026-06-05T04:34:59.514136Z","end":"2026-06-05T04:35:00.416733Z","steps":["trace[769214871] 'process raft request' (duration: 896.586854ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:35:00.41714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.622992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-05T04:35:00.417259Z","caller":"traceutil/trace.go:171","msg":"trace[962027920] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2021; }","duration":"865.846476ms","start":"2026-06-05T04:34:59.551385Z","end":"2026-06-05T04:35:00.417231Z","steps":["trace[962027920] 'agreement among raft nodes before linearized reading' (duration: 865.470191ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:35:00.417259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:34:59.514108Z","time spent":"902.964471ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T04:35:00.417318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:34:59.551364Z","time spent":"865.943957ms","remote":"127.0.0.1:44226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-05T04:35:00.461416Z","caller":"traceutil/trace.go:171","msg":"trace[1408541169] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"474.927703ms","start":"2026-06-05T04:34:59.986459Z","end":"2026-06-05T04:35:00.461387Z","steps":["trace[1408541169] 'process raft request' (duration: 474.633048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:35:00.461628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:34:59.986425Z","time spent":"475.086015ms","remote":"127.0.0.1:53702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1754,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:35:00.461999Z","caller":"traceutil/trace.go:171","msg":"trace[1659887906] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"474.879401ms","start":"2026-06-05T04:34:59.987101Z","end":"2026-06-05T04:35:00.46198Z","steps":["trace[1659887906] 'process raft request' (duration: 474.180641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:35:00.462096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:34:59.987095Z","time spent":"474.955392ms","remote":"127.0.0.1:53558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1230,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T04:35:00.462629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.918039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T04:35:00.462696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.326799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T04:35:00.462868Z","caller":"traceutil/trace.go:171","msg":"trace[1768909524] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2025; }","duration":"811.579654ms","start":"2026-06-05T04:34:59.651262Z","end":"2026-06-05T04:35:00.462841Z","steps":["trace[1768909524] 'agreement among raft nodes before linearized reading' (duration: 811.318339ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:35:00.464348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:34:59.651231Z","time spent":"813.094884ms","remote":"127.0.0.1:53478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-06-05T04:35:00.462924Z","caller":"traceutil/trace.go:171","msg":"trace[876914081] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"472.784513ms","start":"2026-06-05T04:34:59.990123Z","end":"2026-06-05T04:35:00.462908Z","steps":["trace[876914081] 'process raft request' (duration: 471.861971ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:35:00.465057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:34:59.990116Z","time spent":"474.75015ms","remote":"127.0.0.1:53906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5915,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:35:00.46272Z","caller":"traceutil/trace.go:171","msg":"trace[484458507] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2025; }","duration":"151.062592ms","start":"2026-06-05T04:35:00.311635Z","end":"2026-06-05T04:35:00.462698Z","steps":["trace[484458507] 'agreement among raft nodes before linearized reading' (duration: 150.869859ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:35:00.462844Z","caller":"traceutil/trace.go:171","msg":"trace[32596214] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"474.770321ms","start":"2026-06-05T04:34:59.988038Z","end":"2026-06-05T04:35:00.462808Z","steps":["trace[32596214] 'process raft request' (duration: 473.754997ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:35:00.46564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:34:59.988013Z","time spent":"477.467706ms","remote":"127.0.0.1:53648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1846,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:35:00.656171Z","caller":"traceutil/trace.go:171","msg":"trace[51064628] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"183.661354ms","start":"2026-06-05T04:35:00.472456Z","end":"2026-06-05T04:35:00.656117Z","steps":["trace[51064628] 'process raft request' (duration: 102.319294ms)","trace[51064628] 'compare' (duration: 80.710391ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:35:00.656327Z","caller":"traceutil/trace.go:171","msg":"trace[388256184] linearizableReadLoop","detail":"{readStateIndex:2107; appliedIndex:2103; }","duration":"119.254333ms","start":"2026-06-05T04:35:00.537034Z","end":"2026-06-05T04:35:00.656288Z","steps":["trace[388256184] 'read index received' (duration: 37.725561ms)","trace[388256184] 'applied index is now lower than readState.Index' (duration: 81.526032ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:35:00.656297Z","caller":"traceutil/trace.go:171","msg":"trace[280692991] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"178.222ms","start":"2026-06-05T04:35:00.478044Z","end":"2026-06-05T04:35:00.656266Z","steps":["trace[280692991] 'process raft request' (duration: 177.995508ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:35:00.656593Z","caller":"traceutil/trace.go:171","msg":"trace[1261939624] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"150.341322ms","start":"2026-06-05T04:35:00.50623Z","end":"2026-06-05T04:35:00.656571Z","steps":["trace[1261939624] 'process raft request' (duration: 149.939347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:35:00.656945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.894541ms","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-06-05T04:35:00.656954Z","caller":"traceutil/trace.go:171","msg":"trace[392234388] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"127.516624ms","start":"2026-06-05T04:35:00.529424Z","end":"2026-06-05T04:35:00.65694Z","steps":["trace[392234388] 'process raft request' (duration: 126.802235ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:35:00.657001Z","caller":"traceutil/trace.go:171","msg":"trace[701506758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2029; }","duration":"120.023512ms","start":"2026-06-05T04:35:00.536964Z","end":"2026-06-05T04:35:00.656988Z","steps":["trace[701506758] 'agreement among raft nodes before linearized reading' (duration: 119.789729ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:35:04.905641Z","caller":"traceutil/trace.go:171","msg":"trace[1717790118] linearizableReadLoop","detail":"{readStateIndex:2130; appliedIndex:2129; }","duration":"203.357952ms","start":"2026-06-05T04:35:04.702248Z","end":"2026-06-05T04:35:04.905606Z","steps":["trace[1717790118] 'read index received' (duration: 43.97066ms)","trace[1717790118] 'applied index is now lower than readState.Index' (duration: 159.385672ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:35:04.905714Z","caller":"traceutil/trace.go:171","msg":"trace[1209521839] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"234.281009ms","start":"2026-06-05T04:35:04.671391Z","end":"2026-06-05T04:35:04.905672Z","steps":["trace[1209521839] 'process raft request' (duration: 234.011676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:35:04.905946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.661616ms","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-06-05T04:35:04.906045Z","caller":"traceutil/trace.go:171","msg":"trace[72900846] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2052; }","duration":"203.836587ms","start":"2026-06-05T04:35:04.702189Z","end":"2026-06-05T04:35:04.906025Z","steps":["trace[72900846] 'agreement among raft nodes before linearized reading' (duration: 203.538674ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:35:04.908381Z","caller":"traceutil/trace.go:171","msg":"trace[1696059646] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"128.97219ms","start":"2026-06-05T04:35:04.779385Z","end":"2026-06-05T04:35:04.908357Z","steps":["trace[1696059646] 'process raft request' (duration: 128.855949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:35:27.346991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.051957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T04:35:27.347102Z","caller":"traceutil/trace.go:171","msg":"trace[187797830] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2177; }","duration":"121.240748ms","start":"2026-06-05T04:35:27.22584Z","end":"2026-06-05T04:35:27.347081Z","steps":["trace[187797830] 'count revisions from in-memory index tree' (duration: 120.912325ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:35:27.347089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.789069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T04:35:27.347185Z","caller":"traceutil/trace.go:171","msg":"trace[752779706] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2177; }","duration":"124.983101ms","start":"2026-06-05T04:35:27.222175Z","end":"2026-06-05T04:35:27.347158Z","steps":["trace[752779706] 'count revisions from in-memory index tree' (duration: 124.577677ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:35:27.361162Z","caller":"traceutil/trace.go:171","msg":"trace[370463236] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"134.154829ms","start":"2026-06-05T04:35:27.226991Z","end":"2026-06-05T04:35:27.361146Z","steps":["trace[370463236] 'process raft request' (duration: 133.954607ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:35:27.37796Z","caller":"traceutil/trace.go:171","msg":"trace[1568257841] linearizableReadLoop","detail":"{readStateIndex:2262; appliedIndex:2260; }","duration":"144.848352ms","start":"2026-06-05T04:35:27.233075Z","end":"2026-06-05T04:35:27.377923Z","steps":["trace[1568257841] 'read index received' (duration: 127.933146ms)","trace[1568257841] 'applied index is now lower than readState.Index' (duration: 16.913816ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:35:27.378056Z","caller":"traceutil/trace.go:171","msg":"trace[240071819] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"150.68547ms","start":"2026-06-05T04:35:27.22733Z","end":"2026-06-05T04:35:27.378016Z","steps":["trace[240071819] 'process raft request' (duration: 150.416647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:35:27.378223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.123435ms","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-06-05T04:35:27.378273Z","caller":"traceutil/trace.go:171","msg":"trace[1220683867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2179; }","duration":"145.243097ms","start":"2026-06-05T04:35:27.233018Z","end":"2026-06-05T04:35:27.378261Z","steps":["trace[1220683867] 'agreement among raft nodes before linearized reading' (duration: 145.060285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:35:27.378347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.410608ms","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-06-05T04:35:27.37841Z","caller":"traceutil/trace.go:171","msg":"trace[1262272806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2179; }","duration":"138.529299ms","start":"2026-06-05T04:35:27.239864Z","end":"2026-06-05T04:35:27.378393Z","steps":["trace[1262272806] 'agreement among raft nodes before linearized reading' (duration: 138.373257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:35:27.37859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.816393ms","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-06-05T04:35:27.37863Z","caller":"traceutil/trace.go:171","msg":"trace[1569665033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2179; }","duration":"139.877223ms","start":"2026-06-05T04:35:27.238741Z","end":"2026-06-05T04:35:27.378619Z","steps":["trace[1569665033] 'agreement among raft nodes before linearized reading' (duration: 139.386808ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:35:27.837233Z","caller":"traceutil/trace.go:171","msg":"trace[2137538302] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"127.462761ms","start":"2026-06-05T04:35:27.709741Z","end":"2026-06-05T04:35:27.837204Z","steps":["trace[2137538302] 'process raft request' (duration: 127.291839ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:35:29.279396Z","caller":"traceutil/trace.go:171","msg":"trace[906505128] linearizableReadLoop","detail":"{readStateIndex:2270; appliedIndex:2267; }","duration":"167.321615ms","start":"2026-06-05T04:35:29.112037Z","end":"2026-06-05T04:35:29.279359Z","steps":["trace[906505128] 'read index received' (duration: 152.697377ms)","trace[906505128] 'applied index is now lower than readState.Index' (duration: 14.622928ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:35:29.280129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.069905ms","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-06-05T04:35:29.280238Z","caller":"traceutil/trace.go:171","msg":"trace[122410642] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2186; }","duration":"168.259626ms","start":"2026-06-05T04:35:29.111951Z","end":"2026-06-05T04:35:29.280211Z","steps":["trace[122410642] 'agreement among raft nodes before linearized reading' (duration: 167.914951ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:35:29.280287Z","caller":"traceutil/trace.go:171","msg":"trace[1650450503] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"347.327519ms","start":"2026-06-05T04:35:28.932896Z","end":"2026-06-05T04:35:29.280223Z","steps":["trace[1650450503] 'process raft request' (duration: 346.335527ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:35:29.280555Z","caller":"traceutil/trace.go:171","msg":"trace[622649858] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"276.558961ms","start":"2026-06-05T04:35:29.003979Z","end":"2026-06-05T04:35:29.280538Z","steps":["trace[622649858] 'process raft request' (duration: 275.337707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:35:29.280603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:35:28.932862Z","time spent":"347.527401ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:35:29.281053Z","caller":"traceutil/trace.go:171","msg":"trace[1328219829] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"376.695518ms","start":"2026-06-05T04:35:28.903029Z","end":"2026-06-05T04:35:29.279724Z","steps":["trace[1328219829] 'process raft request' (duration: 361.705116ms)","trace[1328219829] 'compare' (duration: 14.164423ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:35:29.281259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:35:28.902995Z","time spent":"378.127905ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:36:17.573806Z","caller":"traceutil/trace.go:171","msg":"trace[304365771] transaction","detail":"{read_only:false; response_revision:2554; number_of_response:1; }","duration":"109.695558ms","start":"2026-06-05T04:36:17.464076Z","end":"2026-06-05T04:36:17.573772Z","steps":["trace[304365771] 'process raft request' (duration: 109.506016ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:36:17.574391Z","caller":"traceutil/trace.go:171","msg":"trace[1408870239] transaction","detail":"{read_only:false; response_revision:2555; number_of_response:1; }","duration":"109.228285ms","start":"2026-06-05T04:36:17.465138Z","end":"2026-06-05T04:36:17.574366Z","steps":["trace[1408870239] 'process raft request' (duration: 108.987952ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:36:43.903736Z","caller":"traceutil/trace.go:171","msg":"trace[1766662815] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"121.939431ms","start":"2026-06-05T04:36:43.78178Z","end":"2026-06-05T04:36:43.90372Z","steps":["trace[1766662815] 'process raft request' (duration: 121.692558ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:36:43.903675Z","caller":"traceutil/trace.go:171","msg":"trace[1180815003] linearizableReadLoop","detail":"{readStateIndex:2803; appliedIndex:2802; }","duration":"105.039556ms","start":"2026-06-05T04:36:43.798607Z","end":"2026-06-05T04:36:43.903647Z","steps":["trace[1180815003] 'read index received' (duration: 104.786254ms)","trace[1180815003] 'applied index is now lower than readState.Index' (duration: 252.372µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:36:43.904068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.43456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-05T04:36:43.90415Z","caller":"traceutil/trace.go:171","msg":"trace[693254194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2702; }","duration":"105.557811ms","start":"2026-06-05T04:36:43.798566Z","end":"2026-06-05T04:36:43.904124Z","steps":["trace[693254194] 'agreement among raft nodes before linearized reading' (duration: 105.265989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:36:43.904199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.948648ms","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-06-05T04:36:43.90422Z","caller":"traceutil/trace.go:171","msg":"trace[1199144345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2702; }","duration":"103.019048ms","start":"2026-06-05T04:36:43.801193Z","end":"2026-06-05T04:36:43.904212Z","steps":["trace[1199144345] 'agreement among raft nodes before linearized reading' (duration: 102.947447ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:36:50.046933Z","caller":"traceutil/trace.go:171","msg":"trace[1223292157] linearizableReadLoop","detail":"{readStateIndex:2836; appliedIndex:2835; }","duration":"120.185842ms","start":"2026-06-05T04:36:49.92672Z","end":"2026-06-05T04:36:50.046906Z","steps":["trace[1223292157] 'read index received' (duration: 86.056216ms)","trace[1223292157] 'applied index is now lower than readState.Index' (duration: 34.128646ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:36:50.04708Z","caller":"traceutil/trace.go:171","msg":"trace[1848985161] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"236.117454ms","start":"2026-06-05T04:36:49.810939Z","end":"2026-06-05T04:36:50.047057Z","steps":["trace[1848985161] 'process raft request' (duration: 201.886407ms)","trace[1848985161] 'compare' (duration: 33.780404ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:36:50.047286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.534692ms","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-06-05T04:36:50.047387Z","caller":"traceutil/trace.go:171","msg":"trace[2105900727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2734; }","duration":"112.646993ms","start":"2026-06-05T04:36:49.934717Z","end":"2026-06-05T04:36:50.047364Z","steps":["trace[2105900727] 'agreement among raft nodes before linearized reading' (duration: 112.413171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:36:50.047675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.940719ms","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-06-05T04:36:50.047703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.050931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-05T04:36:50.047724Z","caller":"traceutil/trace.go:171","msg":"trace[1423923739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2734; }","duration":"121.04768ms","start":"2026-06-05T04:36:49.926665Z","end":"2026-06-05T04:36:50.047713Z","steps":["trace[1423923739] 'agreement among raft nodes before linearized reading' (duration: 120.889409ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:36:50.04775Z","caller":"traceutil/trace.go:171","msg":"trace[461769949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2734; }","duration":"110.155771ms","start":"2026-06-05T04:36:49.93758Z","end":"2026-06-05T04:36:50.047736Z","steps":["trace[461769949] 'agreement among raft nodes before linearized reading' (duration: 110.03565ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:38:18.440369Z","caller":"traceutil/trace.go:171","msg":"trace[1504202963] transaction","detail":"{read_only:false; response_revision:3197; number_of_response:1; }","duration":"268.287593ms","start":"2026-06-05T04:38:18.172048Z","end":"2026-06-05T04:38:18.440336Z","steps":["trace[1504202963] 'process raft request' (duration: 267.664905ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:38:55.066871Z","caller":"traceutil/trace.go:171","msg":"trace[773353198] transaction","detail":"{read_only:false; response_revision:3471; number_of_response:1; }","duration":"299.533803ms","start":"2026-06-05T04:38:54.767307Z","end":"2026-06-05T04:38:55.066841Z","steps":["trace[773353198] 'process raft request' (duration: 299.43518ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:38:55.066897Z","caller":"traceutil/trace.go:171","msg":"trace[1404400610] transaction","detail":"{read_only:false; response_revision:3470; number_of_response:1; }","duration":"300.332833ms","start":"2026-06-05T04:38:54.766517Z","end":"2026-06-05T04:38:55.06685Z","steps":["trace[1404400610] 'process raft request' (duration: 251.046968ms)","trace[1404400610] 'compare' (duration: 48.888415ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:38:55.067106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:38:54.7665Z","time spent":"300.501527ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:38:55.075672Z","caller":"traceutil/trace.go:171","msg":"trace[1230267088] transaction","detail":"{read_only:false; response_revision:3472; number_of_response:1; }","duration":"303.563792ms","start":"2026-06-05T04:38:54.772053Z","end":"2026-06-05T04:38:55.075616Z","steps":["trace[1230267088] 'process raft request' (duration: 303.378788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:38:55.07588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:38:54.772033Z","time spent":"303.730477ms","remote":"127.0.0.1:53644","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-06-05T04:39:14.76001Z","caller":"traceutil/trace.go:171","msg":"trace[1911976835] transaction","detail":"{read_only:false; response_revision:3582; number_of_response:1; }","duration":"109.251309ms","start":"2026-06-05T04:39:14.650733Z","end":"2026-06-05T04:39:14.759985Z","steps":["trace[1911976835] 'process raft request' (duration: 109.076004ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:39:30.688395Z","caller":"traceutil/trace.go:171","msg":"trace[896723903] linearizableReadLoop","detail":"{readStateIndex:3805; appliedIndex:3803; }","duration":"233.8359ms","start":"2026-06-05T04:39:30.454528Z","end":"2026-06-05T04:39:30.688364Z","steps":["trace[896723903] 'read index received' (duration: 170.661539ms)","trace[896723903] 'applied index is now lower than readState.Index' (duration: 63.172871ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:39:30.688589Z","caller":"traceutil/trace.go:171","msg":"trace[245947405] transaction","detail":"{read_only:false; response_revision:3667; number_of_response:1; }","duration":"249.066259ms","start":"2026-06-05T04:39:30.439508Z","end":"2026-06-05T04:39:30.688574Z","steps":["trace[245947405] 'process raft request' (duration: 248.804213ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:39:30.688428Z","caller":"traceutil/trace.go:171","msg":"trace[429587145] transaction","detail":"{read_only:false; response_revision:3666; number_of_response:1; }","duration":"257.162204ms","start":"2026-06-05T04:39:30.431232Z","end":"2026-06-05T04:39:30.688394Z","steps":["trace[429587145] 'process raft request' (duration: 193.939131ms)","trace[429587145] 'compare' (duration: 62.900576ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:39:30.6891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.552665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-05T04:39:30.689147Z","caller":"traceutil/trace.go:171","msg":"trace[717208306] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:3667; }","duration":"234.647457ms","start":"2026-06-05T04:39:30.454488Z","end":"2026-06-05T04:39:30.689135Z","steps":["trace[717208306] 'agreement among raft nodes before linearized reading' (duration: 234.481454ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:39:40.840928Z","caller":"traceutil/trace.go:171","msg":"trace[1851756828] transaction","detail":"{read_only:false; response_revision:3726; number_of_response:1; }","duration":"116.758594ms","start":"2026-06-05T04:39:40.724142Z","end":"2026-06-05T04:39:40.8409Z","steps":["trace[1851756828] 'process raft request' (duration: 116.611881ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:40:06.493737Z","caller":"traceutil/trace.go:171","msg":"trace[652021552] linearizableReadLoop","detail":"{readStateIndex:4079; appliedIndex:4078; }","duration":"116.763038ms","start":"2026-06-05T04:40:06.376949Z","end":"2026-06-05T04:40:06.493712Z","steps":["trace[652021552] 'read index received' (duration: 116.531212ms)","trace[652021552] 'applied index is now lower than readState.Index' (duration: 230.326µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:40:06.493887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.92377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T04:40:06.493921Z","caller":"traceutil/trace.go:171","msg":"trace[1729201965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3928; }","duration":"116.973181ms","start":"2026-06-05T04:40:06.376937Z","end":"2026-06-05T04:40:06.49391Z","steps":["trace[1729201965] 'agreement among raft nodes before linearized reading' (duration: 116.88203ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:40:06.494123Z","caller":"traceutil/trace.go:171","msg":"trace[1077400742] transaction","detail":"{read_only:false; response_revision:3928; number_of_response:1; }","duration":"135.025945ms","start":"2026-06-05T04:40:06.359059Z","end":"2026-06-05T04:40:06.494085Z","steps":["trace[1077400742] 'process raft request' (duration: 134.461083ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:40:15.866284Z","caller":"traceutil/trace.go:171","msg":"trace[475309047] linearizableReadLoop","detail":"{readStateIndex:4135; appliedIndex:4134; }","duration":"102.626863ms","start":"2026-06-05T04:40:15.76364Z","end":"2026-06-05T04:40:15.866266Z","steps":["trace[475309047] 'read index received' (duration: 22.218199ms)","trace[475309047] 'applied index is now lower than readState.Index' (duration: 80.406954ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:40:15.866334Z","caller":"traceutil/trace.go:171","msg":"trace[1918026232] transaction","detail":"{read_only:false; response_revision:3983; number_of_response:1; }","duration":"177.100977ms","start":"2026-06-05T04:40:15.6892Z","end":"2026-06-05T04:40:15.866301Z","steps":["trace[1918026232] 'process raft request' (duration: 96.650202ms)","trace[1918026232] 'compare' (duration: 80.025636ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:40:15.866517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.875748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:8 size:11771"} {"level":"info","ts":"2026-06-05T04:40:15.866546Z","caller":"traceutil/trace.go:171","msg":"trace[1379414699] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:8; response_revision:3984; }","duration":"102.939489ms","start":"2026-06-05T04:40:15.763599Z","end":"2026-06-05T04:40:15.866538Z","steps":["trace[1379414699] 'agreement among raft nodes before linearized reading' (duration: 102.755496ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:40:16.61878Z","caller":"traceutil/trace.go:171","msg":"trace[848144500] transaction","detail":"{read_only:false; number_of_response:0; response_revision:3985; }","duration":"103.633235ms","start":"2026-06-05T04:40:16.515123Z","end":"2026-06-05T04:40:16.618756Z","steps":["trace[848144500] 'process raft request' (duration: 103.494303ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:40:32.236549Z","caller":"traceutil/trace.go:171","msg":"trace[1786171539] linearizableReadLoop","detail":"{readStateIndex:4245; appliedIndex:4244; }","duration":"192.963602ms","start":"2026-06-05T04:40:32.043559Z","end":"2026-06-05T04:40:32.236523Z","steps":["trace[1786171539] 'read index received' (duration: 192.405502ms)","trace[1786171539] 'applied index is now lower than readState.Index' (duration: 556.84µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:40:32.236631Z","caller":"traceutil/trace.go:171","msg":"trace[527306004] transaction","detail":"{read_only:false; response_revision:4078; number_of_response:1; }","duration":"199.809491ms","start":"2026-06-05T04:40:32.036782Z","end":"2026-06-05T04:40:32.236591Z","steps":["trace[527306004] 'process raft request' (duration: 199.282432ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:32.236695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.110155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T04:40:32.236739Z","caller":"traceutil/trace.go:171","msg":"trace[3612615] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:4078; }","duration":"193.181636ms","start":"2026-06-05T04:40:32.043544Z","end":"2026-06-05T04:40:32.236725Z","steps":["trace[3612615] 'agreement among raft nodes before linearized reading' (duration: 193.082064ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:40:32.23689Z","caller":"traceutil/trace.go:171","msg":"trace[53078119] transaction","detail":"{read_only:false; response_revision:4079; number_of_response:1; }","duration":"186.5852ms","start":"2026-06-05T04:40:32.050279Z","end":"2026-06-05T04:40:32.236865Z","steps":["trace[53078119] 'process raft request' (duration: 186.443468ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:40:32.456291Z","caller":"traceutil/trace.go:171","msg":"trace[1961272645] linearizableReadLoop","detail":"{readStateIndex:4247; appliedIndex:4246; }","duration":"163.72709ms","start":"2026-06-05T04:40:32.292547Z","end":"2026-06-05T04:40:32.456274Z","steps":["trace[1961272645] 'read index received' (duration: 163.566446ms)","trace[1961272645] 'applied index is now lower than readState.Index' (duration: 159.934µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:40:32.456427Z","caller":"traceutil/trace.go:171","msg":"trace[1892602323] transaction","detail":"{read_only:false; response_revision:4080; number_of_response:1; }","duration":"180.748968ms","start":"2026-06-05T04:40:32.275625Z","end":"2026-06-05T04:40:32.456374Z","steps":["trace[1892602323] 'process raft request' (duration: 180.543373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:32.456567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.999754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-05T04:40:32.456634Z","caller":"traceutil/trace.go:171","msg":"trace[1606355976] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4080; }","duration":"164.109426ms","start":"2026-06-05T04:40:32.292502Z","end":"2026-06-05T04:40:32.456612Z","steps":["trace[1606355976] 'agreement among raft nodes before linearized reading' (duration: 163.861071ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:32.675681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.25145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T04:40:32.6759Z","caller":"traceutil/trace.go:171","msg":"trace[1852668288] transaction","detail":"{read_only:false; response_revision:4082; number_of_response:1; }","duration":"209.519362ms","start":"2026-06-05T04:40:32.466361Z","end":"2026-06-05T04:40:32.67588Z","steps":["trace[1852668288] 'process raft request' (duration: 209.451361ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:40:32.676005Z","caller":"traceutil/trace.go:171","msg":"trace[1719776537] transaction","detail":"{read_only:false; response_revision:4081; number_of_response:1; }","duration":"210.56075ms","start":"2026-06-05T04:40:32.465379Z","end":"2026-06-05T04:40:32.67594Z","steps":["trace[1719776537] 'process raft request' (duration: 94.957515ms)","trace[1719776537] 'compare' (duration: 115.071986ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:40:35.574581Z","caller":"traceutil/trace.go:171","msg":"trace[1921781741] transaction","detail":"{read_only:false; response_revision:4107; number_of_response:1; }","duration":"168.689683ms","start":"2026-06-05T04:40:35.405856Z","end":"2026-06-05T04:40:35.574545Z","steps":["trace[1921781741] 'process raft request' (duration: 167.224317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:35.574817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.579559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-05T04:40:35.574981Z","caller":"traceutil/trace.go:171","msg":"trace[610699868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4107; }","duration":"142.782471ms","start":"2026-06-05T04:40:35.432178Z","end":"2026-06-05T04:40:35.57496Z","steps":["trace[610699868] 'agreement among raft nodes before linearized reading' (duration: 142.442246ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:40:35.574562Z","caller":"traceutil/trace.go:171","msg":"trace[760885319] linearizableReadLoop","detail":"{readStateIndex:4274; appliedIndex:4273; }","duration":"142.291734ms","start":"2026-06-05T04:40:35.432218Z","end":"2026-06-05T04:40:35.57451Z","steps":["trace[760885319] 'read index received' (duration: 140.809988ms)","trace[760885319] 'applied index is now lower than readState.Index' (duration: 1.480346ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:40:35.76923Z","caller":"traceutil/trace.go:171","msg":"trace[1310506375] transaction","detail":"{read_only:false; response_revision:4109; number_of_response:1; }","duration":"183.441496ms","start":"2026-06-05T04:40:35.585736Z","end":"2026-06-05T04:40:35.769177Z","steps":["trace[1310506375] 'process raft request' (duration: 178.649722ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:46.611114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.95577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T04:40:46.612017Z","caller":"traceutil/trace.go:171","msg":"trace[768587529] linearizableReadLoop","detail":"{readStateIndex:4766; appliedIndex:4763; }","duration":"299.810854ms","start":"2026-06-05T04:40:46.312186Z","end":"2026-06-05T04:40:46.611997Z","steps":["trace[768587529] 'read index received' (duration: 20.958403ms)","trace[768587529] 'applied index is now lower than readState.Index' (duration: 278.850861ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:40:46.612095Z","caller":"traceutil/trace.go:171","msg":"trace[1937261239] transaction","detail":"{read_only:false; response_revision:4592; number_of_response:1; }","duration":"305.677604ms","start":"2026-06-05T04:40:46.306327Z","end":"2026-06-05T04:40:46.612005Z","steps":["trace[1937261239] 'process raft request' (duration: 129.718757ms)","trace[1937261239] 'compare' (duration: 174.843268ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:40:46.612223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.023147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:56195"} {"level":"info","ts":"2026-06-05T04:40:46.612275Z","caller":"traceutil/trace.go:171","msg":"trace[472213827] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4592; }","duration":"300.106908ms","start":"2026-06-05T04:40:46.312158Z","end":"2026-06-05T04:40:46.612265Z","steps":["trace[472213827] 'agreement among raft nodes before linearized reading' (duration: 299.905765ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:46.612302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:40:46.312148Z","time spent":"300.14926ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":6,"response size":56217,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"warn","ts":"2026-06-05T04:40:46.612402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.099564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-83dc63b1-6351-43f3-96e3-d6f0c85116bb\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-06-05T04:40:46.612438Z","caller":"traceutil/trace.go:171","msg":"trace[616680334] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-83dc63b1-6351-43f3-96e3-d6f0c85116bb; range_end:; response_count:1; response_revision:4592; }","duration":"244.188986ms","start":"2026-06-05T04:40:46.368239Z","end":"2026-06-05T04:40:46.612428Z","steps":["trace[616680334] 'agreement among raft nodes before linearized reading' (duration: 244.092354ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:46.612398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:40:46.306307Z","time spent":"305.930708ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T04:40:46.612536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.251663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T04:40:46.612554Z","caller":"traceutil/trace.go:171","msg":"trace[1350207538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4592; }","duration":"236.314334ms","start":"2026-06-05T04:40:46.376235Z","end":"2026-06-05T04:40:46.61255Z","steps":["trace[1350207538] 'agreement among raft nodes before linearized reading' (duration: 236.283813ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:46.612653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.058978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-06-05T04:40:46.612706Z","caller":"traceutil/trace.go:171","msg":"trace[1514209884] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4592; }","duration":"300.115809ms","start":"2026-06-05T04:40:46.312577Z","end":"2026-06-05T04:40:46.612693Z","steps":["trace[1514209884] 'agreement among raft nodes before linearized reading' (duration: 299.56207ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:46.612744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:40:46.31252Z","time spent":"300.216231ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1146,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-06-05T04:40:46.612757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.765965ms","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-06-05T04:40:46.612867Z","caller":"traceutil/trace.go:171","msg":"trace[558872123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4592; }","duration":"289.921638ms","start":"2026-06-05T04:40:46.322923Z","end":"2026-06-05T04:40:46.612844Z","steps":["trace[558872123] 'agreement among raft nodes before linearized reading' (duration: 289.573462ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:40:46.800929Z","caller":"traceutil/trace.go:171","msg":"trace[1075960096] transaction","detail":"{read_only:false; response_revision:4595; number_of_response:1; }","duration":"131.288023ms","start":"2026-06-05T04:40:46.669617Z","end":"2026-06-05T04:40:46.800905Z","steps":["trace[1075960096] 'process raft request' (duration: 131.162031ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:40:46.801022Z","caller":"traceutil/trace.go:171","msg":"trace[1257642500] transaction","detail":"{read_only:false; response_revision:4594; number_of_response:1; }","duration":"163.992365ms","start":"2026-06-05T04:40:46.636993Z","end":"2026-06-05T04:40:46.800985Z","steps":["trace[1257642500] 'process raft request' (duration: 130.985538ms)","trace[1257642500] 'compare' (duration: 31.40657ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:40:49.391878Z","caller":"traceutil/trace.go:171","msg":"trace[1199431203] transaction","detail":"{read_only:false; response_revision:4618; number_of_response:1; }","duration":"231.716815ms","start":"2026-06-05T04:40:49.160135Z","end":"2026-06-05T04:40:49.391852Z","steps":["trace[1199431203] 'process raft request' (duration: 231.527882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:50.134878Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521226639054994803,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T04:40:50.381452Z","caller":"traceutil/trace.go:171","msg":"trace[452995050] transaction","detail":"{read_only:false; response_revision:4620; number_of_response:1; }","duration":"1.084421758s","start":"2026-06-05T04:40:49.297004Z","end":"2026-06-05T04:40:50.381426Z","steps":["trace[452995050] 'process raft request' (duration: 1.084364357s)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:40:50.381494Z","caller":"traceutil/trace.go:171","msg":"trace[1301862015] transaction","detail":"{read_only:false; response_revision:4619; number_of_response:1; }","duration":"1.161856902s","start":"2026-06-05T04:40:49.219612Z","end":"2026-06-05T04:40:50.381469Z","steps":["trace[1301862015] 'process raft request' (duration: 1.161517357s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:50.381622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:40:49.296977Z","time spent":"1.08455641s","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T04:40:50.382114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:40:49.219562Z","time spent":"1.161982475s","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:40:50.406793Z","caller":"traceutil/trace.go:171","msg":"trace[1956517501] linearizableReadLoop","detail":"{readStateIndex:4795; appliedIndex:4792; }","duration":"772.951749ms","start":"2026-06-05T04:40:49.633818Z","end":"2026-06-05T04:40:50.40677Z","steps":["trace[1956517501] 'read index received' (duration: 747.215919ms)","trace[1956517501] 'applied index is now lower than readState.Index' (duration: 25.734129ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:40:50.407212Z","caller":"traceutil/trace.go:171","msg":"trace[954693725] transaction","detail":"{read_only:false; response_revision:4621; number_of_response:1; }","duration":"675.111611ms","start":"2026-06-05T04:40:49.732087Z","end":"2026-06-05T04:40:50.407199Z","steps":["trace[954693725] 'process raft request' (duration: 674.42971ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:50.407308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.117986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-06-05T04:40:50.407338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:40:49.732045Z","time spent":"675.208663ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:40:50.407393Z","caller":"traceutil/trace.go:171","msg":"trace[18921469] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4621; }","duration":"738.260118ms","start":"2026-06-05T04:40:49.669114Z","end":"2026-06-05T04:40:50.407374Z","steps":["trace[18921469] 'agreement among raft nodes before linearized reading' (duration: 737.998033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:50.407445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:40:49.669087Z","time spent":"738.349ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-05T04:40:50.4078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.214267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T04:40:50.407861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.836993ms","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":"warn","ts":"2026-06-05T04:40:50.40788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.179532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c2a9a2c5-7da2-4135-98a4-408dbc9dce4a\" ","response":"range_response_count:1 size:4053"} {"level":"warn","ts":"2026-06-05T04:40:50.407893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.105786ms","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-06-05T04:40:50.40789Z","caller":"traceutil/trace.go:171","msg":"trace[2134745624] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4621; }","duration":"285.36027ms","start":"2026-06-05T04:40:50.122512Z","end":"2026-06-05T04:40:50.407872Z","steps":["trace[2134745624] 'agreement among raft nodes before linearized reading' (duration: 285.195757ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:40:50.407923Z","caller":"traceutil/trace.go:171","msg":"trace[151554719] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c2a9a2c5-7da2-4135-98a4-408dbc9dce4a; range_end:; response_count:1; response_revision:4621; }","duration":"535.264583ms","start":"2026-06-05T04:40:49.872647Z","end":"2026-06-05T04:40:50.407912Z","steps":["trace[151554719] 'agreement among raft nodes before linearized reading' (duration: 535.151611ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:40:50.407923Z","caller":"traceutil/trace.go:171","msg":"trace[1567215330] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4621; }","duration":"459.931844ms","start":"2026-06-05T04:40:49.947974Z","end":"2026-06-05T04:40:50.407906Z","steps":["trace[1567215330] 'agreement among raft nodes before linearized reading' (duration: 459.73018ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:50.407954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:40:49.872627Z","time spent":"535.319834ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4075,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c2a9a2c5-7da2-4135-98a4-408dbc9dce4a\" "} {"level":"warn","ts":"2026-06-05T04:40:50.40797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:40:49.947958Z","time spent":"460.001175ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T04:40:50.407992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.751141ms","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-06-05T04:40:50.408014Z","caller":"traceutil/trace.go:171","msg":"trace[676090006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4621; }","duration":"520.792531ms","start":"2026-06-05T04:40:49.887215Z","end":"2026-06-05T04:40:50.408008Z","steps":["trace[676090006] 'agreement among raft nodes before linearized reading' (duration: 520.73566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:50.408037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:40:49.887206Z","time spent":"520.826222ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-05T04:40:50.407935Z","caller":"traceutil/trace.go:171","msg":"trace[1464922420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4621; }","duration":"524.172238ms","start":"2026-06-05T04:40:49.883751Z","end":"2026-06-05T04:40:50.407924Z","steps":["trace[1464922420] 'agreement among raft nodes before linearized reading' (duration: 524.019856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:50.408095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:40:49.88374Z","time spent":"524.351461ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T04:40:50.408368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.239383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T04:40:50.408418Z","caller":"traceutil/trace.go:171","msg":"trace[1032128576] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:4621; }","duration":"223.346405ms","start":"2026-06-05T04:40:50.185059Z","end":"2026-06-05T04:40:50.408406Z","steps":["trace[1032128576] 'agreement among raft nodes before linearized reading' (duration: 223.254013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:50.41026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.443657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-83dc63b1-6351-43f3-96e3-d6f0c85116bb\" ","response":"range_response_count:1 size:4077"} {"level":"info","ts":"2026-06-05T04:40:50.410324Z","caller":"traceutil/trace.go:171","msg":"trace[1469068484] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-83dc63b1-6351-43f3-96e3-d6f0c85116bb; range_end:; response_count:1; response_revision:4621; }","duration":"776.550238ms","start":"2026-06-05T04:40:49.633762Z","end":"2026-06-05T04:40:50.410312Z","steps":["trace[1469068484] 'agreement among raft nodes before linearized reading' (duration: 776.384635ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:50.410358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:40:49.63374Z","time spent":"776.60885ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4099,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-83dc63b1-6351-43f3-96e3-d6f0c85116bb\" "} {"level":"info","ts":"2026-06-05T04:40:52.060829Z","caller":"traceutil/trace.go:171","msg":"trace[1970235037] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"120.259768ms","start":"2026-06-05T04:40:51.940545Z","end":"2026-06-05T04:40:52.060805Z","steps":["trace[1970235037] 'process raft request' (duration: 98.167232ms)","trace[1970235037] 'compare' (duration: 21.912213ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:40:57.92005Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521226639054995236,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T04:40:58.12873Z","caller":"traceutil/trace.go:171","msg":"trace[2145589135] linearizableReadLoop","detail":"{readStateIndex:4964; appliedIndex:4963; }","duration":"709.007448ms","start":"2026-06-05T04:40:57.41968Z","end":"2026-06-05T04:40:58.128687Z","steps":["trace[2145589135] 'read index received' (duration: 708.585032ms)","trace[2145589135] 'applied index is now lower than readState.Index' (duration: 420.026µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:40:58.128951Z","caller":"traceutil/trace.go:171","msg":"trace[2029899717] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"710.507664ms","start":"2026-06-05T04:40:57.418385Z","end":"2026-06-05T04:40:58.128893Z","steps":["trace[2029899717] 'process raft request' (duration: 710.001186ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:58.12906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.321433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T04:40:58.129127Z","caller":"traceutil/trace.go:171","msg":"trace[1293003090] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:4784; }","duration":"709.453537ms","start":"2026-06-05T04:40:57.419654Z","end":"2026-06-05T04:40:58.129108Z","steps":["trace[1293003090] 'agreement among raft nodes before linearized reading' (duration: 709.295654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:58.129139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.37312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b206074b-567b-4643-af36-d03a614a9e12\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-06-05T04:40:58.129188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:40:57.419642Z","time spent":"709.528978ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-06-05T04:40:58.129209Z","caller":"traceutil/trace.go:171","msg":"trace[1158640910] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b206074b-567b-4643-af36-d03a614a9e12; range_end:; response_count:1; response_revision:4784; }","duration":"382.517621ms","start":"2026-06-05T04:40:57.746674Z","end":"2026-06-05T04:40:58.129192Z","steps":["trace[1158640910] 'agreement among raft nodes before linearized reading' (duration: 382.277437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:58.129192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:40:57.418342Z","time spent":"710.733967ms","remote":"127.0.0.1:53644","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-06-05T04:40:58.129252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:40:57.74663Z","time spent":"382.612633ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b206074b-567b-4643-af36-d03a614a9e12\" "} {"level":"info","ts":"2026-06-05T04:40:58.285893Z","caller":"traceutil/trace.go:171","msg":"trace[1444508604] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"149.080876ms","start":"2026-06-05T04:40:58.136781Z","end":"2026-06-05T04:40:58.285862Z","steps":["trace[1444508604] 'process raft request' (duration: 148.7685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:58.72212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.519689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T04:40:58.722325Z","caller":"traceutil/trace.go:171","msg":"trace[956664036] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"221.050002ms","start":"2026-06-05T04:40:58.501257Z","end":"2026-06-05T04:40:58.722307Z","steps":["trace[956664036] 'process raft request' (duration: 220.982221ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:40:58.722364Z","caller":"traceutil/trace.go:171","msg":"trace[1959233231] transaction","detail":"{read_only:false; response_revision:4788; number_of_response:1; }","duration":"260.810201ms","start":"2026-06-05T04:40:58.461518Z","end":"2026-06-05T04:40:58.722328Z","steps":["trace[1959233231] 'process raft request' (duration: 58.001817ms)","trace[1959233231] 'compare' (duration: 202.403468ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:40:58.722593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.300486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-05T04:40:58.722422Z","caller":"traceutil/trace.go:171","msg":"trace[587228604] linearizableReadLoop","detail":"{readStateIndex:4968; appliedIndex:4967; }","duration":"225.987032ms","start":"2026-06-05T04:40:58.496414Z","end":"2026-06-05T04:40:58.722401Z","steps":["trace[587228604] 'read index received' (duration: 23.144658ms)","trace[587228604] 'applied index is now lower than readState.Index' (duration: 202.840244ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:40:58.722621Z","caller":"traceutil/trace.go:171","msg":"trace[76230281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4789; }","duration":"262.361457ms","start":"2026-06-05T04:40:58.460252Z","end":"2026-06-05T04:40:58.722613Z","steps":["trace[76230281] 'agreement among raft nodes before linearized reading' (duration: 262.197064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:40:58.722762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.493057ms","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-06-05T04:40:58.722869Z","caller":"traceutil/trace.go:171","msg":"trace[852065398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4789; }","duration":"260.633969ms","start":"2026-06-05T04:40:58.462211Z","end":"2026-06-05T04:40:58.722845Z","steps":["trace[852065398] 'agreement among raft nodes before linearized reading' (duration: 260.310643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:00.800553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.674103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T04:41:00.801377Z","caller":"traceutil/trace.go:171","msg":"trace[438376079] linearizableReadLoop","detail":"{readStateIndex:4991; appliedIndex:4989; }","duration":"275.572811ms","start":"2026-06-05T04:41:00.52575Z","end":"2026-06-05T04:41:00.801322Z","steps":["trace[438376079] 'read index received' (duration: 69.037923ms)","trace[438376079] 'applied index is now lower than readState.Index' (duration: 206.533738ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:41:00.801457Z","caller":"traceutil/trace.go:171","msg":"trace[1670128729] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"300.619338ms","start":"2026-06-05T04:41:00.500827Z","end":"2026-06-05T04:41:00.801446Z","steps":["trace[1670128729] 'process raft request' (duration: 93.945508ms)","trace[1670128729] 'compare' (duration: 205.46137ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:41:00.801614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:41:00.500796Z","time spent":"300.762061ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:41:00.801622Z","caller":"traceutil/trace.go:171","msg":"trace[698791670] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"299.280635ms","start":"2026-06-05T04:41:00.50231Z","end":"2026-06-05T04:41:00.80159Z","steps":["trace[698791670] 'process raft request' (duration: 298.93116ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:00.802012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.254103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-06-05T04:41:00.80206Z","caller":"traceutil/trace.go:171","msg":"trace[1310389630] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4810; }","duration":"276.323923ms","start":"2026-06-05T04:41:00.525725Z","end":"2026-06-05T04:41:00.802049Z","steps":["trace[1310389630] 'agreement among raft nodes before linearized reading' (duration: 276.133579ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:41:00.802766Z","caller":"traceutil/trace.go:171","msg":"trace[1845853477] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"275.229834ms","start":"2026-06-05T04:41:00.527515Z","end":"2026-06-05T04:41:00.802745Z","steps":["trace[1845853477] 'process raft request' (duration: 275.123763ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:41:00.805291Z","caller":"traceutil/trace.go:171","msg":"trace[1702666785] transaction","detail":"{read_only:false; response_revision:4812; number_of_response:1; }","duration":"169.13437ms","start":"2026-06-05T04:41:00.63614Z","end":"2026-06-05T04:41:00.805274Z","steps":["trace[1702666785] 'process raft request' (duration: 167.112757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:02.932621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.678719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T04:41:02.932868Z","caller":"traceutil/trace.go:171","msg":"trace[2073262188] transaction","detail":"{read_only:false; response_revision:4853; number_of_response:1; }","duration":"119.082317ms","start":"2026-06-05T04:41:02.813763Z","end":"2026-06-05T04:41:02.932845Z","steps":["trace[2073262188] 'process raft request' (duration: 118.974425ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:41:02.933277Z","caller":"traceutil/trace.go:171","msg":"trace[409534799] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"448.79524ms","start":"2026-06-05T04:41:02.484452Z","end":"2026-06-05T04:41:02.933247Z","steps":["trace[409534799] 'process raft request' (duration: 232.391109ms)","trace[409534799] 'compare' (duration: 215.388414ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:41:02.933428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:41:02.484441Z","time spent":"448.914273ms","remote":"127.0.0.1:53548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1662,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:41:02.933409Z","caller":"traceutil/trace.go:171","msg":"trace[2137551656] linearizableReadLoop","detail":"{readStateIndex:5034; appliedIndex:5033; }","duration":"233.785942ms","start":"2026-06-05T04:41:02.699166Z","end":"2026-06-05T04:41:02.932952Z","steps":["trace[2137551656] 'read index received' (duration: 17.725756ms)","trace[2137551656] 'applied index is now lower than readState.Index' (duration: 216.057906ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:41:02.933488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.323201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-05T04:41:02.933539Z","caller":"traceutil/trace.go:171","msg":"trace[691645434] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4853; }","duration":"234.414942ms","start":"2026-06-05T04:41:02.69911Z","end":"2026-06-05T04:41:02.933525Z","steps":["trace[691645434] 'agreement among raft nodes before linearized reading' (duration: 234.254769ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:02.933857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.43822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-05T04:41:02.933912Z","caller":"traceutil/trace.go:171","msg":"trace[516930294] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4853; }","duration":"104.53371ms","start":"2026-06-05T04:41:02.829369Z","end":"2026-06-05T04:41:02.933902Z","steps":["trace[516930294] 'agreement among raft nodes before linearized reading' (duration: 104.426549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:02.933921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.152208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-05T04:41:02.933973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.019034ms","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-06-05T04:41:02.933978Z","caller":"traceutil/trace.go:171","msg":"trace[1608978329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4853; }","duration":"103.236439ms","start":"2026-06-05T04:41:02.830728Z","end":"2026-06-05T04:41:02.933964Z","steps":["trace[1608978329] 'agreement among raft nodes before linearized reading' (duration: 102.958855ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:41:02.934014Z","caller":"traceutil/trace.go:171","msg":"trace[189340407] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4853; }","duration":"106.083096ms","start":"2026-06-05T04:41:02.827922Z","end":"2026-06-05T04:41:02.934005Z","steps":["trace[189340407] 'agreement among raft nodes before linearized reading' (duration: 105.925824ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:02.934493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.463206ms","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-06-05T04:41:02.934537Z","caller":"traceutil/trace.go:171","msg":"trace[941015422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4853; }","duration":"126.543278ms","start":"2026-06-05T04:41:02.807983Z","end":"2026-06-05T04:41:02.934526Z","steps":["trace[941015422] 'agreement among raft nodes before linearized reading' (duration: 125.662023ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:41:08.434164Z","caller":"traceutil/trace.go:171","msg":"trace[703828703] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"258.249868ms","start":"2026-06-05T04:41:08.175889Z","end":"2026-06-05T04:41:08.434139Z","steps":["trace[703828703] 'process raft request' (duration: 258.034524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:14.759345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.554511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T04:41:14.759464Z","caller":"traceutil/trace.go:171","msg":"trace[1663443613] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4978; }","duration":"523.761504ms","start":"2026-06-05T04:41:14.235678Z","end":"2026-06-05T04:41:14.75944Z","steps":["trace[1663443613] 'count revisions from in-memory index tree' (duration: 523.359439ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:14.759494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.084017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T04:41:14.759571Z","caller":"traceutil/trace.go:171","msg":"trace[1566899156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4978; }","duration":"382.25335ms","start":"2026-06-05T04:41:14.3773Z","end":"2026-06-05T04:41:14.759554Z","steps":["trace[1566899156] 'range keys from in-memory index tree' (duration: 381.910514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:14.759608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:41:14.37725Z","time spent":"382.347311ms","remote":"127.0.0.1:52052","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-06-05T04:41:14.759515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:41:14.235644Z","time spent":"523.858036ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-05T04:41:14.759777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204522552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T04:41:14.759817Z","caller":"traceutil/trace.go:171","msg":"trace[1644514715] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:4978; }","duration":"1.204627025s","start":"2026-06-05T04:41:13.555179Z","end":"2026-06-05T04:41:14.759806Z","steps":["trace[1644514715] 'count revisions from in-memory index tree' (duration: 1.20441503s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:14.759849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:41:13.55515Z","time spent":"1.204689535s","remote":"127.0.0.1:55588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":9,"response size":29,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-06-05T04:41:14.760118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126502183s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-05T04:41:14.760217Z","caller":"traceutil/trace.go:171","msg":"trace[306847449] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4978; }","duration":"1.126615444s","start":"2026-06-05T04:41:13.633576Z","end":"2026-06-05T04:41:14.760192Z","steps":["trace[306847449] 'range keys from in-memory index tree' (duration: 1.12626112s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:14.760266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:41:13.633499Z","time spent":"1.126757586s","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-05T04:41:14.760278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182831791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T04:41:14.760317Z","caller":"traceutil/trace.go:171","msg":"trace[1266390480] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4978; }","duration":"1.184356424s","start":"2026-06-05T04:41:13.57595Z","end":"2026-06-05T04:41:14.760307Z","steps":["trace[1266390480] 'count revisions from in-memory index tree' (duration: 1.182535806s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:14.760342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:41:13.575923Z","time spent":"1.184409876s","remote":"127.0.0.1:58606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-06-05T04:41:14.910296Z","caller":"traceutil/trace.go:171","msg":"trace[2087452575] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"414.171752ms","start":"2026-06-05T04:41:14.496094Z","end":"2026-06-05T04:41:14.910266Z","steps":["trace[2087452575] 'process raft request' (duration: 413.992139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:14.910489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:41:14.496056Z","time spent":"414.332824ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:41:14.92678Z","caller":"traceutil/trace.go:171","msg":"trace[1735791192] linearizableReadLoop","detail":"{readStateIndex:5166; appliedIndex:5164; }","duration":"163.339698ms","start":"2026-06-05T04:41:14.763416Z","end":"2026-06-05T04:41:14.926756Z","steps":["trace[1735791192] 'read index received' (duration: 146.772967ms)","trace[1735791192] 'applied index is now lower than readState.Index' (duration: 16.565791ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:41:14.927083Z","caller":"traceutil/trace.go:171","msg":"trace[1798271422] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"330.177521ms","start":"2026-06-05T04:41:14.596892Z","end":"2026-06-05T04:41:14.92707Z","steps":["trace[1798271422] 'process raft request' (duration: 329.686723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:14.927202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:41:14.596854Z","time spent":"330.266032ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:41:14.927383Z","caller":"traceutil/trace.go:171","msg":"trace[405727538] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"157.296892ms","start":"2026-06-05T04:41:14.770076Z","end":"2026-06-05T04:41:14.927373Z","steps":["trace[405727538] 'process raft request' (duration: 156.629952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:14.92759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.1686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T04:41:14.927665Z","caller":"traceutil/trace.go:171","msg":"trace[545940957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4981; }","duration":"164.282032ms","start":"2026-06-05T04:41:14.763368Z","end":"2026-06-05T04:41:14.92765Z","steps":["trace[545940957] 'agreement among raft nodes before linearized reading' (duration: 164.179001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:14.927975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.097093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-05T04:41:14.928039Z","caller":"traceutil/trace.go:171","msg":"trace[863716376] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4981; }","duration":"161.233615ms","start":"2026-06-05T04:41:14.766789Z","end":"2026-06-05T04:41:14.928022Z","steps":["trace[863716376] 'agreement among raft nodes before linearized reading' (duration: 161.006062ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:41:15.24523Z","caller":"traceutil/trace.go:171","msg":"trace[2015944230] linearizableReadLoop","detail":"{readStateIndex:5172; appliedIndex:5171; }","duration":"128.480195ms","start":"2026-06-05T04:41:15.11672Z","end":"2026-06-05T04:41:15.2452Z","steps":["trace[2015944230] 'read index received' (duration: 82.041367ms)","trace[2015944230] 'applied index is now lower than readState.Index' (duration: 46.437858ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:41:15.245298Z","caller":"traceutil/trace.go:171","msg":"trace[2041379833] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"134.610761ms","start":"2026-06-05T04:41:15.110647Z","end":"2026-06-05T04:41:15.245258Z","steps":["trace[2041379833] 'process raft request' (duration: 88.109902ms)","trace[2041379833] 'compare' (duration: 46.299646ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:41:15.245588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.681687ms","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-06-05T04:41:15.245656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.750428ms","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-06-05T04:41:15.245663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.919771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:10828"} {"level":"info","ts":"2026-06-05T04:41:15.24571Z","caller":"traceutil/trace.go:171","msg":"trace[490309083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4986; }","duration":"140.877339ms","start":"2026-06-05T04:41:15.104821Z","end":"2026-06-05T04:41:15.245698Z","steps":["trace[490309083] 'agreement among raft nodes before linearized reading' (duration: 140.752807ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:41:15.245718Z","caller":"traceutil/trace.go:171","msg":"trace[475083544] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4986; }","duration":"127.001003ms","start":"2026-06-05T04:41:15.118704Z","end":"2026-06-05T04:41:15.245705Z","steps":["trace[475083544] 'agreement among raft nodes before linearized reading' (duration: 126.861279ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:41:15.245642Z","caller":"traceutil/trace.go:171","msg":"trace[1690569961] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4986; }","duration":"142.81397ms","start":"2026-06-05T04:41:15.102815Z","end":"2026-06-05T04:41:15.245629Z","steps":["trace[1690569961] 'agreement among raft nodes before linearized reading' (duration: 142.626017ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:15.246151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.140307ms","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-06-05T04:41:15.246237Z","caller":"traceutil/trace.go:171","msg":"trace[187111192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4986; }","duration":"138.275099ms","start":"2026-06-05T04:41:15.107943Z","end":"2026-06-05T04:41:15.246218Z","steps":["trace[187111192] 'agreement among raft nodes before linearized reading' (duration: 137.424295ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:15.696481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.605893ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-05T04:41:21.438197Z","caller":"traceutil/trace.go:171","msg":"trace[752928783] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"120.026346ms","start":"2026-06-05T04:41:21.318137Z","end":"2026-06-05T04:41:21.438164Z","steps":["trace[752928783] 'process raft request' (duration: 111.983043ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:41:21.448006Z","caller":"traceutil/trace.go:171","msg":"trace[946883894] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"128.834843ms","start":"2026-06-05T04:41:21.319133Z","end":"2026-06-05T04:41:21.447968Z","steps":["trace[946883894] 'process raft request' (duration: 128.540209ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:41:49.744906Z","caller":"traceutil/trace.go:171","msg":"trace[1809072856] linearizableReadLoop","detail":"{readStateIndex:5506; appliedIndex:5505; }","duration":"130.773411ms","start":"2026-06-05T04:41:49.614105Z","end":"2026-06-05T04:41:49.744878Z","steps":["trace[1809072856] 'read index received' (duration: 110.256431ms)","trace[1809072856] 'applied index is now lower than readState.Index' (duration: 20.51578ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:41:49.74526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.139146ms","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-06-05T04:41:49.745306Z","caller":"traceutil/trace.go:171","msg":"trace[1513421119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5312; }","duration":"131.246318ms","start":"2026-06-05T04:41:49.614048Z","end":"2026-06-05T04:41:49.745294Z","steps":["trace[1513421119] 'agreement among raft nodes before linearized reading' (duration: 130.936953ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:41:49.745513Z","caller":"traceutil/trace.go:171","msg":"trace[1013567284] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"138.817687ms","start":"2026-06-05T04:41:49.606665Z","end":"2026-06-05T04:41:49.745483Z","steps":["trace[1013567284] 'process raft request' (duration: 117.648388ms)","trace[1013567284] 'compare' (duration: 20.436308ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:41:57.949912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.967741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T04:41:57.950083Z","caller":"traceutil/trace.go:171","msg":"trace[535859063] transaction","detail":"{read_only:false; response_revision:5359; number_of_response:1; }","duration":"160.577661ms","start":"2026-06-05T04:41:57.789457Z","end":"2026-06-05T04:41:57.950035Z","steps":["trace[535859063] 'process raft request' (duration: 48.398587ms)","trace[535859063] 'compare' (duration: 111.86404ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:41:57.95102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.873327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-06-05T04:41:57.951086Z","caller":"traceutil/trace.go:171","msg":"trace[762916380] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5359; }","duration":"142.961848ms","start":"2026-06-05T04:41:57.808107Z","end":"2026-06-05T04:41:57.951069Z","steps":["trace[762916380] 'agreement among raft nodes before linearized reading' (duration: 142.084395ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:41:57.950142Z","caller":"traceutil/trace.go:171","msg":"trace[1005629770] linearizableReadLoop","detail":"{readStateIndex:5556; appliedIndex:5555; }","duration":"141.868372ms","start":"2026-06-05T04:41:57.808143Z","end":"2026-06-05T04:41:57.950011Z","steps":["trace[1005629770] 'read index received' (duration: 29.660677ms)","trace[1005629770] 'applied index is now lower than readState.Index' (duration: 112.206485ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:41:58.586141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.758114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T04:41:58.586271Z","caller":"traceutil/trace.go:171","msg":"trace[375727886] linearizableReadLoop","detail":"{readStateIndex:5557; appliedIndex:5556; }","duration":"228.496993ms","start":"2026-06-05T04:41:58.357754Z","end":"2026-06-05T04:41:58.586251Z","steps":["trace[375727886] 'read index received' (duration: 24.68µs)","trace[375727886] 'applied index is now lower than readState.Index' (duration: 228.470843ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:41:58.586323Z","caller":"traceutil/trace.go:171","msg":"trace[1769400962] transaction","detail":"{read_only:false; response_revision:5360; number_of_response:1; }","duration":"559.692902ms","start":"2026-06-05T04:41:58.026599Z","end":"2026-06-05T04:41:58.586292Z","steps":["trace[1769400962] 'process raft request' (duration: 253.691496ms)","trace[1769400962] 'compare' (duration: 305.313857ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:41:58.586381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.625405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T04:41:58.586415Z","caller":"traceutil/trace.go:171","msg":"trace[1459652319] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:5360; }","duration":"228.684285ms","start":"2026-06-05T04:41:58.357721Z","end":"2026-06-05T04:41:58.586405Z","steps":["trace[1459652319] 'agreement among raft nodes before linearized reading' (duration: 228.616505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:58.586493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:41:58.026582Z","time spent":"559.829504ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7367,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T04:41:58.58666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.069303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T04:41:58.586703Z","caller":"traceutil/trace.go:171","msg":"trace[1462427218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5360; }","duration":"211.147414ms","start":"2026-06-05T04:41:58.375544Z","end":"2026-06-05T04:41:58.586692Z","steps":["trace[1462427218] 'agreement among raft nodes before linearized reading' (duration: 211.079583ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:41:59.77135Z","caller":"traceutil/trace.go:171","msg":"trace[1052917695] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"386.097398ms","start":"2026-06-05T04:41:59.385221Z","end":"2026-06-05T04:41:59.771318Z","steps":["trace[1052917695] 'process raft request' (duration: 364.069562ms)","trace[1052917695] 'compare' (duration: 21.535949ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:41:59.771493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:41:59.385189Z","time spent":"386.23035ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:41:59.773998Z","caller":"traceutil/trace.go:171","msg":"trace[262275443] linearizableReadLoop","detail":"{readStateIndex:5562; appliedIndex:5560; }","duration":"379.970021ms","start":"2026-06-05T04:41:59.394007Z","end":"2026-06-05T04:41:59.773977Z","steps":["trace[262275443] 'read index received' (duration: 355.296067ms)","trace[262275443] 'applied index is now lower than readState.Index' (duration: 24.672704ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:41:59.774258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.223615ms","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-06-05T04:41:59.77467Z","caller":"traceutil/trace.go:171","msg":"trace[898931075] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5366; }","duration":"380.65719ms","start":"2026-06-05T04:41:59.39397Z","end":"2026-06-05T04:41:59.774627Z","steps":["trace[898931075] 'agreement among raft nodes before linearized reading' (duration: 380.200824ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:59.77437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.282907ms","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-06-05T04:41:59.775038Z","caller":"traceutil/trace.go:171","msg":"trace[826367403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5366; }","duration":"164.886506ms","start":"2026-06-05T04:41:59.609995Z","end":"2026-06-05T04:41:59.774881Z","steps":["trace[826367403] 'agreement among raft nodes before linearized reading' (duration: 164.182176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:59.774802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:41:59.393958Z","time spent":"380.822742ms","remote":"127.0.0.1:53852","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-06-05T04:41:59.774388Z","caller":"traceutil/trace.go:171","msg":"trace[309721065] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"373.759471ms","start":"2026-06-05T04:41:59.400602Z","end":"2026-06-05T04:41:59.774362Z","steps":["trace[309721065] 'process raft request' (duration: 373.337095ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:41:59.774114Z","caller":"traceutil/trace.go:171","msg":"trace[1232846198] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"387.913634ms","start":"2026-06-05T04:41:59.386168Z","end":"2026-06-05T04:41:59.774082Z","steps":["trace[1232846198] 'process raft request' (duration: 387.67193ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:59.774453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.439318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T04:41:59.775813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:41:59.400586Z","time spent":"374.855787ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:41:59.775929Z","caller":"traceutil/trace.go:171","msg":"trace[1803022591] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:5366; }","duration":"121.876988ms","start":"2026-06-05T04:41:59.653965Z","end":"2026-06-05T04:41:59.775842Z","steps":["trace[1803022591] 'agreement among raft nodes before linearized reading' (duration: 120.422337ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:41:59.775823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:41:59.38614Z","time spent":"389.615479ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:42:00.597181Z","caller":"traceutil/trace.go:171","msg":"trace[2132738523] linearizableReadLoop","detail":"{readStateIndex:5567; appliedIndex:5566; }","duration":"221.667574ms","start":"2026-06-05T04:42:00.375476Z","end":"2026-06-05T04:42:00.597144Z","steps":["trace[2132738523] 'read index received' (duration: 221.279929ms)","trace[2132738523] 'applied index is now lower than readState.Index' (duration: 385.765µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:42:00.597312Z","caller":"traceutil/trace.go:171","msg":"trace[1113002446] transaction","detail":"{read_only:false; response_revision:5370; number_of_response:1; }","duration":"380.116283ms","start":"2026-06-05T04:42:00.217129Z","end":"2026-06-05T04:42:00.597245Z","steps":["trace[1113002446] 'process raft request' (duration: 379.766218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:42:00.597461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.959699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T04:42:00.597548Z","caller":"traceutil/trace.go:171","msg":"trace[1522953228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5370; }","duration":"222.10426ms","start":"2026-06-05T04:42:00.375424Z","end":"2026-06-05T04:42:00.597529Z","steps":["trace[1522953228] 'agreement among raft nodes before linearized reading' (duration: 221.932688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:42:00.597732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.324856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-05T04:42:00.597772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:42:00.217106Z","time spent":"380.324836ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7827,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:42:00.597846Z","caller":"traceutil/trace.go:171","msg":"trace[432394456] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:5370; }","duration":"130.501228ms","start":"2026-06-05T04:42:00.467298Z","end":"2026-06-05T04:42:00.597799Z","steps":["trace[432394456] 'agreement among raft nodes before linearized reading' (duration: 130.073892ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:42:00.827546Z","caller":"traceutil/trace.go:171","msg":"trace[388335330] transaction","detail":"{read_only:false; response_revision:5371; number_of_response:1; }","duration":"209.156915ms","start":"2026-06-05T04:42:00.618358Z","end":"2026-06-05T04:42:00.827515Z","steps":["trace[388335330] 'process raft request' (duration: 189.890939ms)","trace[388335330] 'compare' (duration: 19.105494ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:42:11.4161Z","caller":"traceutil/trace.go:171","msg":"trace[1889989149] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"106.925593ms","start":"2026-06-05T04:42:11.30914Z","end":"2026-06-05T04:42:11.416065Z","steps":["trace[1889989149] 'process raft request' (duration: 66.131239ms)","trace[1889989149] 'compare' (duration: 40.397878ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:42:11.41626Z","caller":"traceutil/trace.go:171","msg":"trace[1354803448] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"107.018745ms","start":"2026-06-05T04:42:11.309185Z","end":"2026-06-05T04:42:11.416204Z","steps":["trace[1354803448] 'process raft request' (duration: 106.607318ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:42:16.442314Z","caller":"traceutil/trace.go:171","msg":"trace[1212755613] linearizableReadLoop","detail":"{readStateIndex:5703; appliedIndex:5702; }","duration":"112.405426ms","start":"2026-06-05T04:42:16.329862Z","end":"2026-06-05T04:42:16.442268Z","steps":["trace[1212755613] 'read index received' (duration: 106.590675ms)","trace[1212755613] 'applied index is now lower than readState.Index' (duration: 5.813151ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:42:16.442388Z","caller":"traceutil/trace.go:171","msg":"trace[1366267174] transaction","detail":"{read_only:false; response_revision:5502; number_of_response:1; }","duration":"114.753578ms","start":"2026-06-05T04:42:16.327596Z","end":"2026-06-05T04:42:16.44235Z","steps":["trace[1366267174] 'process raft request' (duration: 108.908757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:42:16.442673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.779362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-fernet-setup-ddckq\" ","response":"range_response_count:1 size:7322"} {"level":"info","ts":"2026-06-05T04:42:16.442749Z","caller":"traceutil/trace.go:171","msg":"trace[1020632268] range","detail":"{range_begin:/registry/pods/openstack/keystone-fernet-setup-ddckq; range_end:; response_count:1; response_revision:5502; }","duration":"112.890134ms","start":"2026-06-05T04:42:16.329834Z","end":"2026-06-05T04:42:16.442725Z","steps":["trace[1020632268] 'agreement among raft nodes before linearized reading' (duration: 112.625461ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:42:17.469081Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2878} {"level":"info","ts":"2026-06-05T04:42:17.532581Z","caller":"traceutil/trace.go:171","msg":"trace[911451632] compact","detail":"{revision:2878; response_revision:5509; }","duration":"147.045806ms","start":"2026-06-05T04:42:17.385488Z","end":"2026-06-05T04:42:17.532534Z","steps":["trace[911451632] 'check and update compact revision' (duration: 62.642152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:42:17.808004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.054045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T04:42:17.808456Z","caller":"traceutil/trace.go:171","msg":"trace[1598818772] transaction","detail":"{read_only:false; response_revision:5510; number_of_response:1; }","duration":"307.899244ms","start":"2026-06-05T04:42:17.500328Z","end":"2026-06-05T04:42:17.808227Z","steps":["trace[1598818772] 'process raft request' (duration: 15.309393ms)","trace[1598818772] 'compare' (duration: 16.898385ms)","trace[1598818772] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; req_size:537; } (duration: 274.897485ms)"],"step_count":3} {"level":"warn","ts":"2026-06-05T04:42:17.808622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:42:17.50029Z","time spent":"308.252059ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T04:42:17.925655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.361373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T04:42:17.925845Z","caller":"traceutil/trace.go:171","msg":"trace[85262664] transaction","detail":"{read_only:false; response_revision:5511; number_of_response:1; }","duration":"353.765303ms","start":"2026-06-05T04:42:17.572038Z","end":"2026-06-05T04:42:17.925803Z","steps":["trace[85262664] 'process raft request' (duration: 236.168457ms)","trace[85262664] 'compare' (duration: 14.834207ms)","trace[85262664] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/events/openstack/keystone-fernet-setup-ddckq.18b615ad7e8ebf9d; req_size:752; } (duration: 102.169272ms)"],"step_count":3} {"level":"warn","ts":"2026-06-05T04:42:17.925952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:42:17.572009Z","time spent":"353.903975ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":755,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T04:42:17.960344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.723152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/keystone-fernet-keys\" ","response":"range_response_count:1 size:345"} {"level":"info","ts":"2026-06-05T04:42:17.960407Z","caller":"traceutil/trace.go:171","msg":"trace[1766369038] range","detail":"{range_begin:/registry/secrets/openstack/keystone-fernet-keys; range_end:; response_count:1; response_revision:5511; }","duration":"123.852844ms","start":"2026-06-05T04:42:17.836536Z","end":"2026-06-05T04:42:17.960389Z","steps":["trace[1766369038] 'agreement among raft nodes before linearized reading' (duration: 89.310993ms)","trace[1766369038] 'range keys from in-memory index tree' (duration: 34.375509ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:42:18.006697Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2878,"took":"536.171851ms","hash":4252576870,"current-db-size-bytes":52895744,"current-db-size":"53 MB","current-db-size-in-use-bytes":28921856,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-05T04:42:18.006813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4252576870,"revision":2878,"compact-revision":-1} {"level":"info","ts":"2026-06-05T04:42:18.007268Z","caller":"traceutil/trace.go:171","msg":"trace[739674271] transaction","detail":"{read_only:false; response_revision:5512; number_of_response:1; }","duration":"117.596246ms","start":"2026-06-05T04:42:17.889478Z","end":"2026-06-05T04:42:18.007074Z","steps":["trace[739674271] 'process raft request' (duration: 36.316775ms)","trace[739674271] 'compare' (duration: 34.41368ms)","trace[739674271] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/08db1feb.percona.com; req_size:507; } (duration: 46.628168ms)"],"step_count":3} {"level":"warn","ts":"2026-06-05T04:42:18.008502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.629105ms","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-06-05T04:42:18.00858Z","caller":"traceutil/trace.go:171","msg":"trace[582448761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5513; }","duration":"101.762867ms","start":"2026-06-05T04:42:17.9068Z","end":"2026-06-05T04:42:18.008562Z","steps":["trace[582448761] 'agreement among raft nodes before linearized reading' (duration: 100.385848ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:42:29.842505Z","caller":"traceutil/trace.go:171","msg":"trace[336113886] transaction","detail":"{read_only:false; response_revision:5597; number_of_response:1; }","duration":"320.490009ms","start":"2026-06-05T04:42:29.521984Z","end":"2026-06-05T04:42:29.842474Z","steps":["trace[336113886] 'process raft request' (duration: 307.555473ms)","trace[336113886] 'compare' (duration: 12.600552ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:42:29.842668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T04:42:29.52195Z","time spent":"320.63031ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T04:42:33.281455Z","caller":"traceutil/trace.go:171","msg":"trace[1468834104] linearizableReadLoop","detail":"{readStateIndex:5828; appliedIndex:5827; }","duration":"219.006924ms","start":"2026-06-05T04:42:33.062425Z","end":"2026-06-05T04:42:33.281432Z","steps":["trace[1468834104] 'read index received' (duration: 218.760851ms)","trace[1468834104] 'applied index is now lower than readState.Index' (duration: 245.123µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:42:33.281687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.240947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-05T04:42:33.281732Z","caller":"traceutil/trace.go:171","msg":"trace[808440216] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5623; }","duration":"219.341859ms","start":"2026-06-05T04:42:33.062376Z","end":"2026-06-05T04:42:33.281718Z","steps":["trace[808440216] 'agreement among raft nodes before linearized reading' (duration: 219.149896ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:42:33.282054Z","caller":"traceutil/trace.go:171","msg":"trace[1022854545] transaction","detail":"{read_only:false; response_revision:5623; number_of_response:1; }","duration":"237.088871ms","start":"2026-06-05T04:42:33.044954Z","end":"2026-06-05T04:42:33.282043Z","steps":["trace[1022854545] 'process raft request' (duration: 236.30118ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:42:33.555988Z","caller":"traceutil/trace.go:171","msg":"trace[65561931] transaction","detail":"{read_only:false; response_revision:5624; number_of_response:1; }","duration":"269.310898ms","start":"2026-06-05T04:42:33.286644Z","end":"2026-06-05T04:42:33.555955Z","steps":["trace[65561931] 'process raft request' (duration: 264.006397ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:44:55.184704Z","caller":"traceutil/trace.go:171","msg":"trace[1407219031] transaction","detail":"{read_only:false; response_revision:6590; number_of_response:1; }","duration":"113.697684ms","start":"2026-06-05T04:44:55.070983Z","end":"2026-06-05T04:44:55.18468Z","steps":["trace[1407219031] 'process raft request' (duration: 113.537942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:44:58.937527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.019576ms","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-06-05T04:44:58.937714Z","caller":"traceutil/trace.go:171","msg":"trace[1698093386] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:6610; }","duration":"109.219879ms","start":"2026-06-05T04:44:58.828465Z","end":"2026-06-05T04:44:58.937685Z","steps":["trace[1698093386] 'range keys from in-memory index tree' (duration: 108.997476ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:45:18.648387Z","caller":"traceutil/trace.go:171","msg":"trace[798064932] transaction","detail":"{read_only:false; response_revision:6763; number_of_response:1; }","duration":"118.493395ms","start":"2026-06-05T04:45:18.529872Z","end":"2026-06-05T04:45:18.648366Z","steps":["trace[798064932] 'process raft request' (duration: 118.329054ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:45:18.774614Z","caller":"traceutil/trace.go:171","msg":"trace[323732989] linearizableReadLoop","detail":"{readStateIndex:7011; appliedIndex:7009; }","duration":"202.98012ms","start":"2026-06-05T04:45:18.571609Z","end":"2026-06-05T04:45:18.774589Z","steps":["trace[323732989] 'read index received' (duration: 76.591363ms)","trace[323732989] 'applied index is now lower than readState.Index' (duration: 126.387747ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:45:18.774813Z","caller":"traceutil/trace.go:171","msg":"trace[189092716] transaction","detail":"{read_only:false; response_revision:6764; number_of_response:1; }","duration":"242.501585ms","start":"2026-06-05T04:45:18.532294Z","end":"2026-06-05T04:45:18.774796Z","steps":["trace[189092716] 'process raft request' (duration: 218.794726ms)","trace[189092716] 'compare' (duration: 23.391365ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:45:18.774996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.365715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T04:45:18.775029Z","caller":"traceutil/trace.go:171","msg":"trace[1220821042] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:6764; }","duration":"203.451336ms","start":"2026-06-05T04:45:18.571568Z","end":"2026-06-05T04:45:18.775019Z","steps":["trace[1220821042] 'agreement among raft nodes before linearized reading' (duration: 203.375714ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:45:18.842313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.821066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-05T04:45:18.842397Z","caller":"traceutil/trace.go:171","msg":"trace[543439560] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:6765; }","duration":"184.954127ms","start":"2026-06-05T04:45:18.657414Z","end":"2026-06-05T04:45:18.842368Z","steps":["trace[543439560] 'agreement among raft nodes before linearized reading' (duration: 184.614102ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:46:36.073281Z","caller":"traceutil/trace.go:171","msg":"trace[540093502] transaction","detail":"{read_only:false; response_revision:7182; number_of_response:1; }","duration":"105.488279ms","start":"2026-06-05T04:46:35.967763Z","end":"2026-06-05T04:46:36.073252Z","steps":["trace[540093502] 'process raft request' (duration: 105.345815ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:46:36.07753Z","caller":"traceutil/trace.go:171","msg":"trace[1915781291] transaction","detail":"{read_only:false; response_revision:7183; number_of_response:1; }","duration":"109.137623ms","start":"2026-06-05T04:46:35.968372Z","end":"2026-06-05T04:46:36.07751Z","steps":["trace[1915781291] 'process raft request' (duration: 108.863975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:46:36.300613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.474434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-05T04:46:36.300681Z","caller":"traceutil/trace.go:171","msg":"trace[216193340] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7184; }","duration":"137.592707ms","start":"2026-06-05T04:46:36.163073Z","end":"2026-06-05T04:46:36.300665Z","steps":["trace[216193340] 'range keys from in-memory index tree' (duration: 137.33372ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:46:52.182768Z","caller":"traceutil/trace.go:171","msg":"trace[965330646] transaction","detail":"{read_only:false; response_revision:7263; number_of_response:1; }","duration":"176.774931ms","start":"2026-06-05T04:46:52.005964Z","end":"2026-06-05T04:46:52.182739Z","steps":["trace[965330646] 'process raft request' (duration: 176.579936ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:46:52.409444Z","caller":"traceutil/trace.go:171","msg":"trace[571226179] transaction","detail":"{read_only:false; response_revision:7265; number_of_response:1; }","duration":"149.510718ms","start":"2026-06-05T04:46:52.2599Z","end":"2026-06-05T04:46:52.409411Z","steps":["trace[571226179] 'process raft request' (duration: 96.447128ms)","trace[571226179] 'compare' (duration: 52.510207ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:46:54.36366Z","caller":"traceutil/trace.go:171","msg":"trace[412001139] transaction","detail":"{read_only:false; response_revision:7274; number_of_response:1; }","duration":"101.400492ms","start":"2026-06-05T04:46:54.262228Z","end":"2026-06-05T04:46:54.363629Z","steps":["trace[412001139] 'process raft request' (duration: 61.198518ms)","trace[412001139] 'compare' (duration: 40.086582ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T04:46:57.500758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.964678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T04:46:57.500865Z","caller":"traceutil/trace.go:171","msg":"trace[1780121827] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:7289; }","duration":"122.191774ms","start":"2026-06-05T04:46:57.378641Z","end":"2026-06-05T04:46:57.500832Z","steps":["trace[1780121827] 'count revisions from in-memory index tree' (duration: 121.841366ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:46:59.9151Z","caller":"traceutil/trace.go:171","msg":"trace[603832657] transaction","detail":"{read_only:false; response_revision:7300; number_of_response:1; }","duration":"128.027967ms","start":"2026-06-05T04:46:59.787046Z","end":"2026-06-05T04:46:59.915074Z","steps":["trace[603832657] 'process raft request' (duration: 127.866703ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:47:00.761115Z","caller":"traceutil/trace.go:171","msg":"trace[590128545] linearizableReadLoop","detail":"{readStateIndex:7571; appliedIndex:7569; }","duration":"253.244792ms","start":"2026-06-05T04:47:00.507846Z","end":"2026-06-05T04:47:00.761091Z","steps":["trace[590128545] 'read index received' (duration: 42.584551ms)","trace[590128545] 'applied index is now lower than readState.Index' (duration: 210.656271ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:47:00.761237Z","caller":"traceutil/trace.go:171","msg":"trace[1913564091] transaction","detail":"{read_only:false; response_revision:7303; number_of_response:1; }","duration":"255.681203ms","start":"2026-06-05T04:47:00.50552Z","end":"2026-06-05T04:47:00.761201Z","steps":["trace[1913564091] 'process raft request' (duration: 255.374076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:47:00.761435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.567801ms","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-06-05T04:47:00.761517Z","caller":"traceutil/trace.go:171","msg":"trace[78948001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7303; }","duration":"253.692343ms","start":"2026-06-05T04:47:00.507806Z","end":"2026-06-05T04:47:00.761498Z","steps":["trace[78948001] 'agreement among raft nodes before linearized reading' (duration: 253.416307ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:47:00.766598Z","caller":"traceutil/trace.go:171","msg":"trace[1818157817] transaction","detail":"{read_only:false; response_revision:7304; number_of_response:1; }","duration":"214.796904ms","start":"2026-06-05T04:47:00.551778Z","end":"2026-06-05T04:47:00.766575Z","steps":["trace[1818157817] 'process raft request' (duration: 214.593369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:47:00.766726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.521302ms","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-06-05T04:47:00.766818Z","caller":"traceutil/trace.go:171","msg":"trace[1551148198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7304; }","duration":"228.733067ms","start":"2026-06-05T04:47:00.538069Z","end":"2026-06-05T04:47:00.766802Z","steps":["trace[1551148198] 'agreement among raft nodes before linearized reading' (duration: 228.42749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T04:47:03.035934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.097405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T04:47:03.03675Z","caller":"traceutil/trace.go:171","msg":"trace[1475664550] transaction","detail":"{read_only:false; response_revision:7317; number_of_response:1; }","duration":"195.845336ms","start":"2026-06-05T04:47:02.840881Z","end":"2026-06-05T04:47:03.036726Z","steps":["trace[1475664550] 'process raft request' (duration: 195.228731ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:47:03.036836Z","caller":"traceutil/trace.go:171","msg":"trace[1382667792] transaction","detail":"{read_only:false; response_revision:7316; number_of_response:1; }","duration":"196.081731ms","start":"2026-06-05T04:47:02.8407Z","end":"2026-06-05T04:47:03.036782Z","steps":["trace[1382667792] 'process raft request' (duration: 70.010142ms)","trace[1382667792] 'compare' (duration: 124.979742ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:47:03.612214Z","caller":"traceutil/trace.go:171","msg":"trace[223601993] transaction","detail":"{read_only:false; response_revision:7321; number_of_response:1; }","duration":"162.439548ms","start":"2026-06-05T04:47:03.449735Z","end":"2026-06-05T04:47:03.612175Z","steps":["trace[223601993] 'process raft request' (duration: 146.530188ms)","trace[223601993] 'compare' (duration: 15.723496ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:47:05.146633Z","caller":"traceutil/trace.go:171","msg":"trace[159671226] transaction","detail":"{read_only:false; response_revision:7327; number_of_response:1; }","duration":"100.617692ms","start":"2026-06-05T04:47:05.04599Z","end":"2026-06-05T04:47:05.146608Z","steps":["trace[159671226] 'process raft request' (duration: 100.472368ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:47:06.07632Z","caller":"traceutil/trace.go:171","msg":"trace[1205538888] transaction","detail":"{read_only:false; response_revision:7332; number_of_response:1; }","duration":"100.040853ms","start":"2026-06-05T04:47:05.976261Z","end":"2026-06-05T04:47:06.076302Z","steps":["trace[1205538888] 'process raft request' (duration: 99.89781ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:47:07.752531Z","caller":"traceutil/trace.go:171","msg":"trace[660394551] transaction","detail":"{read_only:false; response_revision:7340; number_of_response:1; }","duration":"106.943046ms","start":"2026-06-05T04:47:07.645556Z","end":"2026-06-05T04:47:07.752499Z","steps":["trace[660394551] 'process raft request' (duration: 75.351327ms)","trace[660394551] 'compare' (duration: 31.438045ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:47:10.544526Z","caller":"traceutil/trace.go:171","msg":"trace[1063073989] transaction","detail":"{read_only:false; response_revision:7353; number_of_response:1; }","duration":"127.828377ms","start":"2026-06-05T04:47:10.416665Z","end":"2026-06-05T04:47:10.544493Z","steps":["trace[1063073989] 'process raft request' (duration: 127.627063ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:47:10.656595Z","caller":"traceutil/trace.go:171","msg":"trace[720332009] transaction","detail":"{read_only:false; response_revision:7354; number_of_response:1; }","duration":"107.876515ms","start":"2026-06-05T04:47:10.548699Z","end":"2026-06-05T04:47:10.656575Z","steps":["trace[720332009] 'process raft request' (duration: 107.766542ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T04:47:11.761564Z","caller":"traceutil/trace.go:171","msg":"trace[1718063948] transaction","detail":"{read_only:false; response_revision:7361; number_of_response:1; }","duration":"107.485191ms","start":"2026-06-05T04:47:11.654046Z","end":"2026-06-05T04:47:11.761531Z","steps":["trace[1718063948] 'process raft request' (duration: 46.25778ms)","trace[1718063948] 'compare' (duration: 61.151208ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T04:47:17.539076Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5507} {"level":"info","ts":"2026-06-05T04:47:17.656083Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5507,"took":"114.890789ms","hash":2820951369,"current-db-size-bytes":52895744,"current-db-size":"53 MB","current-db-size-in-use-bytes":18477056,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-05T04:47:17.656184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2820951369,"revision":5507,"compact-revision":2878}