{"level":"warn","ts":"2026-08-13T08:30:04.214004Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T08:30:04.214122Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-13T08:30:04.214207Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T08:30:04.214224Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-08-13T08:30:04.214246Z","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-08-13T08:30:04.215063Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-08-13T08:30:04.215159Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-13T08:30:04.219817Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.391865ms"} {"level":"info","ts":"2026-08-13T08:30:04.227579Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-08-13T08:30:04.227662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-08-13T08:30:04.227682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-08-13T08:30:04.227697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-13T08:30:04.227704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-08-13T08:30:04.227747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-08-13T08:30:04.235475Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-13T08:30:04.237099Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-13T08:30:04.238297Z","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-08-13T08:30:04.242494Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-13T08:30:04.242978Z","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-08-13T08:30:04.247696Z","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-08-13T08:30:04.247776Z","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-08-13T08:30:04.247506Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-13T08:30:04.248936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-08-13T08:30:04.24911Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-08-13T08:30:04.251319Z","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-08-13T08:30:04.251554Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-13T08:30:04.251585Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-13T08:30:04.251657Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-08-13T08:30:04.251674Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-08-13T08:30:04.328632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-08-13T08:30:04.328747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-13T08:30:04.328809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-08-13T08:30:04.328836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-08-13T08:30:04.328854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-08-13T08:30:04.328874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-08-13T08:30:04.328898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-08-13T08:30:04.329979Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T08:30:04.330747Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T08:30:04.330753Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-08-13T08:30:04.330919Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T08:30:04.331267Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-13T08:30:04.331376Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-13T08:30:04.331543Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T08:30:04.331748Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T08:30:04.33182Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T08:30:04.336165Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-08-13T08:30:04.336406Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-13T08:30:07.392245Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228168877032408,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:30:08.030407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.293723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-13T08:30:08.030508Z","caller":"traceutil/trace.go:171","msg":"trace[1293354287] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"1.138673076s","start":"2026-08-13T08:30:06.891821Z","end":"2026-08-13T08:30:08.030494Z","steps":["trace[1293354287] 'read index received' (duration: 512.916878ms)","trace[1293354287] 'applied index is now lower than readState.Index' (duration: 625.752448ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:30:08.030582Z","caller":"traceutil/trace.go:171","msg":"trace[434737920] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"1.139466835s","start":"2026-08-13T08:30:06.891105Z","end":"2026-08-13T08:30:08.030572Z","steps":["trace[434737920] 'process raft request' (duration: 513.591116ms)","trace[434737920] 'compare' (duration: 625.121851ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:30:08.030624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:06.89109Z","time spent":"1.139514895s","remote":"127.0.0.1:56026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T08:30:08.031027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139181991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-13T08:30:08.031043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049092094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T08:30:08.031163Z","caller":"traceutil/trace.go:171","msg":"trace[325668985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"1.049282415s","start":"2026-08-13T08:30:06.981862Z","end":"2026-08-13T08:30:08.031144Z","steps":["trace[325668985] 'agreement among raft nodes before linearized reading' (duration: 1.049007503s)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:30:08.031073Z","caller":"traceutil/trace.go:171","msg":"trace[1890042011] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:73; }","duration":"1.139271391s","start":"2026-08-13T08:30:06.891788Z","end":"2026-08-13T08:30:08.03106Z","steps":["trace[1890042011] 'agreement among raft nodes before linearized reading' (duration: 1.13913176s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:30:08.031223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:06.981839Z","time spent":"1.049368216s","remote":"127.0.0.1:55692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T08:30:08.031291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:06.891777Z","time spent":"1.139435513s","remote":"127.0.0.1:56014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-08-13T08:30:09.389738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.083687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-13T08:30:09.38995Z","caller":"traceutil/trace.go:171","msg":"trace[1770831118] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"1.307009685s","start":"2026-08-13T08:30:08.082864Z","end":"2026-08-13T08:30:09.389874Z","steps":["trace[1770831118] 'read index received' (duration: 498.645116ms)","trace[1770831118] 'applied index is now lower than readState.Index' (duration: 808.363049ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:30:09.389941Z","caller":"traceutil/trace.go:171","msg":"trace[1936313008] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"1.350385473s","start":"2026-08-13T08:30:08.039499Z","end":"2026-08-13T08:30:09.389885Z","steps":["trace[1936313008] 'process raft request' (duration: 542.055004ms)","trace[1936313008] 'compare' (duration: 807.856695ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:30:09.390133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:08.039486Z","time spent":"1.350549565s","remote":"127.0.0.1:56014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T08:30:09.390178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307272369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T08:30:09.390255Z","caller":"traceutil/trace.go:171","msg":"trace[1149883957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"1.30744342s","start":"2026-08-13T08:30:08.082792Z","end":"2026-08-13T08:30:09.390236Z","steps":["trace[1149883957] 'agreement among raft nodes before linearized reading' (duration: 1.307214836s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:30:09.390365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:08.082765Z","time spent":"1.307587841s","remote":"127.0.0.1:55692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T08:30:09.538541Z","caller":"traceutil/trace.go:171","msg":"trace[483827197] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"662.795208ms","start":"2026-08-13T08:30:08.875733Z","end":"2026-08-13T08:30:09.538528Z","steps":["trace[483827197] 'process raft request' (duration: 662.084231ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:30:09.53868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:08.875697Z","time spent":"662.895549ms","remote":"127.0.0.1:55864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T08:30:09.538699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.319566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T08:30:09.538783Z","caller":"traceutil/trace.go:171","msg":"trace[1647155197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:75; }","duration":"252.438488ms","start":"2026-08-13T08:30:09.286315Z","end":"2026-08-13T08:30:09.538754Z","steps":["trace[1647155197] 'agreement among raft nodes before linearized reading' (duration: 252.243566ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:30:09.538402Z","caller":"traceutil/trace.go:171","msg":"trace[350834541] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"148.384146ms","start":"2026-08-13T08:30:09.389993Z","end":"2026-08-13T08:30:09.538377Z","steps":["trace[350834541] 'read index received' (duration: 147.668969ms)","trace[350834541] 'applied index is now lower than readState.Index' (duration: 714.167µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:30:09.538936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.184221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T08:30:09.538979Z","caller":"traceutil/trace.go:171","msg":"trace[1008172063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:75; }","duration":"531.259812ms","start":"2026-08-13T08:30:09.007708Z","end":"2026-08-13T08:30:09.538968Z","steps":["trace[1008172063] 'agreement among raft nodes before linearized reading' (duration: 531.179741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:30:09.539012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:09.007689Z","time spent":"531.316152ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T08:30:09.539052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.933762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T08:30:09.5391Z","caller":"traceutil/trace.go:171","msg":"trace[296289271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:75; }","duration":"147.009153ms","start":"2026-08-13T08:30:09.392081Z","end":"2026-08-13T08:30:09.53909Z","steps":["trace[296289271] 'agreement among raft nodes before linearized reading' (duration: 146.946142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:30:09.539207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.377447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T08:30:09.539259Z","caller":"traceutil/trace.go:171","msg":"trace[1993107120] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:0; response_revision:75; }","duration":"144.483488ms","start":"2026-08-13T08:30:09.394765Z","end":"2026-08-13T08:30:09.539248Z","steps":["trace[1993107120] 'agreement among raft nodes before linearized reading' (duration: 144.415698ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:30:41.956078Z","caller":"traceutil/trace.go:171","msg":"trace[2061547547] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"243.884537ms","start":"2026-08-13T08:30:41.712177Z","end":"2026-08-13T08:30:41.956062Z","steps":["trace[2061547547] 'process raft request' (duration: 243.790144ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:30:42.939719Z","caller":"traceutil/trace.go:171","msg":"trace[1985885264] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"289.300558ms","start":"2026-08-13T08:30:42.65039Z","end":"2026-08-13T08:30:42.939691Z","steps":["trace[1985885264] 'process raft request' (duration: 284.28055ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:30:42.940234Z","caller":"traceutil/trace.go:171","msg":"trace[656446651] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:542; }","duration":"239.339731ms","start":"2026-08-13T08:30:42.70087Z","end":"2026-08-13T08:30:42.94021Z","steps":["trace[656446651] 'read index received' (duration: 233.757761ms)","trace[656446651] 'applied index is now lower than readState.Index' (duration: 5.58086ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:30:42.94037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.487757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:30:42.940406Z","caller":"traceutil/trace.go:171","msg":"trace[120123644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"239.575899ms","start":"2026-08-13T08:30:42.70082Z","end":"2026-08-13T08:30:42.940396Z","steps":["trace[120123644] 'agreement among raft nodes before linearized reading' (duration: 239.483816ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:30:42.940577Z","caller":"traceutil/trace.go:171","msg":"trace[247653844] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"289.517935ms","start":"2026-08-13T08:30:42.651045Z","end":"2026-08-13T08:30:42.940563Z","steps":["trace[247653844] 'process raft request' (duration: 288.85403ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:30:42.940648Z","caller":"traceutil/trace.go:171","msg":"trace[572031681] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"276.269988ms","start":"2026-08-13T08:30:42.664363Z","end":"2026-08-13T08:30:42.940633Z","steps":["trace[572031681] 'process raft request' (duration: 275.77385ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:30:43.253109Z","caller":"traceutil/trace.go:171","msg":"trace[849178137] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"253.828418ms","start":"2026-08-13T08:30:42.999249Z","end":"2026-08-13T08:30:43.253078Z","steps":["trace[849178137] 'process raft request' (duration: 247.186452ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:30:43.253432Z","caller":"traceutil/trace.go:171","msg":"trace[1747251809] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"249.750826ms","start":"2026-08-13T08:30:43.003672Z","end":"2026-08-13T08:30:43.253423Z","steps":["trace[1747251809] 'process raft request' (duration: 249.302958ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:30:43.253614Z","caller":"traceutil/trace.go:171","msg":"trace[2113355373] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"237.822571ms","start":"2026-08-13T08:30:43.015768Z","end":"2026-08-13T08:30:43.253591Z","steps":["trace[2113355373] 'process raft request' (duration: 237.330232ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:30:43.253944Z","caller":"traceutil/trace.go:171","msg":"trace[320766969] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:547; }","duration":"130.864395ms","start":"2026-08-13T08:30:43.123057Z","end":"2026-08-13T08:30:43.253922Z","steps":["trace[320766969] 'read index received' (duration: 123.454049ms)","trace[320766969] 'applied index is now lower than readState.Index' (duration: 7.409276ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:30:43.254453Z","caller":"traceutil/trace.go:171","msg":"trace[749306696] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"218.182251ms","start":"2026-08-13T08:30:43.035862Z","end":"2026-08-13T08:30:43.254044Z","steps":["trace[749306696] 'process raft request' (duration: 217.738154ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:30:43.255264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.194134ms","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-08-13T08:30:43.255342Z","caller":"traceutil/trace.go:171","msg":"trace[394556330] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"132.297107ms","start":"2026-08-13T08:30:43.123009Z","end":"2026-08-13T08:30:43.255306Z","steps":["trace[394556330] 'agreement among raft nodes before linearized reading' (duration: 131.00121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:30:49.467048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.421896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:30:49.467243Z","caller":"traceutil/trace.go:171","msg":"trace[215124882] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"172.660487ms","start":"2026-08-13T08:30:49.294561Z","end":"2026-08-13T08:30:49.467222Z","steps":["trace[215124882] 'read index received' (duration: 45.001µs)","trace[215124882] 'applied index is now lower than readState.Index' (duration: 172.613666ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:30:49.467508Z","caller":"traceutil/trace.go:171","msg":"trace[1977877656] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"505.707267ms","start":"2026-08-13T08:30:48.961749Z","end":"2026-08-13T08:30:49.467456Z","steps":["trace[1977877656] 'process raft request' (duration: 258.729232ms)","trace[1977877656] 'compare' (duration: 246.143925ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:30:49.467581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.981078ms","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-08-13T08:30:49.467632Z","caller":"traceutil/trace.go:171","msg":"trace[572904417] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"173.103732ms","start":"2026-08-13T08:30:49.294512Z","end":"2026-08-13T08:30:49.467616Z","steps":["trace[572904417] 'agreement among raft nodes before linearized reading' (duration: 172.816463ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:30:49.467694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:48.961717Z","time spent":"505.896843ms","remote":"127.0.0.1:56188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T08:30:50.062687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.306246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:30:50.062799Z","caller":"traceutil/trace.go:171","msg":"trace[1175257828] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"580.91687ms","start":"2026-08-13T08:30:49.481866Z","end":"2026-08-13T08:30:50.062783Z","steps":["trace[1175257828] 'read index received' (duration: 454.176508ms)","trace[1175257828] 'applied index is now lower than readState.Index' (duration: 126.739171ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:30:50.062806Z","caller":"traceutil/trace.go:171","msg":"trace[1352273354] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"590.720864ms","start":"2026-08-13T08:30:49.472069Z","end":"2026-08-13T08:30:50.06279Z","steps":["trace[1352273354] 'process raft request' (duration: 464.054045ms)","trace[1352273354] 'compare' (duration: 126.158221ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:30:50.062902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.035774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:30:50.062971Z","caller":"traceutil/trace.go:171","msg":"trace[1313314867] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:597; }","duration":"581.125557ms","start":"2026-08-13T08:30:49.481831Z","end":"2026-08-13T08:30:50.062956Z","steps":["trace[1313314867] 'agreement among raft nodes before linearized reading' (duration: 581.038654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:30:50.063012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:49.481815Z","time spent":"581.188089ms","remote":"127.0.0.1:55918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-08-13T08:30:50.063047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:49.472042Z","time spent":"590.8865ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:30:51.715651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.496535196s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:30:51.715759Z","caller":"traceutil/trace.go:171","msg":"trace[1616145767] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"1.648405352s","start":"2026-08-13T08:30:50.067336Z","end":"2026-08-13T08:30:51.715741Z","steps":["trace[1616145767] 'read index received' (duration: 151.627448ms)","trace[1616145767] 'applied index is now lower than readState.Index' (duration: 1.496776574s)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:30:51.716117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.648761264s","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-08-13T08:30:51.716197Z","caller":"traceutil/trace.go:171","msg":"trace[849514733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"1.648871358s","start":"2026-08-13T08:30:50.067308Z","end":"2026-08-13T08:30:51.71618Z","steps":["trace[849514733] 'agreement among raft nodes before linearized reading' (duration: 1.648644161s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:30:51.716233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:50.067287Z","time spent":"1.648939831s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-13T08:30:51.716244Z","caller":"traceutil/trace.go:171","msg":"trace[2077000655] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.649840081s","start":"2026-08-13T08:30:50.066372Z","end":"2026-08-13T08:30:51.716212Z","steps":["trace[2077000655] 'process raft request' (duration: 152.656293ms)","trace[2077000655] 'compare' (duration: 1.496372881s)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:30:51.716416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:50.066332Z","time spent":"1.649986378s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:30:51.73272Z","caller":"traceutil/trace.go:171","msg":"trace[915127799] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.267901286s","start":"2026-08-13T08:30:50.46478Z","end":"2026-08-13T08:30:51.732681Z","steps":["trace[915127799] 'process raft request' (duration: 1.26625727s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:30:51.732857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:50.46475Z","time spent":"1.26802925s","remote":"127.0.0.1:55770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:30:51.732907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030619424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T08:30:51.73295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536009227s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-13T08:30:51.73298Z","caller":"traceutil/trace.go:171","msg":"trace[14836309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"1.030750309s","start":"2026-08-13T08:30:50.702214Z","end":"2026-08-13T08:30:51.732965Z","steps":["trace[14836309] 'agreement among raft nodes before linearized reading' (duration: 1.030625284s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:30:51.733008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:50.702181Z","time spent":"1.030822881s","remote":"127.0.0.1:55692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T08:30:51.732997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.448434045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-13T08:30:51.733014Z","caller":"traceutil/trace.go:171","msg":"trace[53484861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"1.536122601s","start":"2026-08-13T08:30:50.196874Z","end":"2026-08-13T08:30:51.732996Z","steps":["trace[53484861] 'agreement among raft nodes before linearized reading' (duration: 1.535879943s)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:30:51.733058Z","caller":"traceutil/trace.go:171","msg":"trace[1907128130] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:600; }","duration":"1.448539529s","start":"2026-08-13T08:30:50.284504Z","end":"2026-08-13T08:30:51.733044Z","steps":["trace[1907128130] 'agreement among raft nodes before linearized reading' (duration: 1.448445396s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:30:51.73307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:50.196848Z","time spent":"1.536209594s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T08:30:51.733099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:50.284481Z","time spent":"1.448610302s","remote":"127.0.0.1:55728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.223\" "} {"level":"info","ts":"2026-08-13T08:30:53.395127Z","caller":"traceutil/trace.go:171","msg":"trace[1885236594] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"353.83903ms","start":"2026-08-13T08:30:53.041256Z","end":"2026-08-13T08:30:53.395095Z","steps":["trace[1885236594] 'process raft request' (duration: 350.342632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:30:53.395372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:53.041232Z","time spent":"354.021145ms","remote":"127.0.0.1:55734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5162,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:30:53.402154Z","caller":"traceutil/trace.go:171","msg":"trace[72780215] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"359.792609ms","start":"2026-08-13T08:30:53.042335Z","end":"2026-08-13T08:30:53.402128Z","steps":["trace[72780215] 'process raft request' (duration: 359.641184ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:30:53.402365Z","caller":"traceutil/trace.go:171","msg":"trace[1138754887] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"182.940136ms","start":"2026-08-13T08:30:53.219348Z","end":"2026-08-13T08:30:53.402288Z","steps":["trace[1138754887] 'process raft request' (duration: 182.738699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:30:53.402372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:30:53.042321Z","time spent":"359.912622ms","remote":"127.0.0.1:55734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:31:06.169864Z","caller":"traceutil/trace.go:171","msg":"trace[1970632511] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"385.676491ms","start":"2026-08-13T08:31:05.784155Z","end":"2026-08-13T08:31:06.169831Z","steps":["trace[1970632511] 'read index received' (duration: 385.492376ms)","trace[1970632511] 'applied index is now lower than readState.Index' (duration: 182.655µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:31:06.17003Z","caller":"traceutil/trace.go:171","msg":"trace[1228302524] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"389.175737ms","start":"2026-08-13T08:31:05.780841Z","end":"2026-08-13T08:31:06.170016Z","steps":["trace[1228302524] 'process raft request' (duration: 388.783265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:31:06.170157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:31:05.780817Z","time spent":"389.248378ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:31:06.170287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.081784ms","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-08-13T08:31:06.170559Z","caller":"traceutil/trace.go:171","msg":"trace[238360095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"386.404613ms","start":"2026-08-13T08:31:05.784133Z","end":"2026-08-13T08:31:06.170538Z","steps":["trace[238360095] 'agreement among raft nodes before linearized reading' (duration: 385.939639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:31:06.170609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:31:05.784126Z","time spent":"386.468915ms","remote":"127.0.0.1:55944","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-08-13T08:31:06.183057Z","caller":"traceutil/trace.go:171","msg":"trace[1170846472] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"192.390071ms","start":"2026-08-13T08:31:05.990655Z","end":"2026-08-13T08:31:06.183045Z","steps":["trace[1170846472] 'process raft request' (duration: 192.05036ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:31:06.183591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.162313ms","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-08-13T08:31:06.183653Z","caller":"traceutil/trace.go:171","msg":"trace[1033167931] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:757; }","duration":"383.279226ms","start":"2026-08-13T08:31:05.80036Z","end":"2026-08-13T08:31:06.18364Z","steps":["trace[1033167931] 'agreement among raft nodes before linearized reading' (duration: 382.494903ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:31:06.183685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:31:05.80034Z","time spent":"383.337608ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-13T08:31:36.433904Z","caller":"traceutil/trace.go:171","msg":"trace[254232430] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1198; }","duration":"103.645154ms","start":"2026-08-13T08:31:36.330231Z","end":"2026-08-13T08:31:36.433876Z","steps":["trace[254232430] 'read index received' (duration: 103.345417ms)","trace[254232430] 'applied index is now lower than readState.Index' (duration: 298.427µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:31:36.43517Z","caller":"traceutil/trace.go:171","msg":"trace[381684718] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"116.974115ms","start":"2026-08-13T08:31:36.318159Z","end":"2026-08-13T08:31:36.435133Z","steps":["trace[381684718] 'process raft request' (duration: 115.49562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:31:36.43524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.991746ms","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-08-13T08:31:36.435278Z","caller":"traceutil/trace.go:171","msg":"trace[958415710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1162; }","duration":"105.075018ms","start":"2026-08-13T08:31:36.330193Z","end":"2026-08-13T08:31:36.435268Z","steps":["trace[958415710] 'agreement among raft nodes before linearized reading' (duration: 104.928274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:31:54.379024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.357502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-phpcp\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-08-13T08:31:54.379154Z","caller":"traceutil/trace.go:171","msg":"trace[412164041] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-phpcp; range_end:; response_count:1; response_revision:1307; }","duration":"205.533116ms","start":"2026-08-13T08:31:54.173595Z","end":"2026-08-13T08:31:54.379128Z","steps":["trace[412164041] 'range keys from in-memory index tree' (duration: 205.187929ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:32:02.87223Z","caller":"traceutil/trace.go:171","msg":"trace[599637594] linearizableReadLoop","detail":"{readStateIndex:1484; appliedIndex:1483; }","duration":"277.623362ms","start":"2026-08-13T08:32:02.594581Z","end":"2026-08-13T08:32:02.872204Z","steps":["trace[599637594] 'read index received' (duration: 277.5199ms)","trace[599637594] 'applied index is now lower than readState.Index' (duration: 102.242µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:32:02.872458Z","caller":"traceutil/trace.go:171","msg":"trace[1530465412] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"278.737444ms","start":"2026-08-13T08:32:02.593688Z","end":"2026-08-13T08:32:02.872425Z","steps":["trace[1530465412] 'process raft request' (duration: 278.347477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:02.872493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.896017ms","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-08-13T08:32:02.87278Z","caller":"traceutil/trace.go:171","msg":"trace[1709881615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1436; }","duration":"278.191792ms","start":"2026-08-13T08:32:02.594554Z","end":"2026-08-13T08:32:02.872746Z","steps":["trace[1709881615] 'agreement among raft nodes before linearized reading' (duration: 277.762104ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:32:03.080355Z","caller":"traceutil/trace.go:171","msg":"trace[1530703077] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"484.695484ms","start":"2026-08-13T08:32:02.595635Z","end":"2026-08-13T08:32:03.08033Z","steps":["trace[1530703077] 'process raft request' (duration: 430.195206ms)","trace[1530703077] 'compare' (duration: 54.336165ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:32:03.080494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:02.595615Z","time spent":"484.809837ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:32:03.080637Z","caller":"traceutil/trace.go:171","msg":"trace[2048884357] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"203.797179ms","start":"2026-08-13T08:32:02.876824Z","end":"2026-08-13T08:32:03.080622Z","steps":["trace[2048884357] 'process raft request' (duration: 203.464522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:19.187044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.157435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:32:19.18714Z","caller":"traceutil/trace.go:171","msg":"trace[1579610760] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1738; }","duration":"112.284616ms","start":"2026-08-13T08:32:19.074839Z","end":"2026-08-13T08:32:19.187124Z","steps":["trace[1579610760] 'range keys from in-memory index tree' (duration: 112.095523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:19.187497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.251126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:32:19.187552Z","caller":"traceutil/trace.go:171","msg":"trace[917321611] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1738; }","duration":"112.329038ms","start":"2026-08-13T08:32:19.075213Z","end":"2026-08-13T08:32:19.187542Z","steps":["trace[917321611] 'count revisions from in-memory index tree' (duration: 112.224376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:27.438567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109454515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5ec8c4bc-7696-4f37-8dc8-d362c0623b9f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-13T08:32:27.438673Z","caller":"traceutil/trace.go:171","msg":"trace[1076104011] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5ec8c4bc-7696-4f37-8dc8-d362c0623b9f; range_end:; response_count:1; response_revision:1844; }","duration":"1.109569256s","start":"2026-08-13T08:32:26.329062Z","end":"2026-08-13T08:32:27.438631Z","steps":["trace[1076104011] 'range keys from in-memory index tree' (duration: 1.109300762s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:27.438717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:26.329048Z","time spent":"1.109655648s","remote":"127.0.0.1:55864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5ec8c4bc-7696-4f37-8dc8-d362c0623b9f\" "} {"level":"warn","ts":"2026-08-13T08:32:27.439117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044697953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5ec8c4bc-7696-4f37-8dc8-d362c0623b9f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-13T08:32:27.439168Z","caller":"traceutil/trace.go:171","msg":"trace[304149584] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5ec8c4bc-7696-4f37-8dc8-d362c0623b9f; range_end:; response_count:1; response_revision:1844; }","duration":"1.044760434s","start":"2026-08-13T08:32:26.394394Z","end":"2026-08-13T08:32:27.439154Z","steps":["trace[304149584] 'range keys from in-memory index tree' (duration: 1.044577741s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:27.439199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:26.394382Z","time spent":"1.044808575s","remote":"127.0.0.1:55864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5ec8c4bc-7696-4f37-8dc8-d362c0623b9f\" "} {"level":"warn","ts":"2026-08-13T08:32:27.439248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.022569ms","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-08-13T08:32:27.439294Z","caller":"traceutil/trace.go:171","msg":"trace[1627791611] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1844; }","duration":"949.118421ms","start":"2026-08-13T08:32:26.490163Z","end":"2026-08-13T08:32:27.439282Z","steps":["trace[1627791611] 'count revisions from in-memory index tree' (duration: 948.956858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:27.439327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:26.490147Z","time spent":"949.170562ms","remote":"127.0.0.1:56272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-13T08:32:27.439619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.870925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:32:27.439709Z","caller":"traceutil/trace.go:171","msg":"trace[1905933588] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1919; }","duration":"744.700017ms","start":"2026-08-13T08:32:26.694994Z","end":"2026-08-13T08:32:27.439694Z","steps":["trace[1905933588] 'read index received' (duration: 380.202721ms)","trace[1905933588] 'applied index is now lower than readState.Index' (duration: 364.495946ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:32:27.44Z","caller":"traceutil/trace.go:171","msg":"trace[330648061] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"917.257475ms","start":"2026-08-13T08:32:26.522725Z","end":"2026-08-13T08:32:27.439982Z","steps":["trace[330648061] 'process raft request' (duration: 552.890882ms)","trace[330648061] 'compare' (duration: 362.971098ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:32:27.440072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:26.522705Z","time spent":"917.329866ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:32:27.440299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.323908ms","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-08-13T08:32:27.440327Z","caller":"traceutil/trace.go:171","msg":"trace[1442099382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1845; }","duration":"745.352289ms","start":"2026-08-13T08:32:26.694966Z","end":"2026-08-13T08:32:27.440318Z","steps":["trace[1442099382] 'agreement among raft nodes before linearized reading' (duration: 745.246167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:27.440355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:26.694888Z","time spent":"745.4605ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-13T08:32:27.531287Z","caller":"traceutil/trace.go:171","msg":"trace[1015069637] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"571.396941ms","start":"2026-08-13T08:32:26.959868Z","end":"2026-08-13T08:32:27.531265Z","steps":["trace[1015069637] 'process raft request' (duration: 571.263678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:27.531411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:26.959842Z","time spent":"571.505942ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:32:28.538948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.005699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:32:28.539309Z","caller":"traceutil/trace.go:171","msg":"trace[1832641972] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1920; }","duration":"1.099560841s","start":"2026-08-13T08:32:27.439731Z","end":"2026-08-13T08:32:28.539292Z","steps":["trace[1832641972] 'read index received' (duration: 91.466401ms)","trace[1832641972] 'applied index is now lower than readState.Index' (duration: 1.00809355s)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:32:28.539343Z","caller":"traceutil/trace.go:171","msg":"trace[1787252658] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"1.094180667s","start":"2026-08-13T08:32:27.445132Z","end":"2026-08-13T08:32:28.539312Z","steps":["trace[1787252658] 'process raft request' (duration: 1.093977104s)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:32:28.539449Z","caller":"traceutil/trace.go:171","msg":"trace[193489621] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"1.273348186s","start":"2026-08-13T08:32:27.266039Z","end":"2026-08-13T08:32:28.539387Z","steps":["trace[193489621] 'process raft request' (duration: 1.069801949s)","trace[193489621] 'compare' (duration: 202.860196ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:32:28.539515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:27.445113Z","time spent":"1.094311439s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:32:28.539653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:27.266015Z","time spent":"1.273522149s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:32:28.539728Z","caller":"traceutil/trace.go:171","msg":"trace[939304888] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"1.093227311s","start":"2026-08-13T08:32:27.446479Z","end":"2026-08-13T08:32:28.539706Z","steps":["trace[939304888] 'process raft request' (duration: 1.092726542s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:28.539769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272358909s","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-08-13T08:32:28.539803Z","caller":"traceutil/trace.go:171","msg":"trace[679934312] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"1.041224115s","start":"2026-08-13T08:32:27.498562Z","end":"2026-08-13T08:32:28.539786Z","steps":["trace[679934312] 'process raft request' (duration: 1.040690286s)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:32:28.539809Z","caller":"traceutil/trace.go:171","msg":"trace[797862499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1850; }","duration":"1.27243396s","start":"2026-08-13T08:32:27.267364Z","end":"2026-08-13T08:32:28.539798Z","steps":["trace[797862499] 'agreement among raft nodes before linearized reading' (duration: 1.272285918s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:28.539854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:27.267348Z","time spent":"1.272498081s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-13T08:32:28.539855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:27.446455Z","time spent":"1.093335262s","remote":"127.0.0.1:55864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:32:28.539737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267322911s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-08-13T08:32:28.539909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539579991s","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-08-13T08:32:28.539953Z","caller":"traceutil/trace.go:171","msg":"trace[1029246576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1850; }","duration":"1.539691013s","start":"2026-08-13T08:32:27.000251Z","end":"2026-08-13T08:32:28.539942Z","steps":["trace[1029246576] 'agreement among raft nodes before linearized reading' (duration: 1.5395344s)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:32:28.539955Z","caller":"traceutil/trace.go:171","msg":"trace[1235139446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1850; }","duration":"1.267566876s","start":"2026-08-13T08:32:27.272367Z","end":"2026-08-13T08:32:28.539933Z","steps":["trace[1235139446] 'agreement among raft nodes before linearized reading' (duration: 1.26721696s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:28.539995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:27.000223Z","time spent":"1.539764965s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T08:32:28.540038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:27.272359Z","time spent":"1.267653367s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T08:32:28.539894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:27.498539Z","time spent":"1.041301706s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:32:28.540183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.607498518s","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-08-13T08:32:28.541941Z","caller":"traceutil/trace.go:171","msg":"trace[51293235] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1850; }","duration":"1.607926846s","start":"2026-08-13T08:32:26.932523Z","end":"2026-08-13T08:32:28.54045Z","steps":["trace[51293235] 'agreement among raft nodes before linearized reading' (duration: 1.607143832s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:28.542049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:26.932502Z","time spent":"1.609526133s","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-13T08:32:28.546891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029183027s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T08:32:28.54694Z","caller":"traceutil/trace.go:171","msg":"trace[237786143] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"437.227568ms","start":"2026-08-13T08:32:28.1097Z","end":"2026-08-13T08:32:28.546927Z","steps":["trace[237786143] 'process raft request' (duration: 436.890063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:28.546928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028401382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-13T08:32:28.546981Z","caller":"traceutil/trace.go:171","msg":"trace[240910308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1852; }","duration":"1.028524964s","start":"2026-08-13T08:32:27.518446Z","end":"2026-08-13T08:32:28.546971Z","steps":["trace[240910308] 'agreement among raft nodes before linearized reading' (duration: 1.028366122s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:28.547009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:27.518422Z","time spent":"1.028582435s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-13T08:32:28.547008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:28.109675Z","time spent":"437.29428ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:32:28.547002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.817716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:32:28.546945Z","caller":"traceutil/trace.go:171","msg":"trace[1759411037] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1852; }","duration":"1.029276087s","start":"2026-08-13T08:32:27.517653Z","end":"2026-08-13T08:32:28.546929Z","steps":["trace[1759411037] 'agreement among raft nodes before linearized reading' (duration: 1.029155595s)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:32:28.54706Z","caller":"traceutil/trace.go:171","msg":"trace[1222951783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1852; }","duration":"844.897677ms","start":"2026-08-13T08:32:27.702144Z","end":"2026-08-13T08:32:28.547041Z","steps":["trace[1222951783] 'agreement among raft nodes before linearized reading' (duration: 844.802806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:28.547093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:27.702126Z","time spent":"844.960319ms","remote":"127.0.0.1:55692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T08:32:28.547097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:27.517634Z","time spent":"1.029441241s","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":3,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-13T08:32:28.546896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.764262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5ec8c4bc-7696-4f37-8dc8-d362c0623b9f\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-08-13T08:32:28.547152Z","caller":"traceutil/trace.go:171","msg":"trace[1999476670] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5ec8c4bc-7696-4f37-8dc8-d362c0623b9f; range_end:; response_count:1; response_revision:1852; }","duration":"103.045376ms","start":"2026-08-13T08:32:28.444097Z","end":"2026-08-13T08:32:28.547142Z","steps":["trace[1999476670] 'agreement among raft nodes before linearized reading' (duration: 102.70663ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:32:28.547677Z","caller":"traceutil/trace.go:171","msg":"trace[973972248] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"986.694585ms","start":"2026-08-13T08:32:27.559939Z","end":"2026-08-13T08:32:28.546634Z","steps":["trace[973972248] 'process raft request' (duration: 986.532383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:28.548746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:27.559918Z","time spent":"987.867855ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:32:33.799019Z","caller":"traceutil/trace.go:171","msg":"trace[2053859185] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"338.750861ms","start":"2026-08-13T08:32:33.460231Z","end":"2026-08-13T08:32:33.798981Z","steps":["trace[2053859185] 'process raft request' (duration: 338.574578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:33.799194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:33.460209Z","time spent":"338.897144ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:32:33.953749Z","caller":"traceutil/trace.go:171","msg":"trace[1155949753] linearizableReadLoop","detail":"{readStateIndex:1985; appliedIndex:1983; }","duration":"380.568341ms","start":"2026-08-13T08:32:33.573154Z","end":"2026-08-13T08:32:33.953723Z","steps":["trace[1155949753] 'read index received' (duration: 225.58249ms)","trace[1155949753] 'applied index is now lower than readState.Index' (duration: 154.984951ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:32:33.953929Z","caller":"traceutil/trace.go:171","msg":"trace[1022496930] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"404.951586ms","start":"2026-08-13T08:32:33.548941Z","end":"2026-08-13T08:32:33.953893Z","steps":["trace[1022496930] 'process raft request' (duration: 404.169553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:33.953966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.778684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:32:33.954007Z","caller":"traceutil/trace.go:171","msg":"trace[697849793] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1908; }","duration":"380.876077ms","start":"2026-08-13T08:32:33.57312Z","end":"2026-08-13T08:32:33.953996Z","steps":["trace[697849793] 'agreement among raft nodes before linearized reading' (duration: 380.748914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:33.954049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:33.573102Z","time spent":"380.934647ms","remote":"127.0.0.1:56356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-13T08:32:33.954091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.191065ms","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-08-13T08:32:33.954193Z","caller":"traceutil/trace.go:171","msg":"trace[310849486] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1908; }","duration":"370.318447ms","start":"2026-08-13T08:32:33.583848Z","end":"2026-08-13T08:32:33.954166Z","steps":["trace[310849486] 'agreement among raft nodes before linearized reading' (duration: 370.082733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:33.954246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:33.583828Z","time spent":"370.402828ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-13T08:32:33.954349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:33.548918Z","time spent":"405.087218ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:32:41.072965Z","caller":"traceutil/trace.go:171","msg":"trace[1576596931] linearizableReadLoop","detail":"{readStateIndex:2035; appliedIndex:2034; }","duration":"489.002277ms","start":"2026-08-13T08:32:40.58394Z","end":"2026-08-13T08:32:41.072942Z","steps":["trace[1576596931] 'read index received' (duration: 488.922145ms)","trace[1576596931] 'applied index is now lower than readState.Index' (duration: 79.332µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:32:41.073049Z","caller":"traceutil/trace.go:171","msg":"trace[1508678973] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"494.632769ms","start":"2026-08-13T08:32:40.578385Z","end":"2026-08-13T08:32:41.073018Z","steps":["trace[1508678973] 'process raft request' (duration: 494.417035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:41.073308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.350442ms","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-08-13T08:32:41.073343Z","caller":"traceutil/trace.go:171","msg":"trace[826322776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1956; }","duration":"489.480834ms","start":"2026-08-13T08:32:40.583854Z","end":"2026-08-13T08:32:41.073335Z","steps":["trace[826322776] 'agreement among raft nodes before linearized reading' (duration: 489.21662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:41.073367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:40.578357Z","time spent":"494.764382ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:32:41.073371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:40.583818Z","time spent":"489.546775ms","remote":"127.0.0.1:55944","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-08-13T08:32:41.140325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.609577ms","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-08-13T08:32:41.140434Z","caller":"traceutil/trace.go:171","msg":"trace[166559522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1957; }","duration":"519.78043ms","start":"2026-08-13T08:32:40.620628Z","end":"2026-08-13T08:32:41.140408Z","steps":["trace[166559522] 'agreement among raft nodes before linearized reading' (duration: 519.486996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:41.140475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.170054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-13T08:32:41.140518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:40.620604Z","time spent":"519.899973ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-13T08:32:41.140565Z","caller":"traceutil/trace.go:171","msg":"trace[949177299] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1957; }","duration":"512.309197ms","start":"2026-08-13T08:32:40.628236Z","end":"2026-08-13T08:32:41.140546Z","steps":["trace[949177299] 'agreement among raft nodes before linearized reading' (duration: 512.115853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:41.14062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:40.628216Z","time spent":"512.394939ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-13T08:32:41.140748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.350371ms","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-08-13T08:32:41.140805Z","caller":"traceutil/trace.go:171","msg":"trace[1941298515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1957; }","duration":"517.420011ms","start":"2026-08-13T08:32:40.623367Z","end":"2026-08-13T08:32:41.140787Z","steps":["trace[1941298515] 'agreement among raft nodes before linearized reading' (duration: 517.293209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:41.140852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:40.623355Z","time spent":"517.486703ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T08:32:41.140946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.005655ms","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":"warn","ts":"2026-08-13T08:32:41.140934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.589628ms","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-08-13T08:32:41.140986Z","caller":"traceutil/trace.go:171","msg":"trace[817761868] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1957; }","duration":"353.082726ms","start":"2026-08-13T08:32:40.787893Z","end":"2026-08-13T08:32:41.140976Z","steps":["trace[817761868] 'agreement among raft nodes before linearized reading' (duration: 353.021255ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:32:41.141003Z","caller":"traceutil/trace.go:171","msg":"trace[712950634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1957; }","duration":"519.703321ms","start":"2026-08-13T08:32:40.621286Z","end":"2026-08-13T08:32:41.140989Z","steps":["trace[712950634] 'agreement among raft nodes before linearized reading' (duration: 519.458996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:41.141023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:40.787833Z","time spent":"353.177689ms","remote":"127.0.0.1:34192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-08-13T08:32:41.141046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:40.621275Z","time spent":"519.761181ms","remote":"127.0.0.1:55944","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-08-13T08:32:41.14045Z","caller":"traceutil/trace.go:171","msg":"trace[2113403222] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"544.323316ms","start":"2026-08-13T08:32:40.596081Z","end":"2026-08-13T08:32:41.140404Z","steps":["trace[2113403222] 'process raft request' (duration: 543.793968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:41.141327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:40.596058Z","time spent":"545.149921ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:32:41.140262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.345443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:32:41.141564Z","caller":"traceutil/trace.go:171","msg":"trace[746862559] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1957; }","duration":"264.704926ms","start":"2026-08-13T08:32:40.87683Z","end":"2026-08-13T08:32:41.141535Z","steps":["trace[746862559] 'agreement among raft nodes before linearized reading' (duration: 263.300513ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:41.143482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.906306ms","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-08-13T08:32:41.143779Z","caller":"traceutil/trace.go:171","msg":"trace[763893364] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1957; }","duration":"142.206002ms","start":"2026-08-13T08:32:41.001474Z","end":"2026-08-13T08:32:41.14368Z","steps":["trace[763893364] 'agreement among raft nodes before linearized reading' (duration: 139.290483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:41.375012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.275713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:32:41.375114Z","caller":"traceutil/trace.go:171","msg":"trace[93522851] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"297.808162ms","start":"2026-08-13T08:32:41.077287Z","end":"2026-08-13T08:32:41.375095Z","steps":["trace[93522851] 'process raft request' (duration: 155.373147ms)","trace[93522851] 'compare' (duration: 142.06428ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:32:41.640742Z","caller":"traceutil/trace.go:171","msg":"trace[1292409735] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"493.19621ms","start":"2026-08-13T08:32:41.147527Z","end":"2026-08-13T08:32:41.640723Z","steps":["trace[1292409735] 'process raft request' (duration: 493.138809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:41.640916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:41.147497Z","time spent":"493.352662ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:32:41.641022Z","caller":"traceutil/trace.go:171","msg":"trace[1792357766] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"493.879321ms","start":"2026-08-13T08:32:41.147136Z","end":"2026-08-13T08:32:41.641015Z","steps":["trace[1792357766] 'process raft request' (duration: 493.405313ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:41.64106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:41.147108Z","time spent":"493.933322ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:32:41.64134Z","caller":"traceutil/trace.go:171","msg":"trace[108520343] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"493.675098ms","start":"2026-08-13T08:32:41.147649Z","end":"2026-08-13T08:32:41.641324Z","steps":["trace[108520343] 'process raft request' (duration: 493.043998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:41.641395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:41.147621Z","time spent":"493.746008ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:32:41.641615Z","caller":"traceutil/trace.go:171","msg":"trace[1970991359] linearizableReadLoop","detail":"{readStateIndex:2041; appliedIndex:2037; }","duration":"141.855885ms","start":"2026-08-13T08:32:41.499732Z","end":"2026-08-13T08:32:41.641588Z","steps":["trace[1970991359] 'read index received' (duration: 140.800518ms)","trace[1970991359] 'applied index is now lower than readState.Index' (duration: 1.053897ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:32:41.641638Z","caller":"traceutil/trace.go:171","msg":"trace[1564975294] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"493.460544ms","start":"2026-08-13T08:32:41.148144Z","end":"2026-08-13T08:32:41.641605Z","steps":["trace[1564975294] 'process raft request' (duration: 493.099968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:41.641925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:41.148136Z","time spent":"493.582166ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:32:41.641941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.137319ms","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-08-13T08:32:41.641995Z","caller":"traceutil/trace.go:171","msg":"trace[1185578016] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1962; }","duration":"142.286601ms","start":"2026-08-13T08:32:41.499693Z","end":"2026-08-13T08:32:41.64198Z","steps":["trace[1185578016] 'agreement among raft nodes before linearized reading' (duration: 141.997657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:44.022788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.207205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T08:32:44.022873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.964174ms","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-08-13T08:32:44.022873Z","caller":"traceutil/trace.go:171","msg":"trace[1462242092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1981; }","duration":"322.329487ms","start":"2026-08-13T08:32:43.700526Z","end":"2026-08-13T08:32:44.022855Z","steps":["trace[1462242092] 'range keys from in-memory index tree' (duration: 322.098063ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:32:44.022898Z","caller":"traceutil/trace.go:171","msg":"trace[343124128] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1981; }","duration":"375.993134ms","start":"2026-08-13T08:32:43.646897Z","end":"2026-08-13T08:32:44.022891Z","steps":["trace[343124128] 'range keys from in-memory index tree' (duration: 375.804071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:44.022915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:43.646894Z","time spent":"376.016525ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T08:32:44.022915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:43.700511Z","time spent":"322.396449ms","remote":"127.0.0.1:55706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T08:32:44.022914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.688529ms","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-08-13T08:32:44.022987Z","caller":"traceutil/trace.go:171","msg":"trace[1254338707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1981; }","duration":"375.779111ms","start":"2026-08-13T08:32:43.647188Z","end":"2026-08-13T08:32:44.022967Z","steps":["trace[1254338707] 'range keys from in-memory index tree' (duration: 375.522317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:44.023041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:43.647185Z","time spent":"375.845672ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T08:32:44.022807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.938634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-13T08:32:44.023163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.92251ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:32:44.023215Z","caller":"traceutil/trace.go:171","msg":"trace[1057375798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1981; }","duration":"376.37703ms","start":"2026-08-13T08:32:43.646827Z","end":"2026-08-13T08:32:44.023204Z","steps":["trace[1057375798] 'range keys from in-memory index tree' (duration: 375.778571ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:32:44.023241Z","caller":"traceutil/trace.go:171","msg":"trace[1532390785] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1981; }","duration":"199.003301ms","start":"2026-08-13T08:32:43.824228Z","end":"2026-08-13T08:32:44.023231Z","steps":["trace[1532390785] 'range keys from in-memory index tree' (duration: 198.91504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:44.023251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:43.646809Z","time spent":"376.430411ms","remote":"127.0.0.1:55944","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-08-13T08:32:44.023395Z","caller":"traceutil/trace.go:171","msg":"trace[95596967] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"166.259845ms","start":"2026-08-13T08:32:43.857127Z","end":"2026-08-13T08:32:44.023387Z","steps":["trace[95596967] 'process raft request' (duration: 166.214235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:44.023536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.167119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-13T08:32:44.023549Z","caller":"traceutil/trace.go:171","msg":"trace[1349414558] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"169.570721ms","start":"2026-08-13T08:32:43.85397Z","end":"2026-08-13T08:32:44.023541Z","steps":["trace[1349414558] 'process raft request' (duration: 111.829918ms)","trace[1349414558] 'compare' (duration: 56.931859ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:32:44.023567Z","caller":"traceutil/trace.go:171","msg":"trace[1967335391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1981; }","duration":"376.240949ms","start":"2026-08-13T08:32:43.647315Z","end":"2026-08-13T08:32:44.023556Z","steps":["trace[1967335391] 'range keys from in-memory index tree' (duration: 375.275483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:44.0236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:43.647291Z","time spent":"376.302791ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-13T08:32:45.202806Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228168877036249,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-13T08:32:45.551809Z","caller":"traceutil/trace.go:171","msg":"trace[560785709] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"939.204266ms","start":"2026-08-13T08:32:44.612582Z","end":"2026-08-13T08:32:45.551786Z","steps":["trace[560785709] 'process raft request' (duration: 939.063864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:45.551939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:44.612549Z","time spent":"939.325878ms","remote":"127.0.0.1:55850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8133,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:32:45.554912Z","caller":"traceutil/trace.go:171","msg":"trace[2119330656] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"468.429403ms","start":"2026-08-13T08:32:45.086465Z","end":"2026-08-13T08:32:45.554895Z","steps":["trace[2119330656] 'process raft request' (duration: 468.387462ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:32:45.554945Z","caller":"traceutil/trace.go:171","msg":"trace[428201090] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"552.400765ms","start":"2026-08-13T08:32:45.002516Z","end":"2026-08-13T08:32:45.554917Z","steps":["trace[428201090] 'process raft request' (duration: 552.235323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:45.555027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:45.086423Z","time spent":"468.539994ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:32:45.555096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:45.0025Z","time spent":"552.496067ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:32:45.555202Z","caller":"traceutil/trace.go:171","msg":"trace[1017136494] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"401.348184ms","start":"2026-08-13T08:32:45.153847Z","end":"2026-08-13T08:32:45.555195Z","steps":["trace[1017136494] 'process raft request' (duration: 401.026909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:45.555254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:45.153814Z","time spent":"401.414384ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:32:45.556899Z","caller":"traceutil/trace.go:171","msg":"trace[1125226128] linearizableReadLoop","detail":"{readStateIndex:2071; appliedIndex:2067; }","duration":"854.271893ms","start":"2026-08-13T08:32:44.702616Z","end":"2026-08-13T08:32:45.556887Z","steps":["trace[1125226128] 'read index received' (duration: 849.028838ms)","trace[1125226128] 'applied index is now lower than readState.Index' (duration: 5.242275ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:32:45.557091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.937123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11989"} {"level":"info","ts":"2026-08-13T08:32:45.55713Z","caller":"traceutil/trace.go:171","msg":"trace[1278171711] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1992; }","duration":"410.025514ms","start":"2026-08-13T08:32:45.147096Z","end":"2026-08-13T08:32:45.557121Z","steps":["trace[1278171711] 'agreement among raft nodes before linearized reading' (duration: 409.888442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:45.557116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.585227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-13T08:32:45.557144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.575219ms","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-08-13T08:32:45.557161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:45.147074Z","time spent":"410.079365ms","remote":"127.0.0.1:55864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12011,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-13T08:32:45.557171Z","caller":"traceutil/trace.go:171","msg":"trace[1421721533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1992; }","duration":"165.680319ms","start":"2026-08-13T08:32:45.391478Z","end":"2026-08-13T08:32:45.557158Z","steps":["trace[1421721533] 'agreement among raft nodes before linearized reading' (duration: 165.530617ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:32:45.557181Z","caller":"traceutil/trace.go:171","msg":"trace[512014907] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1992; }","duration":"345.615479ms","start":"2026-08-13T08:32:45.211555Z","end":"2026-08-13T08:32:45.55717Z","steps":["trace[512014907] 'agreement among raft nodes before linearized reading' (duration: 345.488267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:45.557211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:45.211534Z","time spent":"345.66908ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-13T08:32:45.557389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.383694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:32:45.557422Z","caller":"traceutil/trace.go:171","msg":"trace[235055473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1992; }","duration":"854.834861ms","start":"2026-08-13T08:32:44.702579Z","end":"2026-08-13T08:32:45.557414Z","steps":["trace[235055473] 'agreement among raft nodes before linearized reading' (duration: 854.377314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:32:45.557442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:32:44.70256Z","time spent":"854.876872ms","remote":"127.0.0.1:55692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T08:33:09.692705Z","caller":"traceutil/trace.go:171","msg":"trace[1475317848] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"103.499083ms","start":"2026-08-13T08:33:09.589187Z","end":"2026-08-13T08:33:09.692686Z","steps":["trace[1475317848] 'process raft request' (duration: 103.369651ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:33:09.964973Z","caller":"traceutil/trace.go:171","msg":"trace[1230455836] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"324.085134ms","start":"2026-08-13T08:33:09.640858Z","end":"2026-08-13T08:33:09.964944Z","steps":["trace[1230455836] 'process raft request' (duration: 269.525009ms)","trace[1230455836] 'compare' (duration: 54.24377ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:33:09.965115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:09.640834Z","time spent":"324.208336ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:33:09.965165Z","caller":"traceutil/trace.go:171","msg":"trace[2017779577] linearizableReadLoop","detail":"{readStateIndex:2208; appliedIndex:2205; }","duration":"308.609531ms","start":"2026-08-13T08:33:09.656523Z","end":"2026-08-13T08:33:09.965132Z","steps":["trace[2017779577] 'read index received' (duration: 36.101726ms)","trace[2017779577] 'applied index is now lower than readState.Index' (duration: 272.506054ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:33:09.965315Z","caller":"traceutil/trace.go:171","msg":"trace[144629231] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"323.631668ms","start":"2026-08-13T08:33:09.641649Z","end":"2026-08-13T08:33:09.96528Z","steps":["trace[144629231] 'process raft request' (duration: 323.211012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:09.9654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.774263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:33:09.965438Z","caller":"traceutil/trace.go:171","msg":"trace[1907558954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2124; }","duration":"265.837584ms","start":"2026-08-13T08:33:09.69959Z","end":"2026-08-13T08:33:09.965427Z","steps":["trace[1907558954] 'agreement among raft nodes before linearized reading' (duration: 265.778263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:09.965461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.924235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-13T08:33:09.965493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:09.64164Z","time spent":"323.761179ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:33:09.965526Z","caller":"traceutil/trace.go:171","msg":"trace[524042801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2124; }","duration":"309.039006ms","start":"2026-08-13T08:33:09.656469Z","end":"2026-08-13T08:33:09.965508Z","steps":["trace[524042801] 'agreement among raft nodes before linearized reading' (duration: 308.828003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:09.965579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:09.656446Z","time spent":"309.119328ms","remote":"127.0.0.1:55944","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-08-13T08:33:09.966144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.323963ms","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-08-13T08:33:09.966219Z","caller":"traceutil/trace.go:171","msg":"trace[1579677972] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2124; }","duration":"239.420354ms","start":"2026-08-13T08:33:09.726776Z","end":"2026-08-13T08:33:09.966196Z","steps":["trace[1579677972] 'agreement among raft nodes before linearized reading' (duration: 239.230111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:10.697538Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228168877036577,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-13T08:33:11.076913Z","caller":"traceutil/trace.go:171","msg":"trace[1444613531] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"955.447768ms","start":"2026-08-13T08:33:10.121436Z","end":"2026-08-13T08:33:11.076883Z","steps":["trace[1444613531] 'process raft request' (duration: 955.197935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:11.07721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:10.121411Z","time spent":"955.720702ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:33:11.756933Z","caller":"traceutil/trace.go:171","msg":"trace[256183693] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"1.630857476s","start":"2026-08-13T08:33:10.126047Z","end":"2026-08-13T08:33:11.756904Z","steps":["trace[256183693] 'process raft request' (duration: 1.533091207s)","trace[256183693] 'compare' (duration: 97.23825ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:33:11.757116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:10.12603Z","time spent":"1.630997318s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:33:11.757471Z","caller":"traceutil/trace.go:171","msg":"trace[1390552821] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2209; }","duration":"1.560866061s","start":"2026-08-13T08:33:10.196563Z","end":"2026-08-13T08:33:11.757429Z","steps":["trace[1390552821] 'read index received' (duration: 880.113933ms)","trace[1390552821] 'applied index is now lower than readState.Index' (duration: 680.750368ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:33:11.757811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.561255406s","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-08-13T08:33:11.757869Z","caller":"traceutil/trace.go:171","msg":"trace[1318550670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2128; }","duration":"1.561356518s","start":"2026-08-13T08:33:10.196498Z","end":"2026-08-13T08:33:11.757855Z","steps":["trace[1318550670] 'agreement among raft nodes before linearized reading' (duration: 1.561182095s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:11.757978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077175246s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"warn","ts":"2026-08-13T08:33:11.758067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:10.196474Z","time spent":"1.561544911s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-13T08:33:11.758114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.526501723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-08-13T08:33:11.758168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.478442ms","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-08-13T08:33:11.758236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057686342s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T08:33:11.758273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.462430638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-13T08:33:11.75831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.528653935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-08-13T08:33:11.75784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.532476453s","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-08-13T08:33:11.75958Z","caller":"traceutil/trace.go:171","msg":"trace[1605807820] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2128; }","duration":"1.077264057s","start":"2026-08-13T08:33:10.680757Z","end":"2026-08-13T08:33:11.758021Z","steps":["trace[1605807820] 'agreement among raft nodes before linearized reading' (duration: 1.077124845s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:11.759637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:10.680738Z","time spent":"1.078879351s","remote":"127.0.0.1:55864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12408,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-13T08:33:11.759891Z","caller":"traceutil/trace.go:171","msg":"trace[1709169812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2128; }","duration":"1.526609775s","start":"2026-08-13T08:33:10.23159Z","end":"2026-08-13T08:33:11.7582Z","steps":["trace[1709169812] 'agreement among raft nodes before linearized reading' (duration: 1.526375852s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:11.759946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:10.231539Z","time spent":"1.528390941s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-13T08:33:11.760153Z","caller":"traceutil/trace.go:171","msg":"trace[725392173] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2128; }","duration":"783.609544ms","start":"2026-08-13T08:33:10.974599Z","end":"2026-08-13T08:33:11.758208Z","steps":["trace[725392173] 'agreement among raft nodes before linearized reading' (duration: 783.438522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:11.760223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:10.974578Z","time spent":"785.629104ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-13T08:33:11.76048Z","caller":"traceutil/trace.go:171","msg":"trace[1524870234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2128; }","duration":"1.057743293s","start":"2026-08-13T08:33:10.70052Z","end":"2026-08-13T08:33:11.758263Z","steps":["trace[1524870234] 'agreement among raft nodes before linearized reading' (duration: 1.057675952s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:11.760546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:10.700509Z","time spent":"1.060021697s","remote":"127.0.0.1:55692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T08:33:11.760745Z","caller":"traceutil/trace.go:171","msg":"trace[1560301096] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:2128; }","duration":"1.46254547s","start":"2026-08-13T08:33:10.295783Z","end":"2026-08-13T08:33:11.758328Z","steps":["trace[1560301096] 'agreement among raft nodes before linearized reading' (duration: 1.462277606s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:11.760816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:10.295766Z","time spent":"1.465030277s","remote":"127.0.0.1:55728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.223\" "} {"level":"info","ts":"2026-08-13T08:33:11.761065Z","caller":"traceutil/trace.go:171","msg":"trace[338048327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2128; }","duration":"1.528735147s","start":"2026-08-13T08:33:10.229606Z","end":"2026-08-13T08:33:11.758341Z","steps":["trace[338048327] 'agreement among raft nodes before linearized reading' (duration: 1.528618635s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:11.761132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:10.229593Z","time spent":"1.531522969s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-13T08:33:11.761401Z","caller":"traceutil/trace.go:171","msg":"trace[527998853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2128; }","duration":"1.533049431s","start":"2026-08-13T08:33:10.225298Z","end":"2026-08-13T08:33:11.758347Z","steps":["trace[527998853] 'agreement among raft nodes before linearized reading' (duration: 1.532436822s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:11.761469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:10.225277Z","time spent":"1.536175218s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-13T08:33:11.759513Z","caller":"traceutil/trace.go:171","msg":"trace[531166238] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"1.081601212s","start":"2026-08-13T08:33:10.676271Z","end":"2026-08-13T08:33:11.757872Z","steps":["trace[531166238] 'process raft request' (duration: 1.080562357s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:11.763121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:10.676241Z","time spent":"1.08678576s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:33:12.29928Z","caller":"traceutil/trace.go:171","msg":"trace[105937815] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"329.153034ms","start":"2026-08-13T08:33:11.970108Z","end":"2026-08-13T08:33:12.299261Z","steps":["trace[105937815] 'process raft request' (duration: 328.977361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:12.299427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:11.970084Z","time spent":"329.251005ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:33:12.300239Z","caller":"traceutil/trace.go:171","msg":"trace[1292673453] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2218; }","duration":"309.781923ms","start":"2026-08-13T08:33:11.990434Z","end":"2026-08-13T08:33:12.300216Z","steps":["trace[1292673453] 'read index received' (duration: 308.720377ms)","trace[1292673453] 'applied index is now lower than readState.Index' (duration: 1.060706ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:33:12.300425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.962546ms","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-08-13T08:33:12.300448Z","caller":"traceutil/trace.go:171","msg":"trace[1725262082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2135; }","duration":"310.060028ms","start":"2026-08-13T08:33:11.990382Z","end":"2026-08-13T08:33:12.300443Z","steps":["trace[1725262082] 'agreement among raft nodes before linearized reading' (duration: 309.909376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:12.300468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:11.990359Z","time spent":"310.102738ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-13T08:33:12.30056Z","caller":"traceutil/trace.go:171","msg":"trace[570025067] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"329.693952ms","start":"2026-08-13T08:33:11.970846Z","end":"2026-08-13T08:33:12.30054Z","steps":["trace[570025067] 'process raft request' (duration: 329.296176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:33:12.300628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:33:11.970828Z","time spent":"329.748043ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:33:58.972277Z","caller":"traceutil/trace.go:171","msg":"trace[1100485741] transaction","detail":"{read_only:false; response_revision:2436; number_of_response:1; }","duration":"184.518172ms","start":"2026-08-13T08:33:58.787722Z","end":"2026-08-13T08:33:58.97224Z","steps":["trace[1100485741] 'process raft request' (duration: 184.224058ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:34:07.416668Z","caller":"traceutil/trace.go:171","msg":"trace[1930250673] transaction","detail":"{read_only:false; response_revision:2536; number_of_response:1; }","duration":"160.180393ms","start":"2026-08-13T08:34:07.256461Z","end":"2026-08-13T08:34:07.416641Z","steps":["trace[1930250673] 'process raft request' (duration: 160.027551ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:34:40.471258Z","caller":"traceutil/trace.go:171","msg":"trace[64337802] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"211.083462ms","start":"2026-08-13T08:34:40.260146Z","end":"2026-08-13T08:34:40.47123Z","steps":["trace[64337802] 'process raft request' (duration: 184.385395ms)","trace[64337802] 'compare' (duration: 26.577311ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:34:40.475245Z","caller":"traceutil/trace.go:171","msg":"trace[158355309] transaction","detail":"{read_only:false; response_revision:2716; number_of_response:1; }","duration":"214.732802ms","start":"2026-08-13T08:34:40.260476Z","end":"2026-08-13T08:34:40.475209Z","steps":["trace[158355309] 'process raft request' (duration: 214.422619ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:34:40.475487Z","caller":"traceutil/trace.go:171","msg":"trace[1291115557] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"214.731032ms","start":"2026-08-13T08:34:40.260744Z","end":"2026-08-13T08:34:40.475475Z","steps":["trace[1291115557] 'process raft request' (duration: 214.283352ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:34:40.475622Z","caller":"traceutil/trace.go:171","msg":"trace[542535715] linearizableReadLoop","detail":"{readStateIndex:2822; appliedIndex:2819; }","duration":"173.799139ms","start":"2026-08-13T08:34:40.301542Z","end":"2026-08-13T08:34:40.475341Z","steps":["trace[542535715] 'read index received' (duration: 143.164729ms)","trace[542535715] 'applied index is now lower than readState.Index' (duration: 30.63346ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:34:40.475674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.113693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-13T08:34:40.475736Z","caller":"traceutil/trace.go:171","msg":"trace[395818204] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:2717; }","duration":"174.220309ms","start":"2026-08-13T08:34:40.3015Z","end":"2026-08-13T08:34:40.475721Z","steps":["trace[395818204] 'agreement among raft nodes before linearized reading' (duration: 174.04447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:34:40.840967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.897381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:34:40.841134Z","caller":"traceutil/trace.go:171","msg":"trace[567874248] linearizableReadLoop","detail":"{readStateIndex:2827; appliedIndex:2825; }","duration":"174.673127ms","start":"2026-08-13T08:34:40.666445Z","end":"2026-08-13T08:34:40.841118Z","steps":["trace[567874248] 'read index received' (duration: 30.486593ms)","trace[567874248] 'applied index is now lower than readState.Index' (duration: 144.185474ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:34:40.841288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.841134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-13T08:34:40.841337Z","caller":"traceutil/trace.go:171","msg":"trace[1076566712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2721; }","duration":"174.927458ms","start":"2026-08-13T08:34:40.666399Z","end":"2026-08-13T08:34:40.841327Z","steps":["trace[1076566712] 'agreement among raft nodes before linearized reading' (duration: 174.772052ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:34:40.841282Z","caller":"traceutil/trace.go:171","msg":"trace[1903856231] transaction","detail":"{read_only:false; response_revision:2720; number_of_response:1; }","duration":"258.897099ms","start":"2026-08-13T08:34:40.582249Z","end":"2026-08-13T08:34:40.841147Z","steps":["trace[1903856231] 'process raft request' (duration: 114.734386ms)","trace[1903856231] 'compare' (duration: 143.777126ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:34:40.841626Z","caller":"traceutil/trace.go:171","msg":"trace[884319589] transaction","detail":"{read_only:false; response_revision:2721; number_of_response:1; }","duration":"253.616696ms","start":"2026-08-13T08:34:40.58795Z","end":"2026-08-13T08:34:40.841567Z","steps":["trace[884319589] 'process raft request' (duration: 253.117454ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:34:41.246097Z","caller":"traceutil/trace.go:171","msg":"trace[171247818] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"399.633078ms","start":"2026-08-13T08:34:40.846439Z","end":"2026-08-13T08:34:41.246072Z","steps":["trace[171247818] 'process raft request' (duration: 332.983745ms)","trace[171247818] 'compare' (duration: 66.523676ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:34:41.24623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:34:40.846419Z","time spent":"399.745053ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:35:25.285012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.629625ms","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-08-13T08:35:25.285123Z","caller":"traceutil/trace.go:171","msg":"trace[1209484379] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2953; }","duration":"189.811112ms","start":"2026-08-13T08:35:25.095282Z","end":"2026-08-13T08:35:25.285093Z","steps":["trace[1209484379] 'count revisions from in-memory index tree' (duration: 189.542003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:35:25.285272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.08148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-13T08:35:25.285378Z","caller":"traceutil/trace.go:171","msg":"trace[1254725407] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2953; }","duration":"112.266897ms","start":"2026-08-13T08:35:25.173093Z","end":"2026-08-13T08:35:25.28536Z","steps":["trace[1254725407] 'range keys from in-memory index tree' (duration: 111.803871ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:35:30.466572Z","caller":"traceutil/trace.go:171","msg":"trace[876904298] transaction","detail":"{read_only:false; response_revision:2980; number_of_response:1; }","duration":"125.860439ms","start":"2026-08-13T08:35:30.340691Z","end":"2026-08-13T08:35:30.466552Z","steps":["trace[876904298] 'process raft request' (duration: 125.726235ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:35:33.200948Z","caller":"traceutil/trace.go:171","msg":"trace[2071207063] transaction","detail":"{read_only:false; response_revision:2996; number_of_response:1; }","duration":"119.40435ms","start":"2026-08-13T08:35:33.081497Z","end":"2026-08-13T08:35:33.200902Z","steps":["trace[2071207063] 'process raft request' (duration: 85.111144ms)","trace[2071207063] 'compare' (duration: 33.998096ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:35:52.823518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.782255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:35:52.823629Z","caller":"traceutil/trace.go:171","msg":"trace[2120590541] linearizableReadLoop","detail":"{readStateIndex:3217; appliedIndex:3216; }","duration":"120.399589ms","start":"2026-08-13T08:35:52.703216Z","end":"2026-08-13T08:35:52.823615Z","steps":["trace[2120590541] 'read index received' (duration: 59.602µs)","trace[2120590541] 'applied index is now lower than readState.Index' (duration: 120.338987ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:35:52.823685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.482921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:35:52.823709Z","caller":"traceutil/trace.go:171","msg":"trace[2080864140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3096; }","duration":"120.567663ms","start":"2026-08-13T08:35:52.703135Z","end":"2026-08-13T08:35:52.823702Z","steps":["trace[2080864140] 'agreement among raft nodes before linearized reading' (duration: 120.518832ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:35:52.823794Z","caller":"traceutil/trace.go:171","msg":"trace[2090162353] transaction","detail":"{read_only:false; response_revision:3096; number_of_response:1; }","duration":"187.032504ms","start":"2026-08-13T08:35:52.636697Z","end":"2026-08-13T08:35:52.82373Z","steps":["trace[2090162353] 'process raft request' (duration: 53.941499ms)","trace[2090162353] 'compare' (duration: 132.651751ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:35:54.781733Z","caller":"traceutil/trace.go:171","msg":"trace[1732951190] transaction","detail":"{read_only:false; response_revision:3105; number_of_response:1; }","duration":"187.145341ms","start":"2026-08-13T08:35:54.594535Z","end":"2026-08-13T08:35:54.781681Z","steps":["trace[1732951190] 'process raft request' (duration: 186.80469ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:37:35.721439Z","caller":"traceutil/trace.go:171","msg":"trace[2083205818] transaction","detail":"{read_only:false; response_revision:3765; number_of_response:1; }","duration":"126.566035ms","start":"2026-08-13T08:37:35.59484Z","end":"2026-08-13T08:37:35.721406Z","steps":["trace[2083205818] 'process raft request' (duration: 126.374951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:37:58.201946Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228168877041126,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-13T08:37:58.527944Z","caller":"traceutil/trace.go:171","msg":"trace[1416094078] transaction","detail":"{read_only:false; response_revision:3924; number_of_response:1; }","duration":"721.74781ms","start":"2026-08-13T08:37:57.806168Z","end":"2026-08-13T08:37:58.527916Z","steps":["trace[1416094078] 'process raft request' (duration: 721.580166ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:37:58.528139Z","caller":"traceutil/trace.go:171","msg":"trace[1035938441] linearizableReadLoop","detail":"{readStateIndex:4078; appliedIndex:4076; }","duration":"826.275695ms","start":"2026-08-13T08:37:57.701845Z","end":"2026-08-13T08:37:58.528121Z","steps":["trace[1035938441] 'read index received' (duration: 823.807291ms)","trace[1035938441] 'applied index is now lower than readState.Index' (duration: 2.467304ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:37:58.5283Z","caller":"traceutil/trace.go:171","msg":"trace[1807306269] transaction","detail":"{read_only:false; response_revision:3925; number_of_response:1; }","duration":"203.862975ms","start":"2026-08-13T08:37:58.32443Z","end":"2026-08-13T08:37:58.528293Z","steps":["trace[1807306269] 'process raft request' (duration: 203.429556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:37:58.528536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.653836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-13T08:37:58.528659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.271963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:37:58.528699Z","caller":"traceutil/trace.go:171","msg":"trace[1736788937] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:3925; }","duration":"441.383416ms","start":"2026-08-13T08:37:58.087306Z","end":"2026-08-13T08:37:58.52869Z","steps":["trace[1736788937] 'agreement among raft nodes before linearized reading' (duration: 441.260832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:37:58.528732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:37:58.087289Z","time spent":"441.436577ms","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-08-13T08:37:58.528846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.012233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:37:58.528881Z","caller":"traceutil/trace.go:171","msg":"trace[602905608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3925; }","duration":"827.093075ms","start":"2026-08-13T08:37:57.701783Z","end":"2026-08-13T08:37:58.528876Z","steps":["trace[602905608] 'agreement among raft nodes before linearized reading' (duration: 827.048204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:37:58.528908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:37:57.701749Z","time spent":"827.154335ms","remote":"127.0.0.1:55692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T08:37:58.528868Z","caller":"traceutil/trace.go:171","msg":"trace[1267824167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3925; }","duration":"674.760559ms","start":"2026-08-13T08:37:57.853834Z","end":"2026-08-13T08:37:58.528594Z","steps":["trace[1267824167] 'agreement among raft nodes before linearized reading' (duration: 674.496043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:37:58.529072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.307696ms","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-08-13T08:37:58.529108Z","caller":"traceutil/trace.go:171","msg":"trace[1553023901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3925; }","duration":"323.372228ms","start":"2026-08-13T08:37:58.205731Z","end":"2026-08-13T08:37:58.529103Z","steps":["trace[1553023901] 'agreement among raft nodes before linearized reading' (duration: 323.281026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:37:58.529135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:37:58.205707Z","time spent":"323.423798ms","remote":"127.0.0.1:55944","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-08-13T08:37:58.529225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:37:57.85382Z","time spent":"675.169128ms","remote":"127.0.0.1:55944","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-08-13T08:37:58.529382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.094711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T08:37:58.529485Z","caller":"traceutil/trace.go:171","msg":"trace[705502319] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:3925; }","duration":"588.227704ms","start":"2026-08-13T08:37:57.941249Z","end":"2026-08-13T08:37:58.529477Z","steps":["trace[705502319] 'agreement among raft nodes before linearized reading' (duration: 588.113801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:37:58.529524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:37:57.94123Z","time spent":"588.286685ms","remote":"127.0.0.1:56026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-13T08:37:58.529613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.319439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T08:37:58.529654Z","caller":"traceutil/trace.go:171","msg":"trace[1251620398] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:3925; }","duration":"664.38952ms","start":"2026-08-13T08:37:57.865255Z","end":"2026-08-13T08:37:58.529645Z","steps":["trace[1251620398] 'agreement among raft nodes before linearized reading' (duration: 664.169615ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:37:58.529813Z","caller":"traceutil/trace.go:171","msg":"trace[1115270939] transaction","detail":"{read_only:false; response_revision:3923; number_of_response:1; }","duration":"922.602729ms","start":"2026-08-13T08:37:57.607203Z","end":"2026-08-13T08:37:58.529806Z","steps":["trace[1115270939] 'process raft request' (duration: 918.419526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:37:58.529955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:37:57.607179Z","time spent":"922.728741ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:37:58.529823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:37:57.865242Z","time spent":"664.512673ms","remote":"127.0.0.1:56014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":92,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-08-13T08:37:58.530629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:37:57.806146Z","time spent":"721.887892ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:38:09.726382Z","caller":"traceutil/trace.go:171","msg":"trace[990179828] linearizableReadLoop","detail":"{readStateIndex:4147; appliedIndex:4146; }","duration":"115.995558ms","start":"2026-08-13T08:38:09.610363Z","end":"2026-08-13T08:38:09.726359Z","steps":["trace[990179828] 'read index received' (duration: 115.859015ms)","trace[990179828] 'applied index is now lower than readState.Index' (duration: 135.693µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:38:09.726579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.060119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:38:09.726623Z","caller":"traceutil/trace.go:171","msg":"trace[1882187606] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:3981; }","duration":"116.13665ms","start":"2026-08-13T08:38:09.610474Z","end":"2026-08-13T08:38:09.72661Z","steps":["trace[1882187606] 'agreement among raft nodes before linearized reading' (duration: 116.050948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:09.726644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.267434ms","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-08-13T08:38:09.726751Z","caller":"traceutil/trace.go:171","msg":"trace[1026723243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3981; }","duration":"116.401396ms","start":"2026-08-13T08:38:09.610331Z","end":"2026-08-13T08:38:09.726732Z","steps":["trace[1026723243] 'agreement among raft nodes before linearized reading' (duration: 116.177751ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:09.727335Z","caller":"traceutil/trace.go:171","msg":"trace[1101764694] transaction","detail":"{read_only:false; response_revision:3981; number_of_response:1; }","duration":"277.539289ms","start":"2026-08-13T08:38:09.449745Z","end":"2026-08-13T08:38:09.727285Z","steps":["trace[1101764694] 'process raft request' (duration: 276.458976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:13.080795Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228168877041325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:38:13.581783Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228168877041325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:38:14.082025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228168877041325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:38:14.11225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.077193ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:38:14.112429Z","caller":"traceutil/trace.go:171","msg":"trace[1862702550] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3994; }","duration":"288.264367ms","start":"2026-08-13T08:38:13.824134Z","end":"2026-08-13T08:38:14.112398Z","steps":["trace[1862702550] 'range keys from in-memory index tree' (duration: 288.055053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:14.112808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.744636527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-13T08:38:14.112836Z","caller":"traceutil/trace.go:171","msg":"trace[440512746] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3994; }","duration":"1.744701669s","start":"2026-08-13T08:38:12.368127Z","end":"2026-08-13T08:38:14.112828Z","steps":["trace[440512746] 'range keys from in-memory index tree' (duration: 1.744432523s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:14.112861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:12.368105Z","time spent":"1.744749271s","remote":"127.0.0.1:55864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-13T08:38:14.114578Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.543150708s","expected-duration":"1s"} {"level":"info","ts":"2026-08-13T08:38:14.114833Z","caller":"traceutil/trace.go:171","msg":"trace[689159724] transaction","detail":"{read_only:false; response_revision:3995; number_of_response:1; }","duration":"1.543534276s","start":"2026-08-13T08:38:12.571288Z","end":"2026-08-13T08:38:14.114822Z","steps":["trace[689159724] 'process raft request' (duration: 1.543443314s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:14.114909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:12.571249Z","time spent":"1.543611008s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:38:14.165676Z","caller":"traceutil/trace.go:171","msg":"trace[315211285] transaction","detail":"{read_only:false; response_revision:3996; number_of_response:1; }","duration":"1.590288456s","start":"2026-08-13T08:38:12.575364Z","end":"2026-08-13T08:38:14.165653Z","steps":["trace[315211285] 'process raft request' (duration: 1.590106032s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:14.165834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:12.575354Z","time spent":"1.590399108s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:38:14.165883Z","caller":"traceutil/trace.go:171","msg":"trace[314281501] transaction","detail":"{read_only:false; response_revision:3997; number_of_response:1; }","duration":"1.589419579s","start":"2026-08-13T08:38:12.576448Z","end":"2026-08-13T08:38:14.165868Z","steps":["trace[314281501] 'process raft request' (duration: 1.589158732s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:14.165986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:12.576427Z","time spent":"1.58950195s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:38:14.166288Z","caller":"traceutil/trace.go:171","msg":"trace[2096209157] transaction","detail":"{read_only:false; response_revision:3999; number_of_response:1; }","duration":"453.091371ms","start":"2026-08-13T08:38:13.713183Z","end":"2026-08-13T08:38:14.166275Z","steps":["trace[2096209157] 'process raft request' (duration: 452.953568ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:14.166344Z","caller":"traceutil/trace.go:171","msg":"trace[1012473461] transaction","detail":"{read_only:false; response_revision:4000; number_of_response:1; }","duration":"247.517722ms","start":"2026-08-13T08:38:13.918812Z","end":"2026-08-13T08:38:14.166329Z","steps":["trace[1012473461] 'process raft request' (duration: 247.40769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:14.166371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:13.713156Z","time spent":"453.179493ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:38:14.166295Z","caller":"traceutil/trace.go:171","msg":"trace[370543349] linearizableReadLoop","detail":"{readStateIndex:4167; appliedIndex:4161; }","duration":"1.585865841s","start":"2026-08-13T08:38:12.580415Z","end":"2026-08-13T08:38:14.166281Z","steps":["trace[370543349] 'read index received' (duration: 1.534343608s)","trace[370543349] 'applied index is now lower than readState.Index' (duration: 51.521003ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:38:14.166263Z","caller":"traceutil/trace.go:171","msg":"trace[1122087263] transaction","detail":"{read_only:false; response_revision:3998; number_of_response:1; }","duration":"908.263293ms","start":"2026-08-13T08:38:13.257963Z","end":"2026-08-13T08:38:14.166226Z","steps":["trace[1122087263] 'process raft request' (duration: 907.848173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:14.166528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:13.257938Z","time spent":"908.531809ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:38:14.166596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.586163078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-13T08:38:14.166662Z","caller":"traceutil/trace.go:171","msg":"trace[1462908958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4000; }","duration":"1.58625173s","start":"2026-08-13T08:38:12.580381Z","end":"2026-08-13T08:38:14.166633Z","steps":["trace[1462908958] 'agreement among raft nodes before linearized reading' (duration: 1.586038095s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:14.166675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.581770024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-13T08:38:14.166694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:12.580368Z","time spent":"1.586319401s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-13T08:38:14.166712Z","caller":"traceutil/trace.go:171","msg":"trace[883322965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4000; }","duration":"1.581831945s","start":"2026-08-13T08:38:12.58487Z","end":"2026-08-13T08:38:14.166702Z","steps":["trace[883322965] 'agreement among raft nodes before linearized reading' (duration: 1.581693782s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:14.16672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.539206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-13T08:38:14.166747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:12.584857Z","time spent":"1.581883806s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-13T08:38:14.166755Z","caller":"traceutil/trace.go:171","msg":"trace[832042570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4000; }","duration":"423.608288ms","start":"2026-08-13T08:38:13.743136Z","end":"2026-08-13T08:38:14.166745Z","steps":["trace[832042570] 'agreement among raft nodes before linearized reading' (duration: 423.491776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:14.166779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:13.743125Z","time spent":"423.649609ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T08:38:14.166845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027419818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T08:38:14.166873Z","caller":"traceutil/trace.go:171","msg":"trace[1810841962] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:4000; }","duration":"1.02748648s","start":"2026-08-13T08:38:13.139378Z","end":"2026-08-13T08:38:14.166865Z","steps":["trace[1810841962] 'agreement among raft nodes before linearized reading' (duration: 1.027436339s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:14.166885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350879292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T08:38:14.166897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:13.139356Z","time spent":"1.02753362s","remote":"127.0.0.1:54278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"info","ts":"2026-08-13T08:38:14.166906Z","caller":"traceutil/trace.go:171","msg":"trace[1922287631] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:4000; }","duration":"1.350932153s","start":"2026-08-13T08:38:12.815967Z","end":"2026-08-13T08:38:14.166899Z","steps":["trace[1922287631] 'agreement among raft nodes before linearized reading' (duration: 1.350896713s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:14.166929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:12.815954Z","time spent":"1.350969364s","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-13T08:38:14.166852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.366501058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-grafana\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T08:38:14.166857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.91962ms","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-08-13T08:38:14.167017Z","caller":"traceutil/trace.go:171","msg":"trace[549256517] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-grafana; range_end:; response_count:0; response_revision:4000; }","duration":"1.366680773s","start":"2026-08-13T08:38:12.800321Z","end":"2026-08-13T08:38:14.167002Z","steps":["trace[549256517] 'agreement among raft nodes before linearized reading' (duration: 1.366510469s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:14.167053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.465164098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:38:14.167073Z","caller":"traceutil/trace.go:171","msg":"trace[841620162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4000; }","duration":"1.465225349s","start":"2026-08-13T08:38:12.701841Z","end":"2026-08-13T08:38:14.167066Z","steps":["trace[841620162] 'agreement among raft nodes before linearized reading' (duration: 1.465200078s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:14.167072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:12.800305Z","time spent":"1.366755634s","remote":"127.0.0.1:55884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-grafana\" "} {"level":"warn","ts":"2026-08-13T08:38:14.16709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:12.701816Z","time spent":"1.46527079s","remote":"127.0.0.1:55692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T08:38:14.167018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.95415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-13T08:38:14.167129Z","caller":"traceutil/trace.go:171","msg":"trace[1285036566] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4000; }","duration":"427.098254ms","start":"2026-08-13T08:38:13.74002Z","end":"2026-08-13T08:38:14.167119Z","steps":["trace[1285036566] 'agreement among raft nodes before linearized reading' (duration: 426.950481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:14.167167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:13.740004Z","time spent":"427.154555ms","remote":"127.0.0.1:55944","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-08-13T08:38:14.16714Z","caller":"traceutil/trace.go:171","msg":"trace[1748106632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4000; }","duration":"427.169075ms","start":"2026-08-13T08:38:13.73988Z","end":"2026-08-13T08:38:14.167049Z","steps":["trace[1748106632] 'agreement among raft nodes before linearized reading' (duration: 426.734126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:14.167256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:13.739854Z","time spent":"427.39115ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-13T08:38:24.377037Z","caller":"traceutil/trace.go:171","msg":"trace[1211804105] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4400; }","duration":"248.843909ms","start":"2026-08-13T08:38:24.128168Z","end":"2026-08-13T08:38:24.377012Z","steps":["trace[1211804105] 'process raft request' (duration: 239.686836ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:24.390087Z","caller":"traceutil/trace.go:171","msg":"trace[621073925] linearizableReadLoop","detail":"{readStateIndex:4581; appliedIndex:4575; }","duration":"172.067395ms","start":"2026-08-13T08:38:24.217997Z","end":"2026-08-13T08:38:24.390064Z","steps":["trace[621073925] 'read index received' (duration: 149.855908ms)","trace[621073925] 'applied index is now lower than readState.Index' (duration: 22.210497ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:38:24.39041Z","caller":"traceutil/trace.go:171","msg":"trace[694898077] transaction","detail":"{read_only:false; response_revision:4406; number_of_response:1; }","duration":"155.848264ms","start":"2026-08-13T08:38:24.234532Z","end":"2026-08-13T08:38:24.39038Z","steps":["trace[694898077] 'process raft request' (duration: 155.479247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:24.390579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.562246ms","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-08-13T08:38:24.390628Z","caller":"traceutil/trace.go:171","msg":"trace[2021625393] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4406; }","duration":"172.652597ms","start":"2026-08-13T08:38:24.217963Z","end":"2026-08-13T08:38:24.390616Z","steps":["trace[2021625393] 'agreement among raft nodes before linearized reading' (duration: 172.478604ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:24.390726Z","caller":"traceutil/trace.go:171","msg":"trace[1496999661] transaction","detail":"{read_only:false; response_revision:4402; number_of_response:1; }","duration":"213.274202ms","start":"2026-08-13T08:38:24.177423Z","end":"2026-08-13T08:38:24.390697Z","steps":["trace[1496999661] 'process raft request' (duration: 212.430034ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:24.390885Z","caller":"traceutil/trace.go:171","msg":"trace[201333711] transaction","detail":"{read_only:false; response_revision:4403; number_of_response:1; }","duration":"192.778241ms","start":"2026-08-13T08:38:24.198085Z","end":"2026-08-13T08:38:24.390863Z","steps":["trace[201333711] 'process raft request' (duration: 191.823271ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:24.391058Z","caller":"traceutil/trace.go:171","msg":"trace[774865471] transaction","detail":"{read_only:false; response_revision:4401; number_of_response:1; }","duration":"249.244907ms","start":"2026-08-13T08:38:24.141803Z","end":"2026-08-13T08:38:24.391048Z","steps":["trace[774865471] 'process raft request' (duration: 247.917489ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:24.391091Z","caller":"traceutil/trace.go:171","msg":"trace[211163653] transaction","detail":"{read_only:false; response_revision:4405; number_of_response:1; }","duration":"183.365404ms","start":"2026-08-13T08:38:24.207713Z","end":"2026-08-13T08:38:24.391078Z","steps":["trace[211163653] 'process raft request' (duration: 182.26637ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:24.390623Z","caller":"traceutil/trace.go:171","msg":"trace[652138584] transaction","detail":"{read_only:false; response_revision:4404; number_of_response:1; }","duration":"184.978067ms","start":"2026-08-13T08:38:24.205334Z","end":"2026-08-13T08:38:24.390312Z","steps":["trace[652138584] 'process raft request' (duration: 184.615839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:24.398014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.352726ms","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-08-13T08:38:24.398068Z","caller":"traceutil/trace.go:171","msg":"trace[1990632135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4406; }","duration":"156.456658ms","start":"2026-08-13T08:38:24.241598Z","end":"2026-08-13T08:38:24.398055Z","steps":["trace[1990632135] 'agreement among raft nodes before linearized reading' (duration: 156.298575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:24.39823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.379946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-13T08:38:24.398287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.517479ms","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-08-13T08:38:24.398302Z","caller":"traceutil/trace.go:171","msg":"trace[467001218] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4406; }","duration":"156.443578ms","start":"2026-08-13T08:38:24.241829Z","end":"2026-08-13T08:38:24.398273Z","steps":["trace[467001218] 'agreement among raft nodes before linearized reading' (duration: 156.278384ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:24.398328Z","caller":"traceutil/trace.go:171","msg":"trace[1031642644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4406; }","duration":"156.60335ms","start":"2026-08-13T08:38:24.241715Z","end":"2026-08-13T08:38:24.398319Z","steps":["trace[1031642644] 'agreement among raft nodes before linearized reading' (duration: 156.473218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:24.398428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.094586ms","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-08-13T08:38:24.398475Z","caller":"traceutil/trace.go:171","msg":"trace[706813350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4406; }","duration":"142.503734ms","start":"2026-08-13T08:38:24.255946Z","end":"2026-08-13T08:38:24.39845Z","steps":["trace[706813350] 'agreement among raft nodes before linearized reading' (duration: 141.981453ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:31.131448Z","caller":"traceutil/trace.go:171","msg":"trace[1262225893] transaction","detail":"{read_only:false; response_revision:4556; number_of_response:1; }","duration":"104.752445ms","start":"2026-08-13T08:38:31.026405Z","end":"2026-08-13T08:38:31.131157Z","steps":["trace[1262225893] 'process raft request' (duration: 104.620902ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:32.180176Z","caller":"traceutil/trace.go:171","msg":"trace[522908471] transaction","detail":"{read_only:false; response_revision:4563; number_of_response:1; }","duration":"119.934676ms","start":"2026-08-13T08:38:32.060223Z","end":"2026-08-13T08:38:32.180157Z","steps":["trace[522908471] 'process raft request' (duration: 119.809493ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:32.99445Z","caller":"traceutil/trace.go:171","msg":"trace[1799760495] transaction","detail":"{read_only:false; response_revision:4587; number_of_response:1; }","duration":"163.052678ms","start":"2026-08-13T08:38:32.831381Z","end":"2026-08-13T08:38:32.994434Z","steps":["trace[1799760495] 'process raft request' (duration: 162.931756ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:33.028788Z","caller":"traceutil/trace.go:171","msg":"trace[1137946721] transaction","detail":"{read_only:false; response_revision:4588; number_of_response:1; }","duration":"179.154812ms","start":"2026-08-13T08:38:32.849613Z","end":"2026-08-13T08:38:33.028768Z","steps":["trace[1137946721] 'process raft request' (duration: 179.017549ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:33.540438Z","caller":"traceutil/trace.go:171","msg":"trace[1012989666] linearizableReadLoop","detail":"{readStateIndex:4775; appliedIndex:4774; }","duration":"255.915104ms","start":"2026-08-13T08:38:33.284477Z","end":"2026-08-13T08:38:33.540392Z","steps":["trace[1012989666] 'read index received' (duration: 255.585626ms)","trace[1012989666] 'applied index is now lower than readState.Index' (duration: 328.428µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:38:33.5405Z","caller":"traceutil/trace.go:171","msg":"trace[1267879808] transaction","detail":"{read_only:false; response_revision:4593; number_of_response:1; }","duration":"264.548753ms","start":"2026-08-13T08:38:33.275921Z","end":"2026-08-13T08:38:33.54047Z","steps":["trace[1267879808] 'process raft request' (duration: 264.264867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:33.540874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.336903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"warn","ts":"2026-08-13T08:38:33.540917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.3172ms","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-08-13T08:38:33.540967Z","caller":"traceutil/trace.go:171","msg":"trace[722957435] 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:4593; }","duration":"222.403222ms","start":"2026-08-13T08:38:33.318549Z","end":"2026-08-13T08:38:33.540952Z","steps":["trace[722957435] 'agreement among raft nodes before linearized reading' (duration: 222.265999ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:33.540962Z","caller":"traceutil/trace.go:171","msg":"trace[104382298] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4593; }","duration":"256.477696ms","start":"2026-08-13T08:38:33.28444Z","end":"2026-08-13T08:38:33.540917Z","steps":["trace[104382298] 'agreement among raft nodes before linearized reading' (duration: 256.17553ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:36.625337Z","caller":"traceutil/trace.go:171","msg":"trace[1538624365] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"164.20039ms","start":"2026-08-13T08:38:36.461109Z","end":"2026-08-13T08:38:36.62531Z","steps":["trace[1538624365] 'process raft request' (duration: 164.016626ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:41.432354Z","caller":"traceutil/trace.go:171","msg":"trace[691882859] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"773.974341ms","start":"2026-08-13T08:38:40.658342Z","end":"2026-08-13T08:38:41.432317Z","steps":["trace[691882859] 'process raft request' (duration: 773.896459ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:41.432367Z","caller":"traceutil/trace.go:171","msg":"trace[93995394] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"783.404933ms","start":"2026-08-13T08:38:40.64892Z","end":"2026-08-13T08:38:41.432325Z","steps":["trace[93995394] 'process raft request' (duration: 783.076577ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:41.432553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:40.658295Z","time spent":"774.160125ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:38:41.432577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:40.648888Z","time spent":"783.584857ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:38:41.432698Z","caller":"traceutil/trace.go:171","msg":"trace[1392589312] linearizableReadLoop","detail":"{readStateIndex:4914; appliedIndex:4910; }","duration":"771.373677ms","start":"2026-08-13T08:38:40.661314Z","end":"2026-08-13T08:38:41.432688Z","steps":["trace[1392589312] 'read index received' (duration: 62.900596ms)","trace[1392589312] 'applied index is now lower than readState.Index' (duration: 708.472001ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:38:41.432783Z","caller":"traceutil/trace.go:171","msg":"trace[66314249] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"773.684174ms","start":"2026-08-13T08:38:40.659087Z","end":"2026-08-13T08:38:41.432771Z","steps":["trace[66314249] 'process raft request' (duration: 773.181164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:41.432824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:40.659079Z","time spent":"773.727955ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:38:41.432998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.697634ms","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-08-13T08:38:41.43303Z","caller":"traceutil/trace.go:171","msg":"trace[1688019835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4727; }","duration":"771.729824ms","start":"2026-08-13T08:38:40.661293Z","end":"2026-08-13T08:38:41.433023Z","steps":["trace[1688019835] 'agreement among raft nodes before linearized reading' (duration: 771.592731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:41.433102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:40.66124Z","time spent":"771.856917ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T08:38:41.4332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.964662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:38:41.433221Z","caller":"traceutil/trace.go:171","msg":"trace[1460002990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4727; }","duration":"731.035553ms","start":"2026-08-13T08:38:40.702181Z","end":"2026-08-13T08:38:41.433216Z","steps":["trace[1460002990] 'agreement among raft nodes before linearized reading' (duration: 730.998382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:41.433236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:40.702158Z","time spent":"731.074263ms","remote":"127.0.0.1:55692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T08:38:41.433454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.74093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7d96c16-b07d-4869-a360-cdc765c0b3dc\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-13T08:38:41.43348Z","caller":"traceutil/trace.go:171","msg":"trace[1604644791] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b7d96c16-b07d-4869-a360-cdc765c0b3dc; range_end:; response_count:1; response_revision:4727; }","duration":"349.79759ms","start":"2026-08-13T08:38:41.083674Z","end":"2026-08-13T08:38:41.433472Z","steps":["trace[1604644791] 'agreement among raft nodes before linearized reading' (duration: 349.722559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:41.433494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:41.083655Z","time spent":"349.835892ms","remote":"127.0.0.1:55864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7d96c16-b07d-4869-a360-cdc765c0b3dc\" "} {"level":"warn","ts":"2026-08-13T08:38:41.433678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.768401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-13T08:38:41.433727Z","caller":"traceutil/trace.go:171","msg":"trace[682978568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4727; }","duration":"695.841833ms","start":"2026-08-13T08:38:40.737874Z","end":"2026-08-13T08:38:41.433716Z","steps":["trace[682978568] 'agreement among raft nodes before linearized reading' (duration: 695.639909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:41.433753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:40.737857Z","time spent":"695.889334ms","remote":"127.0.0.1:55944","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-08-13T08:38:41.433833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.260041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-13T08:38:41.433862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.876523ms","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-08-13T08:38:41.433873Z","caller":"traceutil/trace.go:171","msg":"trace[1617920957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4727; }","duration":"674.316612ms","start":"2026-08-13T08:38:40.759542Z","end":"2026-08-13T08:38:41.433858Z","steps":["trace[1617920957] 'agreement among raft nodes before linearized reading' (duration: 674.186879ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:41.433881Z","caller":"traceutil/trace.go:171","msg":"trace[680974914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4727; }","duration":"695.932524ms","start":"2026-08-13T08:38:40.737943Z","end":"2026-08-13T08:38:41.433875Z","steps":["trace[680974914] 'agreement among raft nodes before linearized reading' (duration: 695.872713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:41.433899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:40.737923Z","time spent":"695.972056ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T08:38:41.433891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:40.759526Z","time spent":"674.360902ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-13T08:38:41.433939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.788023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-13T08:38:41.434024Z","caller":"traceutil/trace.go:171","msg":"trace[1675795834] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4727; }","duration":"699.906015ms","start":"2026-08-13T08:38:40.734099Z","end":"2026-08-13T08:38:41.434005Z","steps":["trace[1675795834] 'agreement among raft nodes before linearized reading' (duration: 699.66352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:41.434074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:40.734085Z","time spent":"699.977916ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T08:38:56.02275Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228168877043252,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:38:56.374814Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.155778536s","expected-duration":"1s"} {"level":"info","ts":"2026-08-13T08:38:56.375322Z","caller":"traceutil/trace.go:171","msg":"trace[1201043197] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"1.156384078s","start":"2026-08-13T08:38:55.218907Z","end":"2026-08-13T08:38:56.375291Z","steps":["trace[1201043197] 'process raft request' (duration: 1.156151824s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:56.37552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:55.218895Z","time spent":"1.156542301s","remote":"127.0.0.1:55864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:38:56.530207Z","caller":"traceutil/trace.go:171","msg":"trace[1286994246] linearizableReadLoop","detail":"{readStateIndex:5108; appliedIndex:5103; }","duration":"1.008366947s","start":"2026-08-13T08:38:55.521817Z","end":"2026-08-13T08:38:56.530184Z","steps":["trace[1286994246] 'read index received' (duration: 853.222122ms)","trace[1286994246] 'applied index is now lower than readState.Index' (duration: 155.143855ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:38:56.530252Z","caller":"traceutil/trace.go:171","msg":"trace[1665183986] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"1.044452518s","start":"2026-08-13T08:38:55.485758Z","end":"2026-08-13T08:38:56.530211Z","steps":["trace[1665183986] 'process raft request' (duration: 1.044315245s)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:56.530398Z","caller":"traceutil/trace.go:171","msg":"trace[1429529573] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"1.044530769s","start":"2026-08-13T08:38:55.485829Z","end":"2026-08-13T08:38:56.53036Z","steps":["trace[1429529573] 'process raft request' (duration: 1.044313205s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:56.53044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:55.485749Z","time spent":"1.044593911s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:38:56.530471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008631902s","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-08-13T08:38:56.530534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.486771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-13T08:38:56.530567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:55.485822Z","time spent":"1.044659101s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:38:56.530597Z","caller":"traceutil/trace.go:171","msg":"trace[93238978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4914; }","duration":"1.008805815s","start":"2026-08-13T08:38:55.521779Z","end":"2026-08-13T08:38:56.530585Z","steps":["trace[93238978] 'agreement among raft nodes before linearized reading' (duration: 1.008572501s)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:56.530614Z","caller":"traceutil/trace.go:171","msg":"trace[1759830742] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4914; }","duration":"884.598934ms","start":"2026-08-13T08:38:55.645997Z","end":"2026-08-13T08:38:56.530596Z","steps":["trace[1759830742] 'agreement among raft nodes before linearized reading' (duration: 884.39388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:56.530693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:55.521765Z","time spent":"1.008873886s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-13T08:38:56.530785Z","caller":"traceutil/trace.go:171","msg":"trace[1679313584] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"1.04559601s","start":"2026-08-13T08:38:55.485167Z","end":"2026-08-13T08:38:56.530763Z","steps":["trace[1679313584] 'process raft request' (duration: 1.044751353s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:56.530891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.070334ms","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-08-13T08:38:56.531581Z","caller":"traceutil/trace.go:171","msg":"trace[1073842068] 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:4914; }","duration":"301.788619ms","start":"2026-08-13T08:38:56.229769Z","end":"2026-08-13T08:38:56.531557Z","steps":["trace[1073842068] 'agreement among raft nodes before linearized reading' (duration: 300.951792ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:56.530696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:55.645976Z","time spent":"884.706966ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-13T08:38:56.530972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:55.48514Z","time spent":"1.045736483s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:38:56.531042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.172786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17149"} {"level":"warn","ts":"2026-08-13T08:38:56.531679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:56.229749Z","time spent":"301.880311ms","remote":"127.0.0.1:55824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-08-13T08:38:56.53207Z","caller":"traceutil/trace.go:171","msg":"trace[59461579] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4914; }","duration":"151.186065ms","start":"2026-08-13T08:38:56.380817Z","end":"2026-08-13T08:38:56.532003Z","steps":["trace[59461579] 'agreement among raft nodes before linearized reading' (duration: 150.086963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:56.531132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.758472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T08:38:56.532271Z","caller":"traceutil/trace.go:171","msg":"trace[1060550933] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:4914; }","duration":"249.906245ms","start":"2026-08-13T08:38:56.282323Z","end":"2026-08-13T08:38:56.532229Z","steps":["trace[1060550933] 'agreement among raft nodes before linearized reading' (duration: 248.768982ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:56.531138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.015684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-13T08:38:56.531169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.457654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-13T08:38:56.532478Z","caller":"traceutil/trace.go:171","msg":"trace[203099712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4914; }","duration":"850.788509ms","start":"2026-08-13T08:38:55.681667Z","end":"2026-08-13T08:38:56.532456Z","steps":["trace[203099712] 'agreement among raft nodes before linearized reading' (duration: 849.334211ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:56.532381Z","caller":"traceutil/trace.go:171","msg":"trace[1764302117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4914; }","duration":"846.273288ms","start":"2026-08-13T08:38:55.686088Z","end":"2026-08-13T08:38:56.532361Z","steps":["trace[1764302117] 'agreement among raft nodes before linearized reading' (duration: 844.952282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:56.532562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:55.68165Z","time spent":"850.897252ms","remote":"127.0.0.1:55944","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-08-13T08:38:56.532773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:55.686081Z","time spent":"846.608336ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T08:38:56.531274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.951892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-13T08:38:56.532937Z","caller":"traceutil/trace.go:171","msg":"trace[1908473790] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4914; }","duration":"885.626785ms","start":"2026-08-13T08:38:55.647293Z","end":"2026-08-13T08:38:56.53292Z","steps":["trace[1908473790] 'agreement among raft nodes before linearized reading' (duration: 883.879441ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:56.533005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:55.647284Z","time spent":"885.698046ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T08:38:56.531502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.102847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:38:56.533149Z","caller":"traceutil/trace.go:171","msg":"trace[118497382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4914; }","duration":"831.727468ms","start":"2026-08-13T08:38:55.701349Z","end":"2026-08-13T08:38:56.533076Z","steps":["trace[118497382] 'agreement among raft nodes before linearized reading' (duration: 829.374491ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:56.533247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:38:55.701332Z","time spent":"831.902082ms","remote":"127.0.0.1:55692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T08:38:56.697391Z","caller":"traceutil/trace.go:171","msg":"trace[451844488] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"152.373649ms","start":"2026-08-13T08:38:56.544976Z","end":"2026-08-13T08:38:56.69735Z","steps":["trace[451844488] 'process raft request' (duration: 134.699407ms)","trace[451844488] 'compare' (duration: 17.337846ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:38:56.69777Z","caller":"traceutil/trace.go:171","msg":"trace[2048905577] linearizableReadLoop","detail":"{readStateIndex:5114; appliedIndex:5113; }","duration":"115.393652ms","start":"2026-08-13T08:38:56.582078Z","end":"2026-08-13T08:38:56.697472Z","steps":["trace[2048905577] 'read index received' (duration: 97.558386ms)","trace[2048905577] 'applied index is now lower than readState.Index' (duration: 17.833036ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:38:56.698246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.100905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-13T08:38:56.698304Z","caller":"traceutil/trace.go:171","msg":"trace[1635725453] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4920; }","duration":"116.250509ms","start":"2026-08-13T08:38:56.582039Z","end":"2026-08-13T08:38:56.698289Z","steps":["trace[1635725453] 'agreement among raft nodes before linearized reading' (duration: 115.996064ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:57.021442Z","caller":"traceutil/trace.go:171","msg":"trace[298775413] linearizableReadLoop","detail":"{readStateIndex:5121; appliedIndex:5120; }","duration":"140.047393ms","start":"2026-08-13T08:38:56.88137Z","end":"2026-08-13T08:38:57.021417Z","steps":["trace[298775413] 'read index received' (duration: 54.126189ms)","trace[298775413] 'applied index is now lower than readState.Index' (duration: 85.920014ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:38:57.021582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.190655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:38:57.021615Z","caller":"traceutil/trace.go:171","msg":"trace[902570375] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:4927; }","duration":"140.242836ms","start":"2026-08-13T08:38:56.881362Z","end":"2026-08-13T08:38:57.021605Z","steps":["trace[902570375] 'agreement among raft nodes before linearized reading' (duration: 140.141234ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:57.021495Z","caller":"traceutil/trace.go:171","msg":"trace[74033700] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"228.013288ms","start":"2026-08-13T08:38:56.793442Z","end":"2026-08-13T08:38:57.021455Z","steps":["trace[74033700] 'process raft request' (duration: 142.119905ms)","trace[74033700] 'compare' (duration: 85.541976ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:40:04.591933Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2843} {"level":"info","ts":"2026-08-13T08:40:04.685574Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2843,"took":"91.035316ms","hash":3677915985,"current-db-size-bytes":53641216,"current-db-size":"54 MB","current-db-size-in-use-bytes":29270016,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-13T08:40:04.685669Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3677915985,"revision":2843,"compact-revision":-1} {"level":"info","ts":"2026-08-13T08:42:44.49276Z","caller":"traceutil/trace.go:171","msg":"trace[1145903480] transaction","detail":"{read_only:false; response_revision:6559; number_of_response:1; }","duration":"118.746059ms","start":"2026-08-13T08:42:44.373975Z","end":"2026-08-13T08:42:44.492721Z","steps":["trace[1145903480] 'process raft request' (duration: 101.699172ms)","trace[1145903480] 'compare' (duration: 16.895084ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:43:03.856505Z","caller":"traceutil/trace.go:171","msg":"trace[356426097] transaction","detail":"{read_only:false; response_revision:6710; number_of_response:1; }","duration":"111.060859ms","start":"2026-08-13T08:43:03.745413Z","end":"2026-08-13T08:43:03.856474Z","steps":["trace[356426097] 'process raft request' (duration: 55.61677ms)","trace[356426097] 'compare' (duration: 55.261536ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:45:04.597608Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5487} {"level":"info","ts":"2026-08-13T08:45:04.673993Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5487,"took":"74.961448ms","hash":2692152668,"current-db-size-bytes":53641216,"current-db-size":"54 MB","current-db-size-in-use-bytes":18489344,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-13T08:45:04.674056Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2692152668,"revision":5487,"compact-revision":2843}