{"level":"warn","ts":"2026-06-16T11:30:57.131603Z","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-16T11:30:57.131681Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-16T11:30:57.131745Z","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-16T11:30:57.131755Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-06-16T11:30:57.131772Z","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-16T11:30:57.132359Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-06-16T11:30:57.132425Z","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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-16T11:30:57.136074Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.462863ms"} {"level":"info","ts":"2026-06-16T11:30:57.140917Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-06-16T11:30:57.140985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-06-16T11:30:57.141007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-06-16T11:30:57.141018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-16T11:30:57.14103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-06-16T11:30:57.141067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-06-16T11:30:57.146646Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-16T11:30:57.147833Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-16T11:30:57.148968Z","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-16T11:30:57.151006Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-16T11:30:57.162928Z","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-16T11:30:57.162998Z","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-16T11:30:57.16313Z","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-16T11:30:57.163145Z","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-16T11:30:57.163115Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-16T11:30:57.163447Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-06-16T11:30:57.163494Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-06-16T11:30:57.163452Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-16T11:30:57.163496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-06-16T11:30:57.163486Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-16T11:30:57.1636Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-06-16T11:30:57.341435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-06-16T11:30:57.341511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-06-16T11:30:57.341553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-06-16T11:30:57.341574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-06-16T11:30:57.341585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-06-16T11:30:57.341597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-06-16T11:30:57.34161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-06-16T11:30:57.342496Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-06-16T11:30:57.342739Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T11:30:57.342792Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T11:30:57.342894Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-16T11:30:57.342931Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-16T11:30:57.342948Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T11:30:57.34588Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-06-16T11:30:57.345918Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-16T11:30:57.346106Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T11:30:57.346246Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T11:30:57.346318Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T11:30:59.470497Z","caller":"traceutil/trace.go:171","msg":"trace[1793729945] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"121.796129ms","start":"2026-06-16T11:30:59.348675Z","end":"2026-06-16T11:30:59.470471Z","steps":["trace[1793729945] 'process raft request' (duration: 121.656145ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:00.909221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271808448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-16T11:31:00.909336Z","caller":"traceutil/trace.go:171","msg":"trace[578378694] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:71; }","duration":"1.271984194s","start":"2026-06-16T11:30:59.637329Z","end":"2026-06-16T11:31:00.909313Z","steps":["trace[578378694] 'range keys from in-memory index tree' (duration: 1.271621623s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:00.909382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:30:59.63731Z","time spent":"1.272060977s","remote":"127.0.0.1:48142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-06-16T11:31:00.90923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267090288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-16T11:31:00.909473Z","caller":"traceutil/trace.go:171","msg":"trace[232443010] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:71; }","duration":"1.267414277s","start":"2026-06-16T11:30:59.642029Z","end":"2026-06-16T11:31:00.909443Z","steps":["trace[232443010] 'range keys from in-memory index tree' (duration: 1.266884701s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:00.909597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:30:59.641998Z","time spent":"1.267580052s","remote":"127.0.0.1:48114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-06-16T11:31:00.909185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258810462s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-16T11:31:00.909841Z","caller":"traceutil/trace.go:171","msg":"trace[716044704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"1.259484523s","start":"2026-06-16T11:30:59.650311Z","end":"2026-06-16T11:31:00.909796Z","steps":["trace[716044704] 'range keys from in-memory index tree' (duration: 1.25873255s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:00.909966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:30:59.650301Z","time spent":"1.259626259s","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T11:31:01.300855Z","caller":"traceutil/trace.go:171","msg":"trace[503322022] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"387.75427ms","start":"2026-06-16T11:31:00.913081Z","end":"2026-06-16T11:31:01.300835Z","steps":["trace[503322022] 'read index received' (duration: 387.605726ms)","trace[503322022] 'applied index is now lower than readState.Index' (duration: 147.894µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:31:01.30095Z","caller":"traceutil/trace.go:171","msg":"trace[122644234] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"387.929436ms","start":"2026-06-16T11:31:00.912994Z","end":"2026-06-16T11:31:01.300923Z","steps":["trace[122644234] 'process raft request' (duration: 387.74383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:01.301175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.0692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-16T11:31:01.301289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.637872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-16T11:31:01.301363Z","caller":"traceutil/trace.go:171","msg":"trace[1869409864] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:72; }","duration":"388.247086ms","start":"2026-06-16T11:31:00.91304Z","end":"2026-06-16T11:31:01.301287Z","steps":["trace[1869409864] 'agreement among raft nodes before linearized reading' (duration: 387.967198ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:01.3015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:31:00.913021Z","time spent":"388.464222ms","remote":"127.0.0.1:48130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"info","ts":"2026-06-16T11:31:01.301375Z","caller":"traceutil/trace.go:171","msg":"trace[178883942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"350.763816ms","start":"2026-06-16T11:31:00.950589Z","end":"2026-06-16T11:31:01.301353Z","steps":["trace[178883942] 'agreement among raft nodes before linearized reading' (duration: 350.603611ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:01.301777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:31:00.950574Z","time spent":"351.18339ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T11:31:01.302197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:31:00.912971Z","time spent":"388.288818ms","remote":"127.0.0.1:48142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T11:31:01.549704Z","caller":"traceutil/trace.go:171","msg":"trace[975582718] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"242.385768ms","start":"2026-06-16T11:31:01.307292Z","end":"2026-06-16T11:31:01.549678Z","steps":["trace[975582718] 'read index received' (duration: 211.164287ms)","trace[975582718] 'applied index is now lower than readState.Index' (duration: 31.220571ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:31:01.549874Z","caller":"traceutil/trace.go:171","msg":"trace[1970021834] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"243.365547ms","start":"2026-06-16T11:31:01.306497Z","end":"2026-06-16T11:31:01.549862Z","steps":["trace[1970021834] 'process raft request' (duration: 212.052984ms)","trace[1970021834] 'compare' (duration: 31.028463ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:31:01.550233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.931954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-16T11:31:01.55027Z","caller":"traceutil/trace.go:171","msg":"trace[778988503] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:73; }","duration":"242.999136ms","start":"2026-06-16T11:31:01.307263Z","end":"2026-06-16T11:31:01.550262Z","steps":["trace[778988503] 'agreement among raft nodes before linearized reading' (duration: 242.879542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:01.550341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.167704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-16T11:31:01.550414Z","caller":"traceutil/trace.go:171","msg":"trace[1140166378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:74; }","duration":"209.263818ms","start":"2026-06-16T11:31:01.341131Z","end":"2026-06-16T11:31:01.550395Z","steps":["trace[1140166378] 'agreement among raft nodes before linearized reading' (duration: 209.121473ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:34.345998Z","caller":"traceutil/trace.go:171","msg":"trace[1825000282] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"146.932235ms","start":"2026-06-16T11:31:34.199046Z","end":"2026-06-16T11:31:34.345979Z","steps":["trace[1825000282] 'process raft request' (duration: 146.762901ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:34.353226Z","caller":"traceutil/trace.go:171","msg":"trace[79144451] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"148.679855ms","start":"2026-06-16T11:31:34.204531Z","end":"2026-06-16T11:31:34.353211Z","steps":["trace[79144451] 'process raft request' (duration: 148.174743ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:34.353327Z","caller":"traceutil/trace.go:171","msg":"trace[498706964] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"137.388993ms","start":"2026-06-16T11:31:34.215925Z","end":"2026-06-16T11:31:34.353314Z","steps":["trace[498706964] 'process raft request' (duration: 136.961794ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:34.353381Z","caller":"traceutil/trace.go:171","msg":"trace[1640258313] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"148.316367ms","start":"2026-06-16T11:31:34.205059Z","end":"2026-06-16T11:31:34.353375Z","steps":["trace[1640258313] 'process raft request' (duration: 147.717643ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:34.353399Z","caller":"traceutil/trace.go:171","msg":"trace[1139993841] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"143.201037ms","start":"2026-06-16T11:31:34.210194Z","end":"2026-06-16T11:31:34.353395Z","steps":["trace[1139993841] 'process raft request' (duration: 142.608814ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:34.353434Z","caller":"traceutil/trace.go:171","msg":"trace[1511068033] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"137.569717ms","start":"2026-06-16T11:31:34.215859Z","end":"2026-06-16T11:31:34.353428Z","steps":["trace[1511068033] 'process raft request' (duration: 136.977703ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:34.353452Z","caller":"traceutil/trace.go:171","msg":"trace[2096782399] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"137.546736ms","start":"2026-06-16T11:31:34.215901Z","end":"2026-06-16T11:31:34.353448Z","steps":["trace[2096782399] 'process raft request' (duration: 136.961773ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:34.353421Z","caller":"traceutil/trace.go:171","msg":"trace[152991514] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"129.360816ms","start":"2026-06-16T11:31:34.224034Z","end":"2026-06-16T11:31:34.353395Z","steps":["trace[152991514] 'process raft request' (duration: 128.945177ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:34.353487Z","caller":"traceutil/trace.go:171","msg":"trace[1387037934] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"137.393973ms","start":"2026-06-16T11:31:34.216054Z","end":"2026-06-16T11:31:34.353448Z","steps":["trace[1387037934] 'process raft request' (duration: 136.859401ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:34.35351Z","caller":"traceutil/trace.go:171","msg":"trace[707793298] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"133.796939ms","start":"2026-06-16T11:31:34.219703Z","end":"2026-06-16T11:31:34.3535Z","steps":["trace[707793298] 'process raft request' (duration: 133.242146ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:34.979758Z","caller":"traceutil/trace.go:171","msg":"trace[2098371371] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:602; }","duration":"110.088098ms","start":"2026-06-16T11:31:34.869652Z","end":"2026-06-16T11:31:34.979741Z","steps":["trace[2098371371] 'read index received' (duration: 58.572621ms)","trace[2098371371] 'applied index is now lower than readState.Index' (duration: 51.514827ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:31:34.981706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.037433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T11:31:34.981754Z","caller":"traceutil/trace.go:171","msg":"trace[1107881299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"112.110555ms","start":"2026-06-16T11:31:34.869626Z","end":"2026-06-16T11:31:34.981736Z","steps":["trace[1107881299] 'agreement among raft nodes before linearized reading' (duration: 110.18603ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:34.98318Z","caller":"traceutil/trace.go:171","msg":"trace[391044712] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"172.17446ms","start":"2026-06-16T11:31:34.810989Z","end":"2026-06-16T11:31:34.983163Z","steps":["trace[391044712] 'process raft request' (duration: 117.11344ms)","trace[391044712] 'compare' (duration: 50.76ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:31:34.983273Z","caller":"traceutil/trace.go:171","msg":"trace[1875542221] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"147.18675ms","start":"2026-06-16T11:31:34.836061Z","end":"2026-06-16T11:31:34.983248Z","steps":["trace[1875542221] 'process raft request' (duration: 143.207348ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:35.664635Z","caller":"traceutil/trace.go:171","msg":"trace[1357113615] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"450.190627ms","start":"2026-06-16T11:31:35.21442Z","end":"2026-06-16T11:31:35.664611Z","steps":["trace[1357113615] 'process raft request' (duration: 450.003243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:35.664928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:31:35.214401Z","time spent":"450.29864ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:31:35.859999Z","caller":"traceutil/trace.go:171","msg":"trace[1638607959] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"524.629392ms","start":"2026-06-16T11:31:35.33535Z","end":"2026-06-16T11:31:35.859979Z","steps":["trace[1638607959] 'process raft request' (duration: 524.550901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:35.8602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:31:35.335332Z","time spent":"524.808907ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T11:31:35.860817Z","caller":"traceutil/trace.go:171","msg":"trace[1445192155] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"537.179472ms","start":"2026-06-16T11:31:35.323624Z","end":"2026-06-16T11:31:35.860804Z","steps":["trace[1445192155] 'process raft request' (duration: 488.762977ms)","trace[1445192155] 'compare' (duration: 47.266879ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:31:35.860883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:31:35.323603Z","time spent":"537.240273ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:31:35.877232Z","caller":"traceutil/trace.go:171","msg":"trace[731808212] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:614; }","duration":"100.943277ms","start":"2026-06-16T11:31:35.776272Z","end":"2026-06-16T11:31:35.877216Z","steps":["trace[731808212] 'read index received' (duration: 36.128523ms)","trace[731808212] 'applied index is now lower than readState.Index' (duration: 64.814043ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:31:35.877463Z","caller":"traceutil/trace.go:171","msg":"trace[2045796893] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"197.759328ms","start":"2026-06-16T11:31:35.679694Z","end":"2026-06-16T11:31:35.877453Z","steps":["trace[2045796893] 'process raft request' (duration: 197.445561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:35.877612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.336055ms","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-16T11:31:35.877639Z","caller":"traceutil/trace.go:171","msg":"trace[35435726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"101.411617ms","start":"2026-06-16T11:31:35.776221Z","end":"2026-06-16T11:31:35.877633Z","steps":["trace[35435726] 'agreement among raft nodes before linearized reading' (duration: 101.352886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:36.537645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.880588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T11:31:36.538071Z","caller":"traceutil/trace.go:171","msg":"trace[1541158078] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"625.03734ms","start":"2026-06-16T11:31:35.912996Z","end":"2026-06-16T11:31:36.538033Z","steps":["trace[1541158078] 'process raft request' (duration: 54.604899ms)","trace[1541158078] 'compare' (duration: 569.798296ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:31:36.538139Z","caller":"traceutil/trace.go:171","msg":"trace[208651828] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"618.912889ms","start":"2026-06-16T11:31:35.919185Z","end":"2026-06-16T11:31:36.538098Z","steps":["trace[208651828] 'read index received' (duration: 48.370086ms)","trace[208651828] 'applied index is now lower than readState.Index' (duration: 570.537363ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:31:36.538323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:31:35.912982Z","time spent":"625.189004ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:31:36.538338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.143774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:59631"} {"level":"info","ts":"2026-06-16T11:31:36.53845Z","caller":"traceutil/trace.go:171","msg":"trace[42632737] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:596; }","duration":"619.274448ms","start":"2026-06-16T11:31:35.919159Z","end":"2026-06-16T11:31:36.538433Z","steps":["trace[42632737] 'agreement among raft nodes before linearized reading' (duration: 619.023932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:36.538499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:31:35.919145Z","time spent":"619.344789ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":59655,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-06-16T11:31:36.53959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.787606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-16T11:31:36.539632Z","caller":"traceutil/trace.go:171","msg":"trace[1061984990] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"142.875127ms","start":"2026-06-16T11:31:36.396747Z","end":"2026-06-16T11:31:36.539622Z","steps":["trace[1061984990] 'agreement among raft nodes before linearized reading' (duration: 142.792036ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:36.540839Z","caller":"traceutil/trace.go:171","msg":"trace[1578686423] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"497.779414ms","start":"2026-06-16T11:31:36.043049Z","end":"2026-06-16T11:31:36.540829Z","steps":["trace[1578686423] 'process raft request' (duration: 495.564473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:36.540934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:31:36.043023Z","time spent":"497.849234ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:31:36.541095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.937391ms","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-16T11:31:36.541129Z","caller":"traceutil/trace.go:171","msg":"trace[763942207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"144.025163ms","start":"2026-06-16T11:31:36.397096Z","end":"2026-06-16T11:31:36.541121Z","steps":["trace[763942207] 'agreement among raft nodes before linearized reading' (duration: 143.943731ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:40.843484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.752176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T11:31:40.843635Z","caller":"traceutil/trace.go:171","msg":"trace[166029886] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"254.999541ms","start":"2026-06-16T11:31:40.588597Z","end":"2026-06-16T11:31:40.843596Z","steps":["trace[166029886] 'process raft request' (duration: 92.009869ms)","trace[166029886] 'compare' (duration: 162.499451ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:31:40.941582Z","caller":"traceutil/trace.go:171","msg":"trace[1171473070] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"241.981012ms","start":"2026-06-16T11:31:40.699564Z","end":"2026-06-16T11:31:40.941545Z","steps":["trace[1171473070] 'process raft request' (duration: 234.20389ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:41.625426Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316587787811450,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T11:31:41.837258Z","caller":"traceutil/trace.go:171","msg":"trace[1939299536] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"712.96368ms","start":"2026-06-16T11:31:41.124264Z","end":"2026-06-16T11:31:41.837228Z","steps":["trace[1939299536] 'read index received' (duration: 712.789516ms)","trace[1939299536] 'applied index is now lower than readState.Index' (duration: 173.154µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:31:41.837516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.240535ms","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-16T11:31:41.837559Z","caller":"traceutil/trace.go:171","msg":"trace[1952107006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"713.349578ms","start":"2026-06-16T11:31:41.124198Z","end":"2026-06-16T11:31:41.837548Z","steps":["trace[1952107006] 'agreement among raft nodes before linearized reading' (duration: 713.164184ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:41.837303Z","caller":"traceutil/trace.go:171","msg":"trace[1153700829] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"822.54847ms","start":"2026-06-16T11:31:41.014724Z","end":"2026-06-16T11:31:41.837272Z","steps":["trace[1153700829] 'process raft request' (duration: 822.316595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:41.837593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:31:41.124178Z","time spent":"713.407749ms","remote":"127.0.0.1:48036","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-16T11:31:41.837634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.451229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v6qj8\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-16T11:31:41.837721Z","caller":"traceutil/trace.go:171","msg":"trace[1541534693] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v6qj8; range_end:; response_count:1; response_revision:628; }","duration":"572.564321ms","start":"2026-06-16T11:31:41.265134Z","end":"2026-06-16T11:31:41.837698Z","steps":["trace[1541534693] 'agreement among raft nodes before linearized reading' (duration: 572.304895ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:41.837779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:31:41.265122Z","time spent":"572.646922ms","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-v6qj8\" "} {"level":"warn","ts":"2026-06-16T11:31:41.837798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:31:41.014671Z","time spent":"822.987439ms","remote":"127.0.0.1:48130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T11:31:42.074793Z","caller":"traceutil/trace.go:171","msg":"trace[161724352] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"227.84427ms","start":"2026-06-16T11:31:41.846929Z","end":"2026-06-16T11:31:42.074773Z","steps":["trace[161724352] 'read index received' (duration: 198.888457ms)","trace[161724352] 'applied index is now lower than readState.Index' (duration: 28.955053ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:31:42.074821Z","caller":"traceutil/trace.go:171","msg":"trace[1180100153] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"229.7149ms","start":"2026-06-16T11:31:41.845081Z","end":"2026-06-16T11:31:42.074796Z","steps":["trace[1180100153] 'process raft request' (duration: 200.627955ms)","trace[1180100153] 'compare' (duration: 28.888371ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:31:42.074889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.945223ms","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-16T11:31:42.074938Z","caller":"traceutil/trace.go:171","msg":"trace[1807660000] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:629; }","duration":"228.022064ms","start":"2026-06-16T11:31:41.846901Z","end":"2026-06-16T11:31:42.074923Z","steps":["trace[1807660000] 'agreement among raft nodes before linearized reading' (duration: 227.939122ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:42.075685Z","caller":"traceutil/trace.go:171","msg":"trace[1180794740] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"222.722167ms","start":"2026-06-16T11:31:41.852944Z","end":"2026-06-16T11:31:42.075666Z","steps":["trace[1180794740] 'process raft request' (duration: 222.534683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:42.075719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.591688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T11:31:42.075761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.801643ms","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-16T11:31:42.075796Z","caller":"traceutil/trace.go:171","msg":"trace[655508809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"154.866574ms","start":"2026-06-16T11:31:41.920921Z","end":"2026-06-16T11:31:42.075788Z","steps":["trace[655508809] 'agreement among raft nodes before linearized reading' (duration: 154.750512ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:42.075788Z","caller":"traceutil/trace.go:171","msg":"trace[982978301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"204.769632ms","start":"2026-06-16T11:31:41.871001Z","end":"2026-06-16T11:31:42.075771Z","steps":["trace[982978301] 'agreement among raft nodes before linearized reading' (duration: 204.574888ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:43.594384Z","caller":"traceutil/trace.go:171","msg":"trace[603935507] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"123.389182ms","start":"2026-06-16T11:31:43.470975Z","end":"2026-06-16T11:31:43.594364Z","steps":["trace[603935507] 'process raft request' (duration: 123.28513ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:45.417299Z","caller":"traceutil/trace.go:171","msg":"trace[172429932] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"109.467975ms","start":"2026-06-16T11:31:45.307806Z","end":"2026-06-16T11:31:45.417273Z","steps":["trace[172429932] 'process raft request' (duration: 109.320482ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:53.505994Z","caller":"traceutil/trace.go:171","msg":"trace[598360420] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"134.880347ms","start":"2026-06-16T11:31:53.371085Z","end":"2026-06-16T11:31:53.505966Z","steps":["trace[598360420] 'read index received' (duration: 134.566661ms)","trace[598360420] 'applied index is now lower than readState.Index' (duration: 312.256µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:31:53.506074Z","caller":"traceutil/trace.go:171","msg":"trace[1859407122] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"185.977217ms","start":"2026-06-16T11:31:53.320053Z","end":"2026-06-16T11:31:53.50603Z","steps":["trace[1859407122] 'process raft request' (duration: 185.738482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:53.506246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.135702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-wxltn\" ","response":"range_response_count:1 size:4740"} {"level":"info","ts":"2026-06-16T11:31:53.506297Z","caller":"traceutil/trace.go:171","msg":"trace[1192677375] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-wxltn; range_end:; response_count:1; response_revision:768; }","duration":"135.278405ms","start":"2026-06-16T11:31:53.371005Z","end":"2026-06-16T11:31:53.506283Z","steps":["trace[1192677375] 'agreement among raft nodes before linearized reading' (duration: 135.081291ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:56.552922Z","caller":"traceutil/trace.go:171","msg":"trace[2025547458] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"381.548512ms","start":"2026-06-16T11:31:56.171345Z","end":"2026-06-16T11:31:56.552893Z","steps":["trace[2025547458] 'read index received' (duration: 381.353908ms)","trace[2025547458] 'applied index is now lower than readState.Index' (duration: 193.354µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:31:56.552954Z","caller":"traceutil/trace.go:171","msg":"trace[1703772985] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"385.578211ms","start":"2026-06-16T11:31:56.167355Z","end":"2026-06-16T11:31:56.552933Z","steps":["trace[1703772985] 'process raft request' (duration: 385.344497ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:56.553116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.767236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-16T11:31:56.553114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:31:56.167329Z","time spent":"385.690854ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:31:56.55316Z","caller":"traceutil/trace.go:171","msg":"trace[1827788651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"381.848638ms","start":"2026-06-16T11:31:56.171301Z","end":"2026-06-16T11:31:56.55315Z","steps":["trace[1827788651] 'agreement among raft nodes before linearized reading' (duration: 381.701135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:31:56.553195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:31:56.17129Z","time spent":"381.897428ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-16T11:31:56.696233Z","caller":"traceutil/trace.go:171","msg":"trace[334860370] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"299.160485ms","start":"2026-06-16T11:31:56.397051Z","end":"2026-06-16T11:31:56.696212Z","steps":["trace[334860370] 'process raft request' (duration: 298.992872ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:31:56.700321Z","caller":"traceutil/trace.go:171","msg":"trace[589565248] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"142.85889ms","start":"2026-06-16T11:31:56.55744Z","end":"2026-06-16T11:31:56.700299Z","steps":["trace[589565248] 'process raft request' (duration: 142.572804ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:32:30.122603Z","caller":"traceutil/trace.go:171","msg":"trace[273103165] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1240; }","duration":"119.702314ms","start":"2026-06-16T11:32:30.002869Z","end":"2026-06-16T11:32:30.122571Z","steps":["trace[273103165] 'read index received' (duration: 119.508921ms)","trace[273103165] 'applied index is now lower than readState.Index' (duration: 191.363µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:32:30.122746Z","caller":"traceutil/trace.go:171","msg":"trace[2035009637] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"368.250328ms","start":"2026-06-16T11:32:29.754432Z","end":"2026-06-16T11:32:30.122682Z","steps":["trace[2035009637] 'process raft request' (duration: 367.918803ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:32:30.122838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.941958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-06-16T11:32:30.122893Z","caller":"traceutil/trace.go:171","msg":"trace[1070787507] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1205; }","duration":"120.057919ms","start":"2026-06-16T11:32:30.002819Z","end":"2026-06-16T11:32:30.122877Z","steps":["trace[1070787507] 'agreement among raft nodes before linearized reading' (duration: 119.911597ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:32:30.122925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:32:29.754404Z","time spent":"368.41658ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:32:55.285402Z","caller":"traceutil/trace.go:171","msg":"trace[1294404370] linearizableReadLoop","detail":"{readStateIndex:1536; appliedIndex:1534; }","duration":"122.827547ms","start":"2026-06-16T11:32:55.162552Z","end":"2026-06-16T11:32:55.285379Z","steps":["trace[1294404370] 'read index received' (duration: 36.793288ms)","trace[1294404370] 'applied index is now lower than readState.Index' (duration: 86.033469ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:32:55.285427Z","caller":"traceutil/trace.go:171","msg":"trace[5662397] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"183.790858ms","start":"2026-06-16T11:32:55.101592Z","end":"2026-06-16T11:32:55.285383Z","steps":["trace[5662397] 'process raft request' (duration: 97.77138ms)","trace[5662397] 'compare' (duration: 85.766023ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:32:55.285752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.182102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107251"} {"level":"info","ts":"2026-06-16T11:32:55.285819Z","caller":"traceutil/trace.go:171","msg":"trace[1038285338] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:1490; }","duration":"123.295683ms","start":"2026-06-16T11:32:55.162502Z","end":"2026-06-16T11:32:55.285798Z","steps":["trace[1038285338] 'agreement among raft nodes before linearized reading' (duration: 123.012029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:32:55.285893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.271248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-16T11:32:55.285955Z","caller":"traceutil/trace.go:171","msg":"trace[1202169569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1490; }","duration":"111.370649ms","start":"2026-06-16T11:32:55.174564Z","end":"2026-06-16T11:32:55.285934Z","steps":["trace[1202169569] 'agreement among raft nodes before linearized reading' (duration: 111.209867ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:32:55.285532Z","caller":"traceutil/trace.go:171","msg":"trace[291925328] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1490; }","duration":"157.301371ms","start":"2026-06-16T11:32:55.128174Z","end":"2026-06-16T11:32:55.285476Z","steps":["trace[291925328] 'process raft request' (duration: 157.153739ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:07.461443Z","caller":"traceutil/trace.go:171","msg":"trace[158928574] linearizableReadLoop","detail":"{readStateIndex:1793; appliedIndex:1792; }","duration":"117.332559ms","start":"2026-06-16T11:33:07.344058Z","end":"2026-06-16T11:33:07.46139Z","steps":["trace[158928574] 'read index received' (duration: 30.175524ms)","trace[158928574] 'applied index is now lower than readState.Index' (duration: 87.155444ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:33:07.461649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.570942ms","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-16T11:33:07.461709Z","caller":"traceutil/trace.go:171","msg":"trace[837712834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1723; }","duration":"117.678043ms","start":"2026-06-16T11:33:07.344017Z","end":"2026-06-16T11:33:07.461695Z","steps":["trace[837712834] 'agreement among raft nodes before linearized reading' (duration: 117.48085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:07.46177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.458298ms","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-16T11:33:07.461814Z","caller":"traceutil/trace.go:171","msg":"trace[1517840453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1723; }","duration":"109.555909ms","start":"2026-06-16T11:33:07.352246Z","end":"2026-06-16T11:33:07.461802Z","steps":["trace[1517840453] 'agreement among raft nodes before linearized reading' (duration: 109.376377ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:07.622914Z","caller":"traceutil/trace.go:171","msg":"trace[760599224] transaction","detail":"{read_only:false; response_revision:1725; number_of_response:1; }","duration":"155.250982ms","start":"2026-06-16T11:33:07.467647Z","end":"2026-06-16T11:33:07.622898Z","steps":["trace[760599224] 'process raft request' (duration: 146.521619ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:07.623Z","caller":"traceutil/trace.go:171","msg":"trace[218975885] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"155.291842ms","start":"2026-06-16T11:33:07.467686Z","end":"2026-06-16T11:33:07.622978Z","steps":["trace[218975885] 'process raft request' (duration: 155.095839ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:16.715687Z","caller":"traceutil/trace.go:171","msg":"trace[597709517] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1953; }","duration":"192.647076ms","start":"2026-06-16T11:33:16.523015Z","end":"2026-06-16T11:33:16.715662Z","steps":["trace[597709517] 'read index received' (duration: 192.431722ms)","trace[597709517] 'applied index is now lower than readState.Index' (duration: 214.184µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:33:16.716039Z","caller":"traceutil/trace.go:171","msg":"trace[556264551] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"227.644656ms","start":"2026-06-16T11:33:16.488379Z","end":"2026-06-16T11:33:16.716024Z","steps":["trace[556264551] 'process raft request' (duration: 227.129189ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:16.716255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.226324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T11:33:16.716305Z","caller":"traceutil/trace.go:171","msg":"trace[1619575604] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1882; }","duration":"193.330336ms","start":"2026-06-16T11:33:16.522965Z","end":"2026-06-16T11:33:16.716295Z","steps":["trace[1619575604] 'agreement among raft nodes before linearized reading' (duration: 193.239504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:16.716459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.976976ms","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-16T11:33:16.716549Z","caller":"traceutil/trace.go:171","msg":"trace[561860972] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1882; }","duration":"132.119908ms","start":"2026-06-16T11:33:16.584405Z","end":"2026-06-16T11:33:16.716525Z","steps":["trace[561860972] 'agreement among raft nodes before linearized reading' (duration: 131.889285ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:17.454954Z","caller":"traceutil/trace.go:171","msg":"trace[347217631] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"673.001312ms","start":"2026-06-16T11:33:16.781927Z","end":"2026-06-16T11:33:17.454928Z","steps":["trace[347217631] 'process raft request' (duration: 672.84922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:17.455104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:16.78191Z","time spent":"673.116053ms","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:33:17.646603Z","caller":"traceutil/trace.go:171","msg":"trace[556487575] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"651.060185ms","start":"2026-06-16T11:33:16.995501Z","end":"2026-06-16T11:33:17.646562Z","steps":["trace[556487575] 'process raft request' (duration: 609.518368ms)","trace[556487575] 'compare' (duration: 40.99975ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:33:17.647006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:16.995477Z","time spent":"651.278469ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:33:17.691192Z","caller":"traceutil/trace.go:171","msg":"trace[2042591069] linearizableReadLoop","detail":"{readStateIndex:1956; appliedIndex:1954; }","duration":"691.339135ms","start":"2026-06-16T11:33:16.999747Z","end":"2026-06-16T11:33:17.691086Z","steps":["trace[2042591069] 'read index received' (duration: 455.011289ms)","trace[2042591069] 'applied index is now lower than readState.Index' (duration: 236.325196ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:33:17.691431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.469893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-16T11:33:17.691511Z","caller":"traceutil/trace.go:171","msg":"trace[517364034] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1885; }","duration":"460.606306ms","start":"2026-06-16T11:33:17.230884Z","end":"2026-06-16T11:33:17.69149Z","steps":["trace[517364034] 'agreement among raft nodes before linearized reading' (duration: 460.431564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:17.691548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:17.230864Z","time spent":"460.675437ms","remote":"127.0.0.1:48036","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":"warn","ts":"2026-06-16T11:33:17.691569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.636969ms","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-16T11:33:17.691618Z","caller":"traceutil/trace.go:171","msg":"trace[660620587] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1885; }","duration":"691.893172ms","start":"2026-06-16T11:33:16.999713Z","end":"2026-06-16T11:33:17.691606Z","steps":["trace[660620587] 'agreement among raft nodes before linearized reading' (duration: 691.563047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:17.691657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:16.9997Z","time spent":"691.946452ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T11:33:17.69173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.206499ms","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-16T11:33:17.691819Z","caller":"traceutil/trace.go:171","msg":"trace[113675485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1885; }","duration":"114.348901ms","start":"2026-06-16T11:33:17.577449Z","end":"2026-06-16T11:33:17.691798Z","steps":["trace[113675485] 'agreement among raft nodes before linearized reading' (duration: 114.065657ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:18.227792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.293939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T11:33:18.228149Z","caller":"traceutil/trace.go:171","msg":"trace[2066713859] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"531.528804ms","start":"2026-06-16T11:33:17.696595Z","end":"2026-06-16T11:33:18.228123Z","steps":["trace[2066713859] 'process raft request' (duration: 531.411853ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:18.228141Z","caller":"traceutil/trace.go:171","msg":"trace[1719387826] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"531.874199ms","start":"2026-06-16T11:33:17.696237Z","end":"2026-06-16T11:33:18.228111Z","steps":["trace[1719387826] 'process raft request' (duration: 215.173665ms)","trace[1719387826] 'compare' (duration: 316.151347ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:33:18.228297Z","caller":"traceutil/trace.go:171","msg":"trace[772874633] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1959; }","duration":"506.566224ms","start":"2026-06-16T11:33:17.721713Z","end":"2026-06-16T11:33:18.22828Z","steps":["trace[772874633] 'read index received' (duration: 189.702718ms)","trace[772874633] 'applied index is now lower than readState.Index' (duration: 316.862776ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:33:18.228381Z","caller":"traceutil/trace.go:171","msg":"trace[607297691] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"531.652886ms","start":"2026-06-16T11:33:17.696721Z","end":"2026-06-16T11:33:18.228374Z","steps":["trace[607297691] 'process raft request' (duration: 531.354842ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:18.228402Z","caller":"traceutil/trace.go:171","msg":"trace[1414944841] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"488.833203ms","start":"2026-06-16T11:33:17.739549Z","end":"2026-06-16T11:33:18.228383Z","steps":["trace[1414944841] 'process raft request' (duration: 488.67151ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:18.228515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:17.696699Z","time spent":"531.698918ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:33:18.228453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:17.696577Z","time spent":"531.631426ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:33:18.228498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:17.739534Z","time spent":"488.919304ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:33:18.22873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.99198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-29aeb649-763a-4df0-8421-4f820b11fbc3\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-06-16T11:33:18.228808Z","caller":"traceutil/trace.go:171","msg":"trace[228202536] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-29aeb649-763a-4df0-8421-4f820b11fbc3; range_end:; response_count:1; response_revision:1890; }","duration":"507.127462ms","start":"2026-06-16T11:33:17.721663Z","end":"2026-06-16T11:33:18.22879Z","steps":["trace[228202536] 'agreement among raft nodes before linearized reading' (duration: 506.860248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:18.228856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:17.721638Z","time spent":"507.207823ms","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-29aeb649-763a-4df0-8421-4f820b11fbc3\" "} {"level":"warn","ts":"2026-06-16T11:33:18.228907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:17.696214Z","time spent":"532.001571ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:33:18.250275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.335074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T11:33:18.250361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.972685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T11:33:18.250356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.615509ms","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-16T11:33:18.250373Z","caller":"traceutil/trace.go:171","msg":"trace[2112305536] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1891; }","duration":"169.465696ms","start":"2026-06-16T11:33:18.080889Z","end":"2026-06-16T11:33:18.250354Z","steps":["trace[2112305536] 'agreement among raft nodes before linearized reading' (duration: 169.322844ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:18.250374Z","caller":"traceutil/trace.go:171","msg":"trace[478289487] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"224.334163ms","start":"2026-06-16T11:33:18.026018Z","end":"2026-06-16T11:33:18.250353Z","steps":["trace[478289487] 'process raft request' (duration: 224.077499ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:18.250405Z","caller":"traceutil/trace.go:171","msg":"trace[2036395362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1891; }","duration":"377.036395ms","start":"2026-06-16T11:33:17.873357Z","end":"2026-06-16T11:33:18.250394Z","steps":["trace[2036395362] 'agreement among raft nodes before linearized reading' (duration: 376.978335ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:18.250423Z","caller":"traceutil/trace.go:171","msg":"trace[1022535127] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:1891; }","duration":"183.68967ms","start":"2026-06-16T11:33:18.066716Z","end":"2026-06-16T11:33:18.250406Z","steps":["trace[1022535127] 'agreement among raft nodes before linearized reading' (duration: 183.574378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:18.250439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:17.873345Z","time spent":"377.087316ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T11:33:18.250302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.937211ms","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-16T11:33:18.250725Z","caller":"traceutil/trace.go:171","msg":"trace[1093818768] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1891; }","duration":"527.299147ms","start":"2026-06-16T11:33:17.723325Z","end":"2026-06-16T11:33:18.250625Z","steps":["trace[1093818768] 'agreement among raft nodes before linearized reading' (duration: 526.872441ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:18.250834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:17.72331Z","time spent":"527.448419ms","remote":"127.0.0.1:43946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-16T11:33:22.997457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.219593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T11:33:22.997553Z","caller":"traceutil/trace.go:171","msg":"trace[51458473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1939; }","duration":"127.332474ms","start":"2026-06-16T11:33:22.870198Z","end":"2026-06-16T11:33:22.99753Z","steps":["trace[51458473] 'range keys from in-memory index tree' (duration: 127.097891ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:32.434982Z","caller":"traceutil/trace.go:171","msg":"trace[740330661] linearizableReadLoop","detail":"{readStateIndex:2080; appliedIndex:2078; }","duration":"135.787317ms","start":"2026-06-16T11:33:32.299176Z","end":"2026-06-16T11:33:32.434963Z","steps":["trace[740330661] 'read index received' (duration: 131.075354ms)","trace[740330661] 'applied index is now lower than readState.Index' (duration: 4.711103ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:33:32.435071Z","caller":"traceutil/trace.go:171","msg":"trace[248059527] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"142.17787ms","start":"2026-06-16T11:33:32.292887Z","end":"2026-06-16T11:33:32.435064Z","steps":["trace[248059527] 'process raft request' (duration: 142.051688ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:32.435075Z","caller":"traceutil/trace.go:171","msg":"trace[1677463958] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"144.4979ms","start":"2026-06-16T11:33:32.290514Z","end":"2026-06-16T11:33:32.435012Z","steps":["trace[1677463958] 'process raft request' (duration: 139.725827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:32.435295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.969299ms","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-16T11:33:32.435367Z","caller":"traceutil/trace.go:171","msg":"trace[458393888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2003; }","duration":"136.05949ms","start":"2026-06-16T11:33:32.29929Z","end":"2026-06-16T11:33:32.435349Z","steps":["trace[458393888] 'agreement among raft nodes before linearized reading' (duration: 135.883137ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:32.435496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.306723ms","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-16T11:33:32.435519Z","caller":"traceutil/trace.go:171","msg":"trace[1047129221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2003; }","duration":"136.363084ms","start":"2026-06-16T11:33:32.299149Z","end":"2026-06-16T11:33:32.435512Z","steps":["trace[1047129221] 'agreement among raft nodes before linearized reading' (duration: 136.274562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:32.435549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.254727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-c52s7\" ","response":"range_response_count:1 size:5854"} {"level":"info","ts":"2026-06-16T11:33:32.435581Z","caller":"traceutil/trace.go:171","msg":"trace[627837740] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-c52s7; range_end:; response_count:1; response_revision:2003; }","duration":"106.327228ms","start":"2026-06-16T11:33:32.329242Z","end":"2026-06-16T11:33:32.435569Z","steps":["trace[627837740] 'agreement among raft nodes before linearized reading' (duration: 106.236437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:33.367032Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316587787814144,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T11:33:33.57011Z","caller":"traceutil/trace.go:171","msg":"trace[957574001] linearizableReadLoop","detail":"{readStateIndex:2088; appliedIndex:2087; }","duration":"703.187621ms","start":"2026-06-16T11:33:32.8669Z","end":"2026-06-16T11:33:33.570088Z","steps":["trace[957574001] 'read index received' (duration: 702.966938ms)","trace[957574001] 'applied index is now lower than readState.Index' (duration: 219.783µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:33:33.570406Z","caller":"traceutil/trace.go:171","msg":"trace[2119759533] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"805.482255ms","start":"2026-06-16T11:33:32.764912Z","end":"2026-06-16T11:33:33.570394Z","steps":["trace[2119759533] 'process raft request' (duration: 805.037559ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:33.570512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:32.764892Z","time spent":"805.550926ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:33:33.571066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.162963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T11:33:33.571147Z","caller":"traceutil/trace.go:171","msg":"trace[567319194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2011; }","duration":"704.296535ms","start":"2026-06-16T11:33:32.866831Z","end":"2026-06-16T11:33:33.571127Z","steps":["trace[567319194] 'agreement among raft nodes before linearized reading' (duration: 704.164093ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:33.571186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:32.866811Z","time spent":"704.367577ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T11:33:33.57123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.098811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-fbwmz\" ","response":"range_response_count:1 size:6143"} {"level":"warn","ts":"2026-06-16T11:33:33.571249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.258469ms","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":"warn","ts":"2026-06-16T11:33:33.571278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.493774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T11:33:33.571302Z","caller":"traceutil/trace.go:171","msg":"trace[401916439] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-fbwmz; range_end:; response_count:1; response_revision:2011; }","duration":"605.211203ms","start":"2026-06-16T11:33:32.96607Z","end":"2026-06-16T11:33:33.571281Z","steps":["trace[401916439] 'agreement among raft nodes before linearized reading' (duration: 605.02963ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:33.571355Z","caller":"traceutil/trace.go:171","msg":"trace[1172243245] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2011; }","duration":"595.620117ms","start":"2026-06-16T11:33:32.975719Z","end":"2026-06-16T11:33:33.571339Z","steps":["trace[1172243245] 'agreement among raft nodes before linearized reading' (duration: 595.481555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:33.571402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:32.975698Z","time spent":"595.693227ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":17,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-06-16T11:33:33.571357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:32.966052Z","time spent":"605.296824ms","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":6167,"request content":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-fbwmz\" "} {"level":"info","ts":"2026-06-16T11:33:33.571308Z","caller":"traceutil/trace.go:171","msg":"trace[511467370] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2011; }","duration":"130.370881ms","start":"2026-06-16T11:33:33.440921Z","end":"2026-06-16T11:33:33.571292Z","steps":["trace[511467370] 'agreement among raft nodes before linearized reading' (duration: 130.186488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:33.571721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.695841ms","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-16T11:33:33.571769Z","caller":"traceutil/trace.go:171","msg":"trace[1285607097] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2011; }","duration":"523.775172ms","start":"2026-06-16T11:33:33.047982Z","end":"2026-06-16T11:33:33.571757Z","steps":["trace[1285607097] 'agreement among raft nodes before linearized reading' (duration: 523.151913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:33.5718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:33.047965Z","time spent":"523.827002ms","remote":"127.0.0.1:48230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-06-16T11:33:51.109818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.323428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T11:33:51.109895Z","caller":"traceutil/trace.go:171","msg":"trace[1341277248] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2119; }","duration":"173.436669ms","start":"2026-06-16T11:33:50.93644Z","end":"2026-06-16T11:33:51.109877Z","steps":["trace[1341277248] 'count revisions from in-memory index tree' (duration: 173.220986ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:51.109993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.308615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T11:33:51.110455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.589731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T11:33:51.110516Z","caller":"traceutil/trace.go:171","msg":"trace[2037740721] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2119; }","duration":"143.700542ms","start":"2026-06-16T11:33:50.9668Z","end":"2026-06-16T11:33:51.1105Z","steps":["trace[2037740721] 'count revisions from in-memory index tree' (duration: 143.483599ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:51.110083Z","caller":"traceutil/trace.go:171","msg":"trace[921581793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2119; }","duration":"239.450676ms","start":"2026-06-16T11:33:50.87061Z","end":"2026-06-16T11:33:51.110061Z","steps":["trace[921581793] 'range keys from in-memory index tree' (duration: 239.231203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:52.203178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.331518ms","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-16T11:33:52.203304Z","caller":"traceutil/trace.go:171","msg":"trace[1030633101] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2120; }","duration":"404.456479ms","start":"2026-06-16T11:33:51.798787Z","end":"2026-06-16T11:33:52.203243Z","steps":["trace[1030633101] 'range keys from in-memory index tree' (duration: 404.159096ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:52.203349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:51.79877Z","time spent":"404.568301ms","remote":"127.0.0.1:43946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-16T11:33:52.203997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.592476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T11:33:52.204203Z","caller":"traceutil/trace.go:171","msg":"trace[1888374611] linearizableReadLoop","detail":"{readStateIndex:2201; appliedIndex:2200; }","duration":"296.750884ms","start":"2026-06-16T11:33:51.907308Z","end":"2026-06-16T11:33:52.204059Z","steps":["trace[1888374611] 'read index received' (duration: 100.091768ms)","trace[1888374611] 'applied index is now lower than readState.Index' (duration: 196.658126ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:33:52.204419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.12436ms","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-16T11:33:52.204457Z","caller":"traceutil/trace.go:171","msg":"trace[508849489] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2121; }","duration":"297.20347ms","start":"2026-06-16T11:33:51.907244Z","end":"2026-06-16T11:33:52.204448Z","steps":["trace[508849489] 'agreement among raft nodes before linearized reading' (duration: 296.994998ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:52.204537Z","caller":"traceutil/trace.go:171","msg":"trace[1120045513] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"352.37208ms","start":"2026-06-16T11:33:51.852139Z","end":"2026-06-16T11:33:52.204511Z","steps":["trace[1120045513] 'process raft request' (duration: 155.194347ms)","trace[1120045513] 'compare' (duration: 196.494255ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:33:52.204653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:51.852113Z","time spent":"352.482741ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:33:52.74911Z","caller":"traceutil/trace.go:171","msg":"trace[2028946014] linearizableReadLoop","detail":"{readStateIndex:2207; appliedIndex:2206; }","duration":"198.905756ms","start":"2026-06-16T11:33:52.550178Z","end":"2026-06-16T11:33:52.749084Z","steps":["trace[2028946014] 'read index received' (duration: 198.742684ms)","trace[2028946014] 'applied index is now lower than readState.Index' (duration: 161.792µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:33:52.749236Z","caller":"traceutil/trace.go:171","msg":"trace[1785288546] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"208.992763ms","start":"2026-06-16T11:33:52.540232Z","end":"2026-06-16T11:33:52.749225Z","steps":["trace[1785288546] 'process raft request' (duration: 208.699559ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:52.749613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.707946ms","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-16T11:33:52.749741Z","caller":"traceutil/trace.go:171","msg":"trace[1128486622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2126; }","duration":"195.866287ms","start":"2026-06-16T11:33:52.553855Z","end":"2026-06-16T11:33:52.749721Z","steps":["trace[1128486622] 'agreement among raft nodes before linearized reading' (duration: 195.594734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:52.749745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.607966ms","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-16T11:33:52.749993Z","caller":"traceutil/trace.go:171","msg":"trace[1256161164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2126; }","duration":"192.89795ms","start":"2026-06-16T11:33:52.557076Z","end":"2026-06-16T11:33:52.749974Z","steps":["trace[1256161164] 'agreement among raft nodes before linearized reading' (duration: 192.455414ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:52.749773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.700608ms","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-16T11:33:52.750068Z","caller":"traceutil/trace.go:171","msg":"trace[1551557797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2126; }","duration":"193.035901ms","start":"2026-06-16T11:33:52.557011Z","end":"2026-06-16T11:33:52.750047Z","steps":["trace[1551557797] 'agreement among raft nodes before linearized reading' (duration: 192.586516ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:52.750402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.166782ms","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-16T11:33:52.750586Z","caller":"traceutil/trace.go:171","msg":"trace[116133840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2126; }","duration":"200.401575ms","start":"2026-06-16T11:33:52.550124Z","end":"2026-06-16T11:33:52.750525Z","steps":["trace[116133840] 'agreement among raft nodes before linearized reading' (duration: 199.351012ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:53.240061Z","caller":"traceutil/trace.go:171","msg":"trace[420168602] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"485.22054ms","start":"2026-06-16T11:33:52.754815Z","end":"2026-06-16T11:33:53.240036Z","steps":["trace[420168602] 'process raft request' (duration: 485.081699ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:53.2402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:52.754787Z","time spent":"485.341282ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:33:53.543369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.399636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T11:33:53.543566Z","caller":"traceutil/trace.go:171","msg":"trace[305066259] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"788.201105ms","start":"2026-06-16T11:33:52.755346Z","end":"2026-06-16T11:33:53.543547Z","steps":["trace[305066259] 'process raft request' (duration: 512.518475ms)","trace[305066259] 'compare' (duration: 275.176904ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:33:53.543637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:52.755323Z","time spent":"788.284447ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:33:53.543875Z","caller":"traceutil/trace.go:171","msg":"trace[442921289] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"788.100554ms","start":"2026-06-16T11:33:52.755753Z","end":"2026-06-16T11:33:53.543854Z","steps":["trace[442921289] 'process raft request' (duration: 787.711219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:53.544044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:52.755741Z","time spent":"788.237166ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:33:53.544154Z","caller":"traceutil/trace.go:171","msg":"trace[1971086613] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2207; }","duration":"674.194673ms","start":"2026-06-16T11:33:52.869945Z","end":"2026-06-16T11:33:53.54414Z","steps":["trace[1971086613] 'read index received' (duration: 369.944032ms)","trace[1971086613] 'applied index is now lower than readState.Index' (duration: 304.249641ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:33:53.54426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.305095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T11:33:53.544292Z","caller":"traceutil/trace.go:171","msg":"trace[1915257329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2130; }","duration":"674.368416ms","start":"2026-06-16T11:33:52.869915Z","end":"2026-06-16T11:33:53.544283Z","steps":["trace[1915257329] 'agreement among raft nodes before linearized reading' (duration: 674.308435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:53.544321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:52.869897Z","time spent":"674.416766ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T11:33:53.544693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.51274ms","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-16T11:33:53.544796Z","caller":"traceutil/trace.go:171","msg":"trace[11261980] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2130; }","duration":"333.687844ms","start":"2026-06-16T11:33:53.211085Z","end":"2026-06-16T11:33:53.544773Z","steps":["trace[11261980] 'agreement among raft nodes before linearized reading' (duration: 333.284688ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:33:53.544219Z","caller":"traceutil/trace.go:171","msg":"trace[155928184] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"787.873702ms","start":"2026-06-16T11:33:52.75631Z","end":"2026-06-16T11:33:53.544184Z","steps":["trace[155928184] 'process raft request' (duration: 787.481297ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:33:53.545025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:53.211066Z","time spent":"333.914567ms","remote":"127.0.0.1:43946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-16T11:33:53.54528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:33:52.756298Z","time spent":"788.807954ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:33:53.887015Z","caller":"traceutil/trace.go:171","msg":"trace[1159495008] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"214.492118ms","start":"2026-06-16T11:33:53.672498Z","end":"2026-06-16T11:33:53.88699Z","steps":["trace[1159495008] 'process raft request' (duration: 208.751766ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:34:47.1089Z","caller":"traceutil/trace.go:171","msg":"trace[1309749050] transaction","detail":"{read_only:false; response_revision:2523; number_of_response:1; }","duration":"175.459609ms","start":"2026-06-16T11:34:46.933415Z","end":"2026-06-16T11:34:47.108874Z","steps":["trace[1309749050] 'process raft request' (duration: 175.284994ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:35:20.294561Z","caller":"traceutil/trace.go:171","msg":"trace[1891408922] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"235.320216ms","start":"2026-06-16T11:35:20.059207Z","end":"2026-06-16T11:35:20.294527Z","steps":["trace[1891408922] 'process raft request' (duration: 235.141361ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:35:20.304047Z","caller":"traceutil/trace.go:171","msg":"trace[2126998058] transaction","detail":"{read_only:false; response_revision:2705; number_of_response:1; }","duration":"108.897888ms","start":"2026-06-16T11:35:20.195134Z","end":"2026-06-16T11:35:20.304031Z","steps":["trace[2126998058] 'process raft request' (duration: 108.863667ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:35:20.304267Z","caller":"traceutil/trace.go:171","msg":"trace[1372618802] transaction","detail":"{read_only:false; response_revision:2704; number_of_response:1; }","duration":"244.4615ms","start":"2026-06-16T11:35:20.059787Z","end":"2026-06-16T11:35:20.304248Z","steps":["trace[1372618802] 'process raft request' (duration: 244.141092ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:35:20.813408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.652804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T11:35:20.813505Z","caller":"traceutil/trace.go:171","msg":"trace[1517156718] linearizableReadLoop","detail":"{readStateIndex:2807; appliedIndex:2806; }","duration":"439.501307ms","start":"2026-06-16T11:35:20.373988Z","end":"2026-06-16T11:35:20.813489Z","steps":["trace[1517156718] 'read index received' (duration: 155.938498ms)","trace[1517156718] 'applied index is now lower than readState.Index' (duration: 283.561399ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:35:20.813571Z","caller":"traceutil/trace.go:171","msg":"trace[875735320] transaction","detail":"{read_only:false; response_revision:2706; number_of_response:1; }","duration":"456.121333ms","start":"2026-06-16T11:35:20.357438Z","end":"2026-06-16T11:35:20.81356Z","steps":["trace[875735320] 'process raft request' (duration: 172.574374ms)","trace[875735320] 'compare' (duration: 282.542161ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:35:20.813759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.763024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b98c95470c807d\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-06-16T11:35:20.813926Z","caller":"traceutil/trace.go:171","msg":"trace[396394638] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b98c95470c807d; range_end:; response_count:1; response_revision:2706; }","duration":"439.952119ms","start":"2026-06-16T11:35:20.373957Z","end":"2026-06-16T11:35:20.813909Z","steps":["trace[396394638] 'agreement among raft nodes before linearized reading' (duration: 439.659281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:35:20.813961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:35:20.373942Z","time spent":"440.00956ms","remote":"127.0.0.1:47848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":803,"request content":"key:\"/registry/events/auth-system/keycloak-0.18b98c95470c807d\" "} {"level":"warn","ts":"2026-06-16T11:35:20.814294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:35:20.357414Z","time spent":"456.40853ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:35:20.817135Z","caller":"traceutil/trace.go:171","msg":"trace[1636986601] transaction","detail":"{read_only:false; response_revision:2707; number_of_response:1; }","duration":"237.484084ms","start":"2026-06-16T11:35:20.579641Z","end":"2026-06-16T11:35:20.817125Z","steps":["trace[1636986601] 'process raft request' (duration: 237.433942ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:35:20.98191Z","caller":"traceutil/trace.go:171","msg":"trace[197004605] transaction","detail":"{read_only:false; response_revision:2708; number_of_response:1; }","duration":"321.332531ms","start":"2026-06-16T11:35:20.660551Z","end":"2026-06-16T11:35:20.981884Z","steps":["trace[197004605] 'process raft request' (duration: 321.147906ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:35:20.98206Z","caller":"traceutil/trace.go:171","msg":"trace[200558929] linearizableReadLoop","detail":"{readStateIndex:2809; appliedIndex:2808; }","duration":"168.505945ms","start":"2026-06-16T11:35:20.81353Z","end":"2026-06-16T11:35:20.982036Z","steps":["trace[200558929] 'read index received' (duration: 3.696248ms)","trace[200558929] 'applied index is now lower than readState.Index' (duration: 164.808277ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:35:20.982089Z","caller":"traceutil/trace.go:171","msg":"trace[1533648996] transaction","detail":"{read_only:false; response_revision:2709; number_of_response:1; }","duration":"165.791732ms","start":"2026-06-16T11:35:20.816264Z","end":"2026-06-16T11:35:20.982056Z","steps":["trace[1533648996] 'process raft request' (duration: 165.570257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:35:20.982355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.817084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-16T11:35:20.982419Z","caller":"traceutil/trace.go:171","msg":"trace[1177133160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2709; }","duration":"274.928727ms","start":"2026-06-16T11:35:20.707473Z","end":"2026-06-16T11:35:20.982402Z","steps":["trace[1177133160] 'agreement among raft nodes before linearized reading' (duration: 274.657549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:35:20.982636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.23361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T11:35:20.982733Z","caller":"traceutil/trace.go:171","msg":"trace[55536489] transaction","detail":"{read_only:false; response_revision:2710; number_of_response:1; }","duration":"103.572226ms","start":"2026-06-16T11:35:20.879148Z","end":"2026-06-16T11:35:20.98272Z","steps":["trace[55536489] 'process raft request' (duration: 103.33002ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:35:20.982711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:35:20.660525Z","time spent":"321.451563ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:35:20.982839Z","caller":"traceutil/trace.go:171","msg":"trace[1930345168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2710; }","duration":"105.372174ms","start":"2026-06-16T11:35:20.877355Z","end":"2026-06-16T11:35:20.982727Z","steps":["trace[1930345168] 'agreement among raft nodes before linearized reading' (duration: 105.23025ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:38:41.108276Z","caller":"traceutil/trace.go:171","msg":"trace[1282372101] transaction","detail":"{read_only:false; response_revision:3954; number_of_response:1; }","duration":"100.245254ms","start":"2026-06-16T11:38:41.008Z","end":"2026-06-16T11:38:41.108246Z","steps":["trace[1282372101] 'process raft request' (duration: 100.061701ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:38:41.110935Z","caller":"traceutil/trace.go:171","msg":"trace[2118020277] transaction","detail":"{read_only:false; response_revision:3955; number_of_response:1; }","duration":"100.402117ms","start":"2026-06-16T11:38:41.010504Z","end":"2026-06-16T11:38:41.110906Z","steps":["trace[2118020277] 'process raft request' (duration: 99.478171ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:38:46.76713Z","caller":"traceutil/trace.go:171","msg":"trace[2107903319] transaction","detail":"{read_only:false; response_revision:4314; number_of_response:1; }","duration":"100.492638ms","start":"2026-06-16T11:38:46.666616Z","end":"2026-06-16T11:38:46.767109Z","steps":["trace[2107903319] 'process raft request' (duration: 100.353386ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:38:51.478054Z","caller":"traceutil/trace.go:171","msg":"trace[1799923179] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4429; }","duration":"102.929107ms","start":"2026-06-16T11:38:51.375106Z","end":"2026-06-16T11:38:51.478035Z","steps":["trace[1799923179] 'process raft request' (duration: 31.381238ms)","trace[1799923179] 'compare' (duration: 71.357966ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:38:52.459291Z","caller":"traceutil/trace.go:171","msg":"trace[61357675] linearizableReadLoop","detail":"{readStateIndex:4641; appliedIndex:4639; }","duration":"164.44563ms","start":"2026-06-16T11:38:52.294817Z","end":"2026-06-16T11:38:52.459263Z","steps":["trace[61357675] 'read index received' (duration: 52.307706ms)","trace[61357675] 'applied index is now lower than readState.Index' (duration: 112.136514ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:38:52.459435Z","caller":"traceutil/trace.go:171","msg":"trace[305370949] transaction","detail":"{read_only:false; response_revision:4470; number_of_response:1; }","duration":"167.411629ms","start":"2026-06-16T11:38:52.292005Z","end":"2026-06-16T11:38:52.459417Z","steps":["trace[305370949] 'process raft request' (duration: 167.075103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:38:52.459708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.993989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-703cac49-8f18-493a-a645-434b18240771\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-06-16T11:38:52.459792Z","caller":"traceutil/trace.go:171","msg":"trace[1331589350] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-703cac49-8f18-493a-a645-434b18240771; range_end:; response_count:1; response_revision:4470; }","duration":"165.104661ms","start":"2026-06-16T11:38:52.294668Z","end":"2026-06-16T11:38:52.459773Z","steps":["trace[1331589350] 'agreement among raft nodes before linearized reading' (duration: 164.762406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:38:52.480778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.030375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T11:38:52.480845Z","caller":"traceutil/trace.go:171","msg":"trace[39734822] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4471; }","duration":"132.126387ms","start":"2026-06-16T11:38:52.348705Z","end":"2026-06-16T11:38:52.480831Z","steps":["trace[39734822] 'agreement among raft nodes before linearized reading' (duration: 131.996475ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:38:52.480943Z","caller":"traceutil/trace.go:171","msg":"trace[158524904] transaction","detail":"{read_only:false; response_revision:4471; number_of_response:1; }","duration":"132.086937ms","start":"2026-06-16T11:38:52.348844Z","end":"2026-06-16T11:38:52.480931Z","steps":["trace[158524904] 'process raft request' (duration: 131.732761ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:38:52.600486Z","caller":"traceutil/trace.go:171","msg":"trace[1514684424] linearizableReadLoop","detail":"{readStateIndex:4643; appliedIndex:4642; }","duration":"117.553922ms","start":"2026-06-16T11:38:52.482909Z","end":"2026-06-16T11:38:52.600463Z","steps":["trace[1514684424] 'read index received' (duration: 64.555134ms)","trace[1514684424] 'applied index is now lower than readState.Index' (duration: 52.997768ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:38:52.600755Z","caller":"traceutil/trace.go:171","msg":"trace[1186083463] transaction","detail":"{read_only:false; response_revision:4472; number_of_response:1; }","duration":"130.370108ms","start":"2026-06-16T11:38:52.470366Z","end":"2026-06-16T11:38:52.600736Z","steps":["trace[1186083463] 'process raft request' (duration: 77.160137ms)","trace[1186083463] 'compare' (duration: 52.727093ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:38:52.600759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.823886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-06-16T11:38:52.60083Z","caller":"traceutil/trace.go:171","msg":"trace[94840942] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4472; }","duration":"117.933147ms","start":"2026-06-16T11:38:52.482879Z","end":"2026-06-16T11:38:52.600812Z","steps":["trace[94840942] 'agreement among raft nodes before linearized reading' (duration: 117.773135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:38:52.601091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.421269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T11:38:52.601136Z","caller":"traceutil/trace.go:171","msg":"trace[401958007] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4472; }","duration":"117.507661ms","start":"2026-06-16T11:38:52.483618Z","end":"2026-06-16T11:38:52.601125Z","steps":["trace[401958007] 'agreement among raft nodes before linearized reading' (duration: 117.373189ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:38:54.675998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.501762ms","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-16T11:38:54.676037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.878345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-06-16T11:38:54.676094Z","caller":"traceutil/trace.go:171","msg":"trace[142421275] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4523; }","duration":"219.660275ms","start":"2026-06-16T11:38:54.456412Z","end":"2026-06-16T11:38:54.676072Z","steps":["trace[142421275] 'count revisions from in-memory index tree' (duration: 219.437481ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:38:54.676119Z","caller":"traceutil/trace.go:171","msg":"trace[943665311] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4523; }","duration":"264.985646ms","start":"2026-06-16T11:38:54.411112Z","end":"2026-06-16T11:38:54.676097Z","steps":["trace[943665311] 'range keys from in-memory index tree' (duration: 264.731943ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:39:00.252125Z","caller":"traceutil/trace.go:171","msg":"trace[1345122704] linearizableReadLoop","detail":"{readStateIndex:4789; appliedIndex:4788; }","duration":"167.970422ms","start":"2026-06-16T11:39:00.084131Z","end":"2026-06-16T11:39:00.252102Z","steps":["trace[1345122704] 'read index received' (duration: 167.722718ms)","trace[1345122704] 'applied index is now lower than readState.Index' (duration: 246.494µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:39:00.252276Z","caller":"traceutil/trace.go:171","msg":"trace[131681253] transaction","detail":"{read_only:false; response_revision:4611; number_of_response:1; }","duration":"186.528198ms","start":"2026-06-16T11:39:00.065602Z","end":"2026-06-16T11:39:00.25213Z","steps":["trace[131681253] 'process raft request' (duration: 186.333325ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:39:00.252333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.861442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T11:39:00.252371Z","caller":"traceutil/trace.go:171","msg":"trace[2136013401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4611; }","duration":"101.958683ms","start":"2026-06-16T11:39:00.150402Z","end":"2026-06-16T11:39:00.25236Z","steps":["trace[2136013401] 'agreement among raft nodes before linearized reading' (duration: 101.847441ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:39:00.252419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.261906ms","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-16T11:39:00.252505Z","caller":"traceutil/trace.go:171","msg":"trace[1795142161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4611; }","duration":"168.388519ms","start":"2026-06-16T11:39:00.084097Z","end":"2026-06-16T11:39:00.252486Z","steps":["trace[1795142161] 'agreement among raft nodes before linearized reading' (duration: 168.115214ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:39:00.655301Z","caller":"traceutil/trace.go:171","msg":"trace[1626196999] linearizableReadLoop","detail":"{readStateIndex:4791; appliedIndex:4789; }","duration":"288.531784ms","start":"2026-06-16T11:39:00.366749Z","end":"2026-06-16T11:39:00.655281Z","steps":["trace[1626196999] 'read index received' (duration: 217.267002ms)","trace[1626196999] 'applied index is now lower than readState.Index' (duration: 71.264022ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:39:00.655357Z","caller":"traceutil/trace.go:171","msg":"trace[960936742] transaction","detail":"{read_only:false; response_revision:4612; number_of_response:1; }","duration":"395.655011ms","start":"2026-06-16T11:39:00.259656Z","end":"2026-06-16T11:39:00.655311Z","steps":["trace[960936742] 'process raft request' (duration: 324.387209ms)","trace[960936742] 'compare' (duration: 71.001657ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:39:00.655358Z","caller":"traceutil/trace.go:171","msg":"trace[2037551890] transaction","detail":"{read_only:false; response_revision:4613; number_of_response:1; }","duration":"388.78071ms","start":"2026-06-16T11:39:00.26653Z","end":"2026-06-16T11:39:00.655311Z","steps":["trace[2037551890] 'process raft request' (duration: 388.677658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:39:00.655541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:39:00.259638Z","time spent":"395.814223ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:39:00.655758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:39:00.266516Z","time spent":"388.935772ms","remote":"127.0.0.1:47848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-16T11:39:00.655869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.225292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-06-16T11:39:00.655941Z","caller":"traceutil/trace.go:171","msg":"trace[1345255268] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4613; }","duration":"222.324823ms","start":"2026-06-16T11:39:00.433597Z","end":"2026-06-16T11:39:00.655921Z","steps":["trace[1345255268] 'agreement among raft nodes before linearized reading' (duration: 221.817984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:39:00.655957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.192185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16084"} {"level":"info","ts":"2026-06-16T11:39:00.656021Z","caller":"traceutil/trace.go:171","msg":"trace[1161743872] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4613; }","duration":"289.288446ms","start":"2026-06-16T11:39:00.366715Z","end":"2026-06-16T11:39:00.656003Z","steps":["trace[1161743872] 'agreement among raft nodes before linearized reading' (duration: 288.666056ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:39:01.304585Z","caller":"traceutil/trace.go:171","msg":"trace[612974769] transaction","detail":"{read_only:false; response_revision:4629; number_of_response:1; }","duration":"124.719186ms","start":"2026-06-16T11:39:01.179838Z","end":"2026-06-16T11:39:01.304557Z","steps":["trace[612974769] 'process raft request' (duration: 124.653355ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:39:01.304868Z","caller":"traceutil/trace.go:171","msg":"trace[1917710045] linearizableReadLoop","detail":"{readStateIndex:4806; appliedIndex:4805; }","duration":"201.7849ms","start":"2026-06-16T11:39:01.103056Z","end":"2026-06-16T11:39:01.304841Z","steps":["trace[1917710045] 'read index received' (duration: 125.729983ms)","trace[1917710045] 'applied index is now lower than readState.Index' (duration: 76.052557ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:39:01.304899Z","caller":"traceutil/trace.go:171","msg":"trace[817257274] transaction","detail":"{read_only:false; response_revision:4628; number_of_response:1; }","duration":"318.625541ms","start":"2026-06-16T11:39:00.986231Z","end":"2026-06-16T11:39:01.304857Z","steps":["trace[817257274] 'process raft request' (duration: 242.566424ms)","trace[817257274] 'compare' (duration: 75.509569ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:39:01.305106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.568463ms","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-16T11:39:01.30511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:39:00.986211Z","time spent":"318.787444ms","remote":"127.0.0.1:53476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2797,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:39:01.305146Z","caller":"traceutil/trace.go:171","msg":"trace[1894649934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4629; }","duration":"119.654145ms","start":"2026-06-16T11:39:01.185482Z","end":"2026-06-16T11:39:01.305136Z","steps":["trace[1894649934] 'agreement among raft nodes before linearized reading' (duration: 119.495912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:39:01.305106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.993843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T11:39:01.305205Z","caller":"traceutil/trace.go:171","msg":"trace[1856379827] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:4629; }","duration":"202.176547ms","start":"2026-06-16T11:39:01.103011Z","end":"2026-06-16T11:39:01.305187Z","steps":["trace[1856379827] 'agreement among raft nodes before linearized reading' (duration: 201.916182ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:39:01.638556Z","caller":"traceutil/trace.go:171","msg":"trace[110668831] transaction","detail":"{read_only:false; response_revision:4634; number_of_response:1; }","duration":"200.716282ms","start":"2026-06-16T11:39:01.437813Z","end":"2026-06-16T11:39:01.63853Z","steps":["trace[110668831] 'process raft request' (duration: 177.701574ms)","trace[110668831] 'compare' (duration: 22.343038ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:39:01.638774Z","caller":"traceutil/trace.go:171","msg":"trace[1959679145] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"199.783448ms","start":"2026-06-16T11:39:01.438968Z","end":"2026-06-16T11:39:01.638751Z","steps":["trace[1959679145] 'process raft request' (duration: 199.493603ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:39:01.63877Z","caller":"traceutil/trace.go:171","msg":"trace[266890789] linearizableReadLoop","detail":"{readStateIndex:4812; appliedIndex:4810; }","duration":"200.030582ms","start":"2026-06-16T11:39:01.438716Z","end":"2026-06-16T11:39:01.638747Z","steps":["trace[266890789] 'read index received' (duration: 26.768469ms)","trace[266890789] 'applied index is now lower than readState.Index' (duration: 173.260263ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:39:01.639049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.309706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:114261"} {"level":"info","ts":"2026-06-16T11:39:01.639093Z","caller":"traceutil/trace.go:171","msg":"trace[288994918] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4636; }","duration":"200.405798ms","start":"2026-06-16T11:39:01.438677Z","end":"2026-06-16T11:39:01.639082Z","steps":["trace[288994918] 'agreement among raft nodes before linearized reading' (duration: 200.195165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:39:01.639163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.62147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-16T11:39:01.63922Z","caller":"traceutil/trace.go:171","msg":"trace[100829180] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4636; }","duration":"198.709151ms","start":"2026-06-16T11:39:01.440492Z","end":"2026-06-16T11:39:01.639201Z","steps":["trace[100829180] 'agreement among raft nodes before linearized reading' (duration: 198.540657ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:39:01.639367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.952808ms","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-16T11:39:01.639495Z","caller":"traceutil/trace.go:171","msg":"trace[1567606881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4636; }","duration":"103.126891ms","start":"2026-06-16T11:39:01.536346Z","end":"2026-06-16T11:39:01.639473Z","steps":["trace[1567606881] 'agreement among raft nodes before linearized reading' (duration: 102.850767ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:39:04.372743Z","caller":"traceutil/trace.go:171","msg":"trace[1383204702] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"103.118695ms","start":"2026-06-16T11:39:04.269598Z","end":"2026-06-16T11:39:04.372717Z","steps":["trace[1383204702] 'process raft request' (duration: 102.902801ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:39:09.458822Z","caller":"traceutil/trace.go:171","msg":"trace[1617875434] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"129.21249ms","start":"2026-06-16T11:39:09.329588Z","end":"2026-06-16T11:39:09.458801Z","steps":["trace[1617875434] 'process raft request' (duration: 128.53922ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:39:14.519209Z","caller":"traceutil/trace.go:171","msg":"trace[1533216361] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"632.045156ms","start":"2026-06-16T11:39:13.887139Z","end":"2026-06-16T11:39:14.519184Z","steps":["trace[1533216361] 'process raft request' (duration: 631.877144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:39:14.519386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:39:13.887113Z","time spent":"632.200399ms","remote":"127.0.0.1:47946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11441,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:39:14.5535Z","caller":"traceutil/trace.go:171","msg":"trace[553383283] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"150.630218ms","start":"2026-06-16T11:39:14.402838Z","end":"2026-06-16T11:39:14.553468Z","steps":["trace[553383283] 'process raft request' (duration: 150.441354ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:39:14.678418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.980428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-4b8f3181-44cb-4a91-9ba0-fc9cfe892b57\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-06-16T11:39:14.678865Z","caller":"traceutil/trace.go:171","msg":"trace[1957015251] range","detail":"{range_begin:/registry/persistentvolumes/pvc-4b8f3181-44cb-4a91-9ba0-fc9cfe892b57; range_end:; response_count:1; response_revision:4857; }","duration":"112.458035ms","start":"2026-06-16T11:39:14.566374Z","end":"2026-06-16T11:39:14.678832Z","steps":["trace[1957015251] 'range keys from in-memory index tree' (duration: 111.769994ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:39:15.294196Z","caller":"traceutil/trace.go:171","msg":"trace[958790379] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"225.78876ms","start":"2026-06-16T11:39:15.068374Z","end":"2026-06-16T11:39:15.294163Z","steps":["trace[958790379] 'process raft request' (duration: 198.51692ms)","trace[958790379] 'compare' (duration: 27.015366ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:39:15.29444Z","caller":"traceutil/trace.go:171","msg":"trace[500060265] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"220.830482ms","start":"2026-06-16T11:39:15.073591Z","end":"2026-06-16T11:39:15.294421Z","steps":["trace[500060265] 'process raft request' (duration: 220.70185ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:39:15.294506Z","caller":"traceutil/trace.go:171","msg":"trace[1751352898] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"225.545415ms","start":"2026-06-16T11:39:15.068937Z","end":"2026-06-16T11:39:15.294482Z","steps":["trace[1751352898] 'process raft request' (duration: 225.149319ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:39:50.022666Z","caller":"traceutil/trace.go:171","msg":"trace[1401241317] transaction","detail":"{read_only:false; response_revision:5167; number_of_response:1; }","duration":"107.706435ms","start":"2026-06-16T11:39:49.914932Z","end":"2026-06-16T11:39:50.022638Z","steps":["trace[1401241317] 'process raft request' (duration: 76.368397ms)","trace[1401241317] 'compare' (duration: 31.083344ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:39:52.949966Z","caller":"traceutil/trace.go:171","msg":"trace[1933258074] linearizableReadLoop","detail":"{readStateIndex:5377; appliedIndex:5376; }","duration":"130.959477ms","start":"2026-06-16T11:39:52.818989Z","end":"2026-06-16T11:39:52.949949Z","steps":["trace[1933258074] 'read index received' (duration: 130.805384ms)","trace[1933258074] 'applied index is now lower than readState.Index' (duration: 153.463µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:39:52.950021Z","caller":"traceutil/trace.go:171","msg":"trace[1617991503] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"205.042005ms","start":"2026-06-16T11:39:52.744949Z","end":"2026-06-16T11:39:52.949991Z","steps":["trace[1617991503] 'process raft request' (duration: 204.897893ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:39:52.950209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.19682ms","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-16T11:39:52.950262Z","caller":"traceutil/trace.go:171","msg":"trace[445421235] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:5183; }","duration":"131.298502ms","start":"2026-06-16T11:39:52.818953Z","end":"2026-06-16T11:39:52.950252Z","steps":["trace[445421235] 'agreement among raft nodes before linearized reading' (duration: 131.13233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:39:52.95143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.305185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-06-16T11:39:52.951552Z","caller":"traceutil/trace.go:171","msg":"trace[771481662] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5183; }","duration":"126.474399ms","start":"2026-06-16T11:39:52.82506Z","end":"2026-06-16T11:39:52.951534Z","steps":["trace[771481662] 'agreement among raft nodes before linearized reading' (duration: 125.510404ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:40:04.984208Z","caller":"traceutil/trace.go:171","msg":"trace[1149937068] linearizableReadLoop","detail":"{readStateIndex:5469; appliedIndex:5468; }","duration":"113.738298ms","start":"2026-06-16T11:40:04.870441Z","end":"2026-06-16T11:40:04.984179Z","steps":["trace[1149937068] 'read index received' (duration: 113.486435ms)","trace[1149937068] 'applied index is now lower than readState.Index' (duration: 250.673µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:40:04.984286Z","caller":"traceutil/trace.go:171","msg":"trace[1192294538] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"145.69676ms","start":"2026-06-16T11:40:04.838563Z","end":"2026-06-16T11:40:04.984259Z","steps":["trace[1192294538] 'process raft request' (duration: 145.440047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:40:04.984361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.894851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T11:40:04.984422Z","caller":"traceutil/trace.go:171","msg":"trace[1675021114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5272; }","duration":"113.997103ms","start":"2026-06-16T11:40:04.870407Z","end":"2026-06-16T11:40:04.984404Z","steps":["trace[1675021114] 'agreement among raft nodes before linearized reading' (duration: 113.890981ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:40:32.610325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.917311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T11:40:32.610621Z","caller":"traceutil/trace.go:171","msg":"trace[1032314629] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"547.66267ms","start":"2026-06-16T11:40:32.062918Z","end":"2026-06-16T11:40:32.61058Z","steps":["trace[1032314629] 'process raft request' (duration: 101.395543ms)","trace[1032314629] 'compare' (duration: 445.752268ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:40:32.610879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:40:32.062878Z","time spent":"547.850013ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:40:32.610648Z","caller":"traceutil/trace.go:171","msg":"trace[2112344477] linearizableReadLoop","detail":"{readStateIndex:5671; appliedIndex:5669; }","duration":"375.423436ms","start":"2026-06-16T11:40:32.235173Z","end":"2026-06-16T11:40:32.610596Z","steps":["trace[2112344477] 'read index received' (duration: 361.764793ms)","trace[2112344477] 'applied index is now lower than readState.Index' (duration: 13.657443ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:40:32.611411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.589049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-16T11:40:32.6112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.026064ms","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-16T11:40:32.611456Z","caller":"traceutil/trace.go:171","msg":"trace[462224951] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5469; }","duration":"369.673841ms","start":"2026-06-16T11:40:32.241771Z","end":"2026-06-16T11:40:32.611445Z","steps":["trace[462224951] 'agreement among raft nodes before linearized reading' (duration: 369.510949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:40:32.61153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:40:32.24175Z","time spent":"369.769572ms","remote":"127.0.0.1:48036","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":"info","ts":"2026-06-16T11:40:32.611659Z","caller":"traceutil/trace.go:171","msg":"trace[23327451] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"422.068188ms","start":"2026-06-16T11:40:32.189578Z","end":"2026-06-16T11:40:32.611646Z","steps":["trace[23327451] 'process raft request' (duration: 420.863451ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:40:32.611763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:40:32.18955Z","time spent":"422.148489ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:40:32.611865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.684403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-16T11:40:32.611489Z","caller":"traceutil/trace.go:171","msg":"trace[87110877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5469; }","duration":"376.359119ms","start":"2026-06-16T11:40:32.235116Z","end":"2026-06-16T11:40:32.611475Z","steps":["trace[87110877] 'agreement among raft nodes before linearized reading' (duration: 375.562758ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:40:32.611971Z","caller":"traceutil/trace.go:171","msg":"trace[1281856011] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:5469; }","duration":"328.815284ms","start":"2026-06-16T11:40:32.283122Z","end":"2026-06-16T11:40:32.611937Z","steps":["trace[1281856011] 'agreement among raft nodes before linearized reading' (duration: 328.5122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:40:32.611963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:40:32.235094Z","time spent":"376.835476ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-16T11:40:32.612064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:40:32.283101Z","time spent":"328.902996ms","remote":"127.0.0.1:47822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"warn","ts":"2026-06-16T11:40:32.612079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.218328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8241"} {"level":"info","ts":"2026-06-16T11:40:32.612166Z","caller":"traceutil/trace.go:171","msg":"trace[2144458705] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:5469; }","duration":"345.318159ms","start":"2026-06-16T11:40:32.266827Z","end":"2026-06-16T11:40:32.612146Z","steps":["trace[2144458705] 'agreement among raft nodes before linearized reading' (duration: 345.053735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:40:32.612217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:40:32.266759Z","time spent":"345.446991ms","remote":"127.0.0.1:48008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":8265,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"info","ts":"2026-06-16T11:40:57.459708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2901} {"level":"info","ts":"2026-06-16T11:40:57.553368Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2901,"took":"92.166347ms","hash":3811902442,"current-db-size-bytes":54087680,"current-db-size":"54 MB","current-db-size-in-use-bytes":29556736,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-16T11:40:57.553442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3811902442,"revision":2901,"compact-revision":-1} {"level":"info","ts":"2026-06-16T11:42:47.246273Z","caller":"traceutil/trace.go:171","msg":"trace[77482453] transaction","detail":"{read_only:false; response_revision:6397; number_of_response:1; }","duration":"121.903548ms","start":"2026-06-16T11:42:47.124342Z","end":"2026-06-16T11:42:47.246245Z","steps":["trace[77482453] 'process raft request' (duration: 121.755936ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:42:47.247719Z","caller":"traceutil/trace.go:171","msg":"trace[1718665124] transaction","detail":"{read_only:false; response_revision:6398; number_of_response:1; }","duration":"118.830613ms","start":"2026-06-16T11:42:47.128862Z","end":"2026-06-16T11:42:47.247693Z","steps":["trace[1718665124] 'process raft request' (duration: 118.649941ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:42:59.398536Z","caller":"traceutil/trace.go:171","msg":"trace[535627246] transaction","detail":"{read_only:false; response_revision:6464; number_of_response:1; }","duration":"114.428968ms","start":"2026-06-16T11:42:59.28408Z","end":"2026-06-16T11:42:59.398509Z","steps":["trace[535627246] 'process raft request' (duration: 114.241216ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:42:59.464951Z","caller":"traceutil/trace.go:171","msg":"trace[1368530656] transaction","detail":"{read_only:false; response_revision:6465; number_of_response:1; }","duration":"156.092583ms","start":"2026-06-16T11:42:59.308837Z","end":"2026-06-16T11:42:59.46493Z","steps":["trace[1368530656] 'process raft request' (duration: 155.990751ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:45:57.465422Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5647} {"level":"info","ts":"2026-06-16T11:45:57.577365Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5647,"took":"110.330032ms","hash":1831171486,"current-db-size-bytes":54087680,"current-db-size":"54 MB","current-db-size-in-use-bytes":18165760,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-16T11:45:57.577414Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1831171486,"revision":5647,"compact-revision":2901}