{"level":"warn","ts":"2026-06-18T12:28:23.779019Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-18T12:28:23.779099Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.229:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.229:2380","--initial-cluster=instance=https://199.19.213.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.229:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-18T12:28:23.779162Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-18T12:28:23.77917Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-06-18T12:28:23.779185Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-18T12:28:23.779804Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"]} {"level":"info","ts":"2026-06-18T12:28:23.779874Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.229:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-18T12:28:23.784493Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.352275ms"} {"level":"info","ts":"2026-06-18T12:28:23.793657Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"98aaa0557ab926b1","cluster-id":"250cc9bd258bffbf"} {"level":"info","ts":"2026-06-18T12:28:23.79372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=()"} {"level":"info","ts":"2026-06-18T12:28:23.793738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 0"} {"level":"info","ts":"2026-06-18T12:28:23.793748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 98aaa0557ab926b1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-18T12:28:23.79376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 1"} {"level":"info","ts":"2026-06-18T12:28:23.793798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"warn","ts":"2026-06-18T12:28:23.807865Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-18T12:28:23.809173Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-18T12:28:23.811241Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-18T12:28:23.816323Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"98aaa0557ab926b1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-18T12:28:23.816541Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"98aaa0557ab926b1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-18T12:28:23.816644Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-18T12:28:23.818391Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-18T12:28:23.818435Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-18T12:28:23.818676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"info","ts":"2026-06-18T12:28:23.819027Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","added-peer-id":"98aaa0557ab926b1","added-peer-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-06-18T12:28:23.821114Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-18T12:28:23.821232Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-06-18T12:28:23.821261Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-06-18T12:28:23.821416Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"98aaa0557ab926b1","initial-advertise-peer-urls":["https://199.19.213.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-18T12:28:23.821461Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-18T12:28:24.394074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 is starting a new election at term 1"} {"level":"info","ts":"2026-06-18T12:28:24.394191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-18T12:28:24.394237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgPreVoteResp from 98aaa0557ab926b1 at term 1"} {"level":"info","ts":"2026-06-18T12:28:24.394268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became candidate at term 2"} {"level":"info","ts":"2026-06-18T12:28:24.394292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgVoteResp from 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-06-18T12:28:24.394326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became leader at term 2"} {"level":"info","ts":"2026-06-18T12:28:24.394352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 98aaa0557ab926b1 elected leader 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-06-18T12:28:24.395439Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"98aaa0557ab926b1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.229:2379]}","request-path":"/0/members/98aaa0557ab926b1/attributes","cluster-id":"250cc9bd258bffbf","publish-timeout":"7s"} {"level":"info","ts":"2026-06-18T12:28:24.395589Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-18T12:28:24.395744Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T12:28:24.395957Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-18T12:28:24.395994Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-18T12:28:24.395999Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-18T12:28:24.396553Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T12:28:24.396618Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T12:28:24.396645Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T12:28:24.398597Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-18T12:28:24.398742Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.229:2379"} {"level":"info","ts":"2026-06-18T12:28:27.020773Z","caller":"traceutil/trace.go:171","msg":"trace[1800974201] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"151.010552ms","start":"2026-06-18T12:28:26.869735Z","end":"2026-06-18T12:28:27.020746Z","steps":["trace[1800974201] 'process raft request' (duration: 121.57074ms)","trace[1800974201] 'compare' (duration: 29.319374ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:28:27.611252Z","caller":"traceutil/trace.go:171","msg":"trace[815688096] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"104.691723ms","start":"2026-06-18T12:28:27.506527Z","end":"2026-06-18T12:28:27.611218Z","steps":["trace[815688096] 'process raft request' (duration: 53.253352ms)","trace[815688096] 'compare' (duration: 51.191585ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:28:29.283047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.269633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:28:29.283294Z","caller":"traceutil/trace.go:171","msg":"trace[402831264] linearizableReadLoop","detail":"{readStateIndex:164; appliedIndex:163; }","duration":"413.233118ms","start":"2026-06-18T12:28:28.870038Z","end":"2026-06-18T12:28:29.283272Z","steps":["trace[402831264] 'read index received' (duration: 42.91µs)","trace[402831264] 'applied index is now lower than readState.Index' (duration: 413.188258ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:28:29.283427Z","caller":"traceutil/trace.go:171","msg":"trace[665891054] transaction","detail":"{read_only:false; response_revision:160; number_of_response:1; }","duration":"713.877538ms","start":"2026-06-18T12:28:28.569487Z","end":"2026-06-18T12:28:29.283365Z","steps":["trace[665891054] 'process raft request' (duration: 211.637426ms)","trace[665891054] 'compare' (duration: 501.095806ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:28:29.283508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.442506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T12:28:29.283506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.954131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:28:29.283707Z","caller":"traceutil/trace.go:171","msg":"trace[395363813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:160; }","duration":"348.180689ms","start":"2026-06-18T12:28:28.935507Z","end":"2026-06-18T12:28:29.283688Z","steps":["trace[395363813] 'agreement among raft nodes before linearized reading' (duration: 347.942422ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:28:29.283735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:28:28.569473Z","time spent":"714.093724ms","remote":"127.0.0.1:48478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":792,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T12:28:29.283623Z","caller":"traceutil/trace.go:171","msg":"trace[313974518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:160; }","duration":"413.604063ms","start":"2026-06-18T12:28:28.86999Z","end":"2026-06-18T12:28:29.283594Z","steps":["trace[313974518] 'agreement among raft nodes before linearized reading' (duration: 413.406666ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:28:29.283801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:28:28.935497Z","time spent":"348.287208ms","remote":"127.0.0.1:48122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T12:28:29.283895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:28:28.869967Z","time spent":"413.913789ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T12:28:29.766715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.348028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:28:29.766814Z","caller":"traceutil/trace.go:171","msg":"trace[2012148154] transaction","detail":"{read_only:false; response_revision:161; number_of_response:1; }","duration":"478.524675ms","start":"2026-06-18T12:28:29.288274Z","end":"2026-06-18T12:28:29.766799Z","steps":["trace[2012148154] 'process raft request' (duration: 330.049169ms)","trace[2012148154] 'compare' (duration: 148.220309ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:28:29.766904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:28:29.288261Z","time spent":"478.619904ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T12:28:31.038469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047606038s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-06-18T12:28:31.038726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:28:29.77131Z","time spent":"1.267409895s","remote":"127.0.0.1:48206","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-18T12:28:31.039004Z","caller":"traceutil/trace.go:171","msg":"trace[2143018146] transaction","detail":"{read_only:false; response_revision:162; number_of_response:1; }","duration":"1.749085253s","start":"2026-06-18T12:28:29.289898Z","end":"2026-06-18T12:28:31.038983Z","steps":["trace[2143018146] 'process raft request' (duration: 700.86762ms)","trace[2143018146] 'compare' (duration: 1.047221772s)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:28:31.039077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:28:29.289875Z","time spent":"1.749175972s","remote":"127.0.0.1:48478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":728,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T12:28:31.03921Z","caller":"traceutil/trace.go:171","msg":"trace[1471355249] linearizableReadLoop","detail":"{readStateIndex:166; appliedIndex:165; }","duration":"1.268884758s","start":"2026-06-18T12:28:29.770316Z","end":"2026-06-18T12:28:31.0392Z","steps":["trace[1471355249] 'read index received' (duration: 220.483366ms)","trace[1471355249] 'applied index is now lower than readState.Index' (duration: 1.048400322s)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:28:31.039391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.269056356s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-18T12:28:31.039451Z","caller":"traceutil/trace.go:171","msg":"trace[534507763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:162; }","duration":"1.269155515s","start":"2026-06-18T12:28:29.770283Z","end":"2026-06-18T12:28:31.039439Z","steps":["trace[534507763] 'agreement among raft nodes before linearized reading' (duration: 1.269044707s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:28:31.039486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:28:29.770267Z","time spent":"1.269212785s","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-18T12:28:31.039506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207023391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-18T12:28:31.039554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.738409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T12:28:31.039589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104423143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:28:31.039622Z","caller":"traceutil/trace.go:171","msg":"trace[2102686382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:162; }","duration":"1.104454343s","start":"2026-06-18T12:28:29.935159Z","end":"2026-06-18T12:28:31.039614Z","steps":["trace[2102686382] 'agreement among raft nodes before linearized reading' (duration: 1.104411083s)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:28:31.03955Z","caller":"traceutil/trace.go:171","msg":"trace[1135633682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:162; }","duration":"1.20710959s","start":"2026-06-18T12:28:29.832429Z","end":"2026-06-18T12:28:31.039539Z","steps":["trace[1135633682] 'agreement among raft nodes before linearized reading' (duration: 1.206994332s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:28:31.039657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:28:29.9351Z","time spent":"1.104550801s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-18T12:28:31.039593Z","caller":"traceutil/trace.go:171","msg":"trace[220506386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:162; }","duration":"965.807489ms","start":"2026-06-18T12:28:30.073772Z","end":"2026-06-18T12:28:31.03958Z","steps":["trace[220506386] 'agreement among raft nodes before linearized reading' (duration: 965.76289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:28:31.039702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:28:30.07375Z","time spent":"965.947988ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-18T12:28:31.039498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092947305s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:28:31.039808Z","caller":"traceutil/trace.go:171","msg":"trace[2037039025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:162; }","duration":"1.093256042s","start":"2026-06-18T12:28:29.946516Z","end":"2026-06-18T12:28:31.039772Z","steps":["trace[2037039025] 'agreement among raft nodes before linearized reading' (duration: 1.092912046s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:28:31.039895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:28:29.946443Z","time spent":"1.093425791s","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-18T12:28:31.039671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:28:29.83241Z","time spent":"1.207243168s","remote":"127.0.0.1:48354","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-06-18T12:28:31.40404Z","caller":"traceutil/trace.go:171","msg":"trace[844084553] linearizableReadLoop","detail":"{readStateIndex:199; appliedIndex:198; }","duration":"152.495119ms","start":"2026-06-18T12:28:31.251519Z","end":"2026-06-18T12:28:31.404014Z","steps":["trace[844084553] 'read index received' (duration: 98.057277ms)","trace[844084553] 'applied index is now lower than readState.Index' (duration: 54.436563ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:28:31.40411Z","caller":"traceutil/trace.go:171","msg":"trace[314188736] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"153.207522ms","start":"2026-06-18T12:28:31.25085Z","end":"2026-06-18T12:28:31.404057Z","steps":["trace[314188736] 'process raft request' (duration: 98.78246ms)","trace[314188736] 'compare' (duration: 54.232964ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:28:31.404204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.665558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:28:31.404255Z","caller":"traceutil/trace.go:171","msg":"trace[361765561] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:route-controller; range_end:; response_count:0; response_revision:194; }","duration":"152.732477ms","start":"2026-06-18T12:28:31.251505Z","end":"2026-06-18T12:28:31.404238Z","steps":["trace[361765561] 'agreement among raft nodes before linearized reading' (duration: 152.606739ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:28:31.916643Z","caller":"traceutil/trace.go:171","msg":"trace[1156908714] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"228.418136ms","start":"2026-06-18T12:28:31.688193Z","end":"2026-06-18T12:28:31.916611Z","steps":["trace[1156908714] 'process raft request' (duration: 181.692551ms)","trace[1156908714] 'compare' (duration: 46.525127ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:28:31.91662Z","caller":"traceutil/trace.go:171","msg":"trace[1822291838] linearizableReadLoop","detail":"{readStateIndex:213; appliedIndex:212; }","duration":"191.041382ms","start":"2026-06-18T12:28:31.725504Z","end":"2026-06-18T12:28:31.916546Z","steps":["trace[1822291838] 'read index received' (duration: 144.412765ms)","trace[1822291838] 'applied index is now lower than readState.Index' (duration: 46.627167ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:28:31.916924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.375039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:28:31.917059Z","caller":"traceutil/trace.go:171","msg":"trace[155726360] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pv-protection-controller; range_end:; response_count:0; response_revision:208; }","duration":"191.544397ms","start":"2026-06-18T12:28:31.725498Z","end":"2026-06-18T12:28:31.917042Z","steps":["trace[155726360] 'agreement among raft nodes before linearized reading' (duration: 191.194111ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:28:55.400935Z","caller":"traceutil/trace.go:171","msg":"trace[4346229] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:502; }","duration":"186.488088ms","start":"2026-06-18T12:28:55.214411Z","end":"2026-06-18T12:28:55.400899Z","steps":["trace[4346229] 'read index received' (duration: 178.000651ms)","trace[4346229] 'applied index is now lower than readState.Index' (duration: 8.485887ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:28:55.400975Z","caller":"traceutil/trace.go:171","msg":"trace[1463755464] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"188.115681ms","start":"2026-06-18T12:28:55.212834Z","end":"2026-06-18T12:28:55.40095Z","steps":["trace[1463755464] 'process raft request' (duration: 179.515011ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:28:55.401143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.710492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-18T12:28:55.401183Z","caller":"traceutil/trace.go:171","msg":"trace[703427606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:484; }","duration":"186.803385ms","start":"2026-06-18T12:28:55.214368Z","end":"2026-06-18T12:28:55.401171Z","steps":["trace[703427606] 'agreement among raft nodes before linearized reading' (duration: 186.642702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:28:57.696618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.077003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:28:57.697013Z","caller":"traceutil/trace.go:171","msg":"trace[1547203119] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"463.676275ms","start":"2026-06-18T12:28:57.233316Z","end":"2026-06-18T12:28:57.696992Z","steps":["trace[1547203119] 'process raft request' (duration: 248.934297ms)","trace[1547203119] 'compare' (duration: 213.95422ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:28:57.697111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:28:57.2333Z","time spent":"463.771237ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:28:57.725382Z","caller":"traceutil/trace.go:171","msg":"trace[2132156815] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:506; }","duration":"319.585886ms","start":"2026-06-18T12:28:57.405781Z","end":"2026-06-18T12:28:57.725367Z","steps":["trace[2132156815] 'read index received' (duration: 76.479854ms)","trace[2132156815] 'applied index is now lower than readState.Index' (duration: 243.104872ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:28:57.725533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.222059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-18T12:28:57.725565Z","caller":"traceutil/trace.go:171","msg":"trace[74413265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:488; }","duration":"313.29537ms","start":"2026-06-18T12:28:57.412259Z","end":"2026-06-18T12:28:57.725554Z","steps":["trace[74413265] 'agreement among raft nodes before linearized reading' (duration: 313.226208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:28:57.725571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.769269ms","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-06-18T12:28:57.725649Z","caller":"traceutil/trace.go:171","msg":"trace[1303375335] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:488; }","duration":"319.896802ms","start":"2026-06-18T12:28:57.405733Z","end":"2026-06-18T12:28:57.725629Z","steps":["trace[1303375335] 'agreement among raft nodes before linearized reading' (duration: 319.732269ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:28:57.725697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:28:57.405711Z","time spent":"319.971284ms","remote":"127.0.0.1:48354","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-06-18T12:28:57.725595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:28:57.412238Z","time spent":"313.350421ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-18T12:29:04.437719Z","caller":"traceutil/trace.go:171","msg":"trace[282715385] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"124.079292ms","start":"2026-06-18T12:29:04.313619Z","end":"2026-06-18T12:29:04.437698Z","steps":["trace[282715385] 'process raft request' (duration: 123.942818ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:29:06.04306Z","caller":"traceutil/trace.go:171","msg":"trace[1741320795] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"478.744063ms","start":"2026-06-18T12:29:05.56429Z","end":"2026-06-18T12:29:06.043034Z","steps":["trace[1741320795] 'process raft request' (duration: 465.605282ms)","trace[1741320795] 'compare' (duration: 12.957466ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:29:06.043202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:05.564271Z","time spent":"478.860256ms","remote":"127.0.0.1:48168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:29:06.048005Z","caller":"traceutil/trace.go:171","msg":"trace[1596500897] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"481.210849ms","start":"2026-06-18T12:29:05.566776Z","end":"2026-06-18T12:29:06.047987Z","steps":["trace[1596500897] 'process raft request' (duration: 481.024613ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:06.048134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:05.566744Z","time spent":"481.322392ms","remote":"127.0.0.1:48168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:29:06.048391Z","caller":"traceutil/trace.go:171","msg":"trace[1097527817] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:597; }","duration":"324.290629ms","start":"2026-06-18T12:29:05.724082Z","end":"2026-06-18T12:29:06.048373Z","steps":["trace[1097527817] 'read index received' (duration: 306.089287ms)","trace[1097527817] 'applied index is now lower than readState.Index' (duration: 18.200532ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:29:06.048491Z","caller":"traceutil/trace.go:171","msg":"trace[539035617] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"450.278684ms","start":"2026-06-18T12:29:05.598202Z","end":"2026-06-18T12:29:06.04848Z","steps":["trace[539035617] 'process raft request' (duration: 449.755869ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:06.048556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:05.598177Z","time spent":"450.336085ms","remote":"127.0.0.1:48168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T12:29:06.0487Z","caller":"traceutil/trace.go:171","msg":"trace[1984120360] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"421.755269ms","start":"2026-06-18T12:29:05.626936Z","end":"2026-06-18T12:29:06.048691Z","steps":["trace[1984120360] 'process raft request' (duration: 421.272475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:06.048769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:05.626907Z","time spent":"421.825161ms","remote":"127.0.0.1:48168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:29:06.049033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.946808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-18T12:29:06.049073Z","caller":"traceutil/trace.go:171","msg":"trace[751892945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"325.0195ms","start":"2026-06-18T12:29:05.724044Z","end":"2026-06-18T12:29:06.049063Z","steps":["trace[751892945] 'agreement among raft nodes before linearized reading' (duration: 324.825525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:06.049102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:05.724024Z","time spent":"325.070342ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-18T12:29:06.04927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.328046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:29:06.049314Z","caller":"traceutil/trace.go:171","msg":"trace[932479527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"291.408208ms","start":"2026-06-18T12:29:05.757895Z","end":"2026-06-18T12:29:06.049303Z","steps":["trace[932479527] 'agreement among raft nodes before linearized reading' (duration: 291.348867ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:06.049465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.102178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-18T12:29:06.04947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.876882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-18T12:29:06.049502Z","caller":"traceutil/trace.go:171","msg":"trace[935398066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"264.17779ms","start":"2026-06-18T12:29:05.785316Z","end":"2026-06-18T12:29:06.049494Z","steps":["trace[935398066] 'agreement among raft nodes before linearized reading' (duration: 264.117478ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:29:06.049518Z","caller":"traceutil/trace.go:171","msg":"trace[730694885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"263.949533ms","start":"2026-06-18T12:29:05.785555Z","end":"2026-06-18T12:29:06.049505Z","steps":["trace[730694885] 'agreement among raft nodes before linearized reading' (duration: 263.853861ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:29:11.229162Z","caller":"traceutil/trace.go:171","msg":"trace[1120787649] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"325.902323ms","start":"2026-06-18T12:29:10.903238Z","end":"2026-06-18T12:29:11.22914Z","steps":["trace[1120787649] 'process raft request' (duration: 325.777819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:11.229281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:10.903228Z","time spent":"325.995676ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T12:29:12.839888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788184306482509470,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:29:13.340817Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788184306482509470,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:29:13.841181Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788184306482509470,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:29:14.342141Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788184306482509470,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:29:14.368333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.265862077s","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-06-18T12:29:14.368347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.278352799s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-18T12:29:14.368393Z","caller":"traceutil/trace.go:171","msg":"trace[1245854364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"2.265948081s","start":"2026-06-18T12:29:12.102426Z","end":"2026-06-18T12:29:14.368374Z","steps":["trace[1245854364] 'range keys from in-memory index tree' (duration: 2.265714952s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:14.368436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:12.102416Z","time spent":"2.266010713s","remote":"127.0.0.1:48354","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-06-18T12:29:14.368438Z","caller":"traceutil/trace.go:171","msg":"trace[665454608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"2.278456693s","start":"2026-06-18T12:29:12.089958Z","end":"2026-06-18T12:29:14.368414Z","steps":["trace[665454608] 'range keys from in-memory index tree' (duration: 2.278182664s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:14.368762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.455141906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rh77n\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-06-18T12:29:14.36876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:12.089912Z","time spent":"2.278816457s","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-18T12:29:14.368804Z","caller":"traceutil/trace.go:171","msg":"trace[473528234] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rh77n; range_end:; response_count:1; response_revision:634; }","duration":"2.455211789s","start":"2026-06-18T12:29:11.913583Z","end":"2026-06-18T12:29:14.368795Z","steps":["trace[473528234] 'range keys from in-memory index tree' (duration: 2.455046903s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:14.368882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:11.913568Z","time spent":"2.455303742s","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-rh77n\" "} {"level":"warn","ts":"2026-06-18T12:29:14.368931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.278979562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-18T12:29:14.369009Z","caller":"traceutil/trace.go:171","msg":"trace[848036965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"2.279103557s","start":"2026-06-18T12:29:12.089887Z","end":"2026-06-18T12:29:14.368991Z","steps":["trace[848036965] 'range keys from in-memory index tree' (duration: 2.278807466s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:14.369055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:12.089868Z","time spent":"2.27917801s","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-18T12:29:14.610843Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.453665851s","expected-duration":"1s"} {"level":"info","ts":"2026-06-18T12:29:14.613478Z","caller":"traceutil/trace.go:171","msg":"trace[643589902] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"2.273811531s","start":"2026-06-18T12:29:12.339643Z","end":"2026-06-18T12:29:14.613454Z","steps":["trace[643589902] 'read index received' (duration: 2.271459836s)","trace[643589902] 'applied index is now lower than readState.Index' (duration: 2.350685ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:29:14.613593Z","caller":"traceutil/trace.go:171","msg":"trace[977331365] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"2.457655432s","start":"2026-06-18T12:29:12.155901Z","end":"2026-06-18T12:29:14.613556Z","steps":["trace[977331365] 'process raft request' (duration: 2.456031464s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:14.613656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.27402194s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-18T12:29:14.613737Z","caller":"traceutil/trace.go:171","msg":"trace[150871717] range","detail":"{range_begin:/registry/masterleases/199.19.213.229; range_end:; response_count:1; response_revision:635; }","duration":"2.274149684s","start":"2026-06-18T12:29:12.339567Z","end":"2026-06-18T12:29:14.613717Z","steps":["trace[150871717] 'agreement among raft nodes before linearized reading' (duration: 2.274002638s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:14.613753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:12.155883Z","time spent":"2.457786417s","remote":"127.0.0.1:48226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036101,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:29:14.613786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:12.339517Z","time spent":"2.274260467s","remote":"127.0.0.1:48158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.229\" "} {"level":"warn","ts":"2026-06-18T12:29:15.113642Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788184306482509477,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-18T12:29:15.506648Z","caller":"traceutil/trace.go:171","msg":"trace[642449466] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.132279478s","start":"2026-06-18T12:29:14.374333Z","end":"2026-06-18T12:29:15.506613Z","steps":["trace[642449466] 'process raft request' (duration: 1.132176815s)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:29:15.506649Z","caller":"traceutil/trace.go:171","msg":"trace[281573630] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.132655812s","start":"2026-06-18T12:29:14.373969Z","end":"2026-06-18T12:29:15.506625Z","steps":["trace[281573630] 'process raft request' (duration: 1.132462615s)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:29:15.506674Z","caller":"traceutil/trace.go:171","msg":"trace[636902848] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.132738465s","start":"2026-06-18T12:29:14.373915Z","end":"2026-06-18T12:29:15.506654Z","steps":["trace[636902848] 'process raft request' (duration: 1.069407812s)","trace[636902848] 'compare' (duration: 62.541134ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:29:15.507621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:14.373896Z","time spent":"1.133660549s","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:29:15.507655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:14.37417Z","time spent":"1.132536308s","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:29:15.508058Z","caller":"traceutil/trace.go:171","msg":"trace[1589951134] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.122162195s","start":"2026-06-18T12:29:14.385863Z","end":"2026-06-18T12:29:15.508025Z","steps":["trace[1589951134] 'process raft request' (duration: 1.12067709s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:15.508176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:14.385847Z","time spent":"1.122265299s","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:29:15.508242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:14.373946Z","time spent":"1.132770616s","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:29:15.508755Z","caller":"traceutil/trace.go:171","msg":"trace[1318095732] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.041535624s","start":"2026-06-18T12:29:14.467177Z","end":"2026-06-18T12:29:15.508713Z","steps":["trace[1318095732] 'process raft request' (duration: 1.040801717s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:15.508877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:14.467149Z","time spent":"1.041659069s","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:29:15.509878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:14.614944Z","time spent":"894.925531ms","remote":"127.0.0.1:48158","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-18T12:29:15.510227Z","caller":"traceutil/trace.go:171","msg":"trace[1528343775] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"824.79503ms","start":"2026-06-18T12:29:14.685411Z","end":"2026-06-18T12:29:15.510206Z","steps":["trace[1528343775] 'process raft request' (duration: 823.944699ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:15.510572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:14.685394Z","time spent":"824.861692ms","remote":"127.0.0.1:48312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T12:29:15.511371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.440218789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:29:15.511452Z","caller":"traceutil/trace.go:171","msg":"trace[1128055098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"1.440311043s","start":"2026-06-18T12:29:14.071108Z","end":"2026-06-18T12:29:15.511419Z","steps":["trace[1128055098] 'agreement among raft nodes before linearized reading' (duration: 1.43940651s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:15.511513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:14.071093Z","time spent":"1.440406297s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T12:29:15.511884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.215844483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-18T12:29:15.511972Z","caller":"traceutil/trace.go:171","msg":"trace[1291981010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"2.215970047s","start":"2026-06-18T12:29:13.295984Z","end":"2026-06-18T12:29:15.511954Z","steps":["trace[1291981010] 'agreement among raft nodes before linearized reading' (duration: 2.214773354s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:15.512012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:13.295964Z","time spent":"2.21603298s","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-18T12:29:15.512086Z","caller":"traceutil/trace.go:171","msg":"trace[1119721815] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:662; }","duration":"896.091594ms","start":"2026-06-18T12:29:14.61356Z","end":"2026-06-18T12:29:15.509651Z","steps":["trace[1119721815] 'read index received' (duration: 829.775622ms)","trace[1119721815] 'applied index is now lower than readState.Index' (duration: 66.232189ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:29:16.044794Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788184306482509484,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:29:16.545743Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788184306482509484,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:29:17.046194Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788184306482509484,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:29:17.54419Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.00056768s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-18T12:29:17.549949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00032177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-18T12:29:17.550046Z","caller":"traceutil/trace.go:171","msg":"trace[67060864] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000461186s","start":"2026-06-18T12:29:15.549569Z","end":"2026-06-18T12:29:17.55003Z","steps":["trace[67060864] 'agreement among raft nodes before linearized reading' (duration: 2.000339041s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:17.550082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:15.549559Z","time spent":"2.000515478s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T12:29:17.667306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.760378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:29:17.667431Z","caller":"traceutil/trace.go:171","msg":"trace[966526615] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"2.151988896s","start":"2026-06-18T12:29:15.51542Z","end":"2026-06-18T12:29:17.667409Z","steps":["trace[966526615] 'process raft request' (duration: 2.02903864s)","trace[966526615] 'compare' (duration: 122.653154ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:29:17.667513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:15.51541Z","time spent":"2.152060529s","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:29:17.676246Z","caller":"traceutil/trace.go:171","msg":"trace[1376797574] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"953.405044ms","start":"2026-06-18T12:29:16.722797Z","end":"2026-06-18T12:29:17.676202Z","steps":["trace[1376797574] 'process raft request' (duration: 953.104721ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:17.676415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:16.722777Z","time spent":"953.546218ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:29:17.684662Z","caller":"traceutil/trace.go:171","msg":"trace[436355132] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:670; }","duration":"2.14083899s","start":"2026-06-18T12:29:15.543787Z","end":"2026-06-18T12:29:17.684626Z","steps":["trace[436355132] 'read index received' (duration: 2.000673395s)","trace[436355132] 'applied index is now lower than readState.Index' (duration: 140.164395ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:29:17.684845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.16254765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:29:17.684891Z","caller":"traceutil/trace.go:171","msg":"trace[698673627] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:644; }","duration":"2.162647464s","start":"2026-06-18T12:29:15.522231Z","end":"2026-06-18T12:29:17.684878Z","steps":["trace[698673627] 'agreement among raft nodes before linearized reading' (duration: 2.1625473s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:17.684944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:15.522217Z","time spent":"2.162707425s","remote":"127.0.0.1:48440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":28,"request content":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-06-18T12:29:17.68568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.139416459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-06-18T12:29:17.685688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.101152ms","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-06-18T12:29:17.685718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.171079885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rh77n\" ","response":"range_response_count:1 size:17149"} {"level":"warn","ts":"2026-06-18T12:29:17.685761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.241388ms","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-06-18T12:29:17.685788Z","caller":"traceutil/trace.go:171","msg":"trace[855169221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:644; }","duration":"169.239127ms","start":"2026-06-18T12:29:17.516522Z","end":"2026-06-18T12:29:17.685761Z","steps":["trace[855169221] 'agreement among raft nodes before linearized reading' (duration: 168.495679ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:29:17.685809Z","caller":"traceutil/trace.go:171","msg":"trace[1842398259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:644; }","duration":"169.313641ms","start":"2026-06-18T12:29:17.516484Z","end":"2026-06-18T12:29:17.685797Z","steps":["trace[1842398259] 'agreement among raft nodes before linearized reading' (duration: 169.162315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:17.685817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.007177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:29:17.685816Z","caller":"traceutil/trace.go:171","msg":"trace[94843806] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rh77n; range_end:; response_count:1; response_revision:644; }","duration":"2.17120948s","start":"2026-06-18T12:29:15.514583Z","end":"2026-06-18T12:29:17.685792Z","steps":["trace[94843806] 'agreement among raft nodes before linearized reading' (duration: 2.170301736s)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:29:17.685843Z","caller":"traceutil/trace.go:171","msg":"trace[1255560842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"135.056169ms","start":"2026-06-18T12:29:17.550779Z","end":"2026-06-18T12:29:17.685836Z","steps":["trace[1255560842] 'agreement among raft nodes before linearized reading' (duration: 135.014067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:17.685873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:15.514571Z","time spent":"2.171292323s","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17172,"request content":"key:\"/registry/pods/kube-system/cilium-rh77n\" "} {"level":"warn","ts":"2026-06-18T12:29:17.685864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.80237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-18T12:29:17.685753Z","caller":"traceutil/trace.go:171","msg":"trace[1257747247] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:644; }","duration":"2.139535993s","start":"2026-06-18T12:29:15.546199Z","end":"2026-06-18T12:29:17.685735Z","steps":["trace[1257747247] 'agreement among raft nodes before linearized reading' (duration: 2.138694541s)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:29:17.685918Z","caller":"traceutil/trace.go:171","msg":"trace[1300743246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"169.896993ms","start":"2026-06-18T12:29:17.516003Z","end":"2026-06-18T12:29:17.6859Z","steps":["trace[1300743246] 'agreement among raft nodes before linearized reading' (duration: 169.720847ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:17.685955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:15.546183Z","time spent":"2.139755472s","remote":"127.0.0.1:48290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-06-18T12:29:19.637085Z","caller":"traceutil/trace.go:171","msg":"trace[719870799] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"385.334126ms","start":"2026-06-18T12:29:19.25171Z","end":"2026-06-18T12:29:19.637045Z","steps":["trace[719870799] 'process raft request' (duration: 385.20291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:19.637239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:19.251687Z","time spent":"385.483962ms","remote":"127.0.0.1:48168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T12:29:19.650944Z","caller":"traceutil/trace.go:171","msg":"trace[1210501021] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"199.946431ms","start":"2026-06-18T12:29:19.450969Z","end":"2026-06-18T12:29:19.650916Z","steps":["trace[1210501021] 'process raft request' (duration: 199.569996ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:29:19.651194Z","caller":"traceutil/trace.go:171","msg":"trace[2110335774] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"120.632925ms","start":"2026-06-18T12:29:19.530543Z","end":"2026-06-18T12:29:19.651176Z","steps":["trace[2110335774] 'process raft request' (duration: 120.222638ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:29:22.429069Z","caller":"traceutil/trace.go:171","msg":"trace[1520905518] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"173.498402ms","start":"2026-06-18T12:29:22.255535Z","end":"2026-06-18T12:29:22.429033Z","steps":["trace[1520905518] 'process raft request' (duration: 173.317394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:33.096946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.047637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T12:29:33.097033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.120613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-18T12:29:33.097049Z","caller":"traceutil/trace.go:171","msg":"trace[64689843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"339.201614ms","start":"2026-06-18T12:29:32.757826Z","end":"2026-06-18T12:29:33.097027Z","steps":["trace[64689843] 'range keys from in-memory index tree' (duration: 338.91507ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:29:33.097078Z","caller":"traceutil/trace.go:171","msg":"trace[1702566668] range","detail":"{range_begin:/registry/masterleases/199.19.213.229; range_end:; response_count:1; response_revision:816; }","duration":"757.211637ms","start":"2026-06-18T12:29:32.339854Z","end":"2026-06-18T12:29:33.097066Z","steps":["trace[1702566668] 'range keys from in-memory index tree' (duration: 756.926284ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:33.097105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:32.757801Z","time spent":"339.290878ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T12:29:33.097127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:32.339819Z","time spent":"757.292661ms","remote":"127.0.0.1:48158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.229\" "} {"level":"info","ts":"2026-06-18T12:30:00.879401Z","caller":"traceutil/trace.go:171","msg":"trace[1713700247] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"117.493425ms","start":"2026-06-18T12:30:00.761887Z","end":"2026-06-18T12:30:00.87938Z","steps":["trace[1713700247] 'process raft request' (duration: 117.379278ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:18.218618Z","caller":"traceutil/trace.go:171","msg":"trace[191344713] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"178.478973ms","start":"2026-06-18T12:30:18.040115Z","end":"2026-06-18T12:30:18.218594Z","steps":["trace[191344713] 'process raft request' (duration: 178.326453ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:18.219045Z","caller":"traceutil/trace.go:171","msg":"trace[1755174357] linearizableReadLoop","detail":"{readStateIndex:1406; appliedIndex:1406; }","duration":"131.652362ms","start":"2026-06-18T12:30:18.087365Z","end":"2026-06-18T12:30:18.219017Z","steps":["trace[1755174357] 'read index received' (duration: 131.643552ms)","trace[1755174357] 'applied index is now lower than readState.Index' (duration: 6.76µs)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:30:18.219345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.332637ms","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-06-18T12:30:18.219346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.964382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-18T12:30:18.219407Z","caller":"traceutil/trace.go:171","msg":"trace[2036284954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1359; }","duration":"132.067819ms","start":"2026-06-18T12:30:18.087325Z","end":"2026-06-18T12:30:18.219393Z","steps":["trace[2036284954] 'agreement among raft nodes before linearized reading' (duration: 131.896208ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:18.219391Z","caller":"traceutil/trace.go:171","msg":"trace[864777918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1359; }","duration":"127.444274ms","start":"2026-06-18T12:30:18.091933Z","end":"2026-06-18T12:30:18.219377Z","steps":["trace[864777918] 'agreement among raft nodes before linearized reading' (duration: 127.245011ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:18.219543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.511672ms","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-06-18T12:30:18.219574Z","caller":"traceutil/trace.go:171","msg":"trace[1453754589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1359; }","duration":"126.583557ms","start":"2026-06-18T12:30:18.092982Z","end":"2026-06-18T12:30:18.219566Z","steps":["trace[1453754589] 'agreement among raft nodes before linearized reading' (duration: 126.497051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:49.622479Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788184306482511905,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:30:50.151901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.60947886s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1f4fe0f-5512-481b-ab94-2b888005f46e\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-18T12:30:50.151993Z","caller":"traceutil/trace.go:171","msg":"trace[1815036619] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b1f4fe0f-5512-481b-ab94-2b888005f46e; range_end:; response_count:1; response_revision:1883; }","duration":"1.609604249s","start":"2026-06-18T12:30:48.542373Z","end":"2026-06-18T12:30:50.151978Z","steps":["trace[1815036619] 'range keys from in-memory index tree' (duration: 1.609356921s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:50.152032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:48.542359Z","time spent":"1.609656073s","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1f4fe0f-5512-481b-ab94-2b888005f46e\" "} {"level":"warn","ts":"2026-06-18T12:30:50.152329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.428422396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1f4fe0f-5512-481b-ab94-2b888005f46e\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-06-18T12:30:50.152347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120803111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T12:30:50.152378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237681138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-06-18T12:30:50.152408Z","caller":"traceutil/trace.go:171","msg":"trace[290628606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1883; }","duration":"1.120910709s","start":"2026-06-18T12:30:49.031483Z","end":"2026-06-18T12:30:50.152394Z","steps":["trace[290628606] 'range keys from in-memory index tree' (duration: 1.120665612s)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:50.152407Z","caller":"traceutil/trace.go:171","msg":"trace[2119623340] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1883; }","duration":"1.237748522s","start":"2026-06-18T12:30:48.914649Z","end":"2026-06-18T12:30:50.152398Z","steps":["trace[2119623340] 'range keys from in-memory index tree' (duration: 1.237567069s)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:50.152404Z","caller":"traceutil/trace.go:171","msg":"trace[269004917] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b1f4fe0f-5512-481b-ab94-2b888005f46e; range_end:; response_count:1; response_revision:1883; }","duration":"1.428548215s","start":"2026-06-18T12:30:48.723838Z","end":"2026-06-18T12:30:50.152386Z","steps":["trace[269004917] 'range keys from in-memory index tree' (duration: 1.42820667s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:50.152434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:48.914628Z","time spent":"1.237801487s","remote":"127.0.0.1:45224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-18T12:30:50.152439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:49.031461Z","time spent":"1.120968204s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T12:30:50.152453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:48.72382Z","time spent":"1.428624671s","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1f4fe0f-5512-481b-ab94-2b888005f46e\" "} {"level":"warn","ts":"2026-06-18T12:30:50.152583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.246796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:30:50.152825Z","caller":"traceutil/trace.go:171","msg":"trace[2034535685] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1958; }","duration":"1.030845125s","start":"2026-06-18T12:30:49.121962Z","end":"2026-06-18T12:30:50.152808Z","steps":["trace[2034535685] 'read index received' (duration: 731.304646ms)","trace[2034535685] 'applied index is now lower than readState.Index' (duration: 299.539429ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:30:50.15292Z","caller":"traceutil/trace.go:171","msg":"trace[1141552108] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"1.084054592s","start":"2026-06-18T12:30:49.068847Z","end":"2026-06-18T12:30:50.152901Z","steps":["trace[1141552108] 'process raft request' (duration: 784.408135ms)","trace[1141552108] 'compare' (duration: 298.477351ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:30:50.152994Z","caller":"traceutil/trace.go:171","msg":"trace[845388273] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"1.053922063s","start":"2026-06-18T12:30:49.099057Z","end":"2026-06-18T12:30:50.152979Z","steps":["trace[845388273] 'process raft request' (duration: 1.053616349s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:50.152999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:49.068825Z","time spent":"1.084127707s","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:30:50.153085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:49.099028Z","time spent":"1.054001828s","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:30:50.153343Z","caller":"traceutil/trace.go:171","msg":"trace[1894627507] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"708.079424ms","start":"2026-06-18T12:30:49.445246Z","end":"2026-06-18T12:30:50.153325Z","steps":["trace[1894627507] 'process raft request' (duration: 707.514693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:50.153444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:49.445222Z","time spent":"708.176441ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:30:50.153708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031739431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-18T12:30:50.153761Z","caller":"traceutil/trace.go:171","msg":"trace[149508647] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1886; }","duration":"1.031835437s","start":"2026-06-18T12:30:49.121912Z","end":"2026-06-18T12:30:50.153747Z","steps":["trace[149508647] 'agreement among raft nodes before linearized reading' (duration: 1.031696487s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:50.153801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:49.121891Z","time spent":"1.031900091s","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-18T12:30:50.168845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.62711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:30:50.168936Z","caller":"traceutil/trace.go:171","msg":"trace[1095494180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1887; }","duration":"413.723417ms","start":"2026-06-18T12:30:49.75519Z","end":"2026-06-18T12:30:50.168913Z","steps":["trace[1095494180] 'agreement among raft nodes before linearized reading' (duration: 413.578727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:50.168839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.426153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-18T12:30:50.168955Z","caller":"traceutil/trace.go:171","msg":"trace[1508230170] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"137.754472ms","start":"2026-06-18T12:30:50.031167Z","end":"2026-06-18T12:30:50.168922Z","steps":["trace[1508230170] 'process raft request' (duration: 137.345662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:50.168989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:49.755152Z","time spent":"413.826025ms","remote":"127.0.0.1:48122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-18T12:30:50.169024Z","caller":"traceutil/trace.go:171","msg":"trace[1413697925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1887; }","duration":"581.650329ms","start":"2026-06-18T12:30:49.587356Z","end":"2026-06-18T12:30:50.169006Z","steps":["trace[1413697925] 'agreement among raft nodes before linearized reading' (duration: 581.313505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:50.169087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:49.587336Z","time spent":"581.733916ms","remote":"127.0.0.1:48354","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":"info","ts":"2026-06-18T12:30:50.39369Z","caller":"traceutil/trace.go:171","msg":"trace[1451391885] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"235.598488ms","start":"2026-06-18T12:30:50.158072Z","end":"2026-06-18T12:30:50.39367Z","steps":["trace[1451391885] 'process raft request' (duration: 228.742498ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:50.393793Z","caller":"traceutil/trace.go:171","msg":"trace[11304860] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"232.753822ms","start":"2026-06-18T12:30:50.161021Z","end":"2026-06-18T12:30:50.393775Z","steps":["trace[11304860] 'process raft request' (duration: 232.590329ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:50.860323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.601412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:30:50.860512Z","caller":"traceutil/trace.go:171","msg":"trace[1806493641] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1962; }","duration":"477.436659ms","start":"2026-06-18T12:30:50.383059Z","end":"2026-06-18T12:30:50.860496Z","steps":["trace[1806493641] 'read index received' (duration: 3.742602ms)","trace[1806493641] 'applied index is now lower than readState.Index' (duration: 473.692417ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:30:50.860797Z","caller":"traceutil/trace.go:171","msg":"trace[826957993] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"687.986392ms","start":"2026-06-18T12:30:50.172791Z","end":"2026-06-18T12:30:50.860777Z","steps":["trace[826957993] 'process raft request' (duration: 517.756085ms)","trace[826957993] 'compare' (duration: 169.368235ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:30:50.860872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:50.172764Z","time spent":"688.072388ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:30:50.861132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.018178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T12:30:50.861153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.081235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-18T12:30:50.861234Z","caller":"traceutil/trace.go:171","msg":"trace[267006895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1890; }","duration":"478.207985ms","start":"2026-06-18T12:30:50.383008Z","end":"2026-06-18T12:30:50.861216Z","steps":["trace[267006895] 'agreement among raft nodes before linearized reading' (duration: 477.992009ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:50.861294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:50.38299Z","time spent":"478.295871ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-18T12:30:50.861349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.337653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-18T12:30:50.86118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.929547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:30:50.861396Z","caller":"traceutil/trace.go:171","msg":"trace[2102483933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1890; }","duration":"428.424639ms","start":"2026-06-18T12:30:50.43296Z","end":"2026-06-18T12:30:50.861385Z","steps":["trace[2102483933] 'agreement among raft nodes before linearized reading' (duration: 428.286569ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:50.861409Z","caller":"traceutil/trace.go:171","msg":"trace[38291139] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1890; }","duration":"394.195796ms","start":"2026-06-18T12:30:50.467201Z","end":"2026-06-18T12:30:50.861397Z","steps":["trace[38291139] 'agreement among raft nodes before linearized reading' (duration: 393.926967ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:50.86143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:50.432919Z","time spent":"428.502785ms","remote":"127.0.0.1:48354","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-06-18T12:30:50.861445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:50.467184Z","time spent":"394.248311ms","remote":"127.0.0.1:53996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-18T12:30:50.861553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.621875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-18T12:30:50.861567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.748409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-18T12:30:50.861587Z","caller":"traceutil/trace.go:171","msg":"trace[1662590296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1890; }","duration":"428.691699ms","start":"2026-06-18T12:30:50.432886Z","end":"2026-06-18T12:30:50.861578Z","steps":["trace[1662590296] 'agreement among raft nodes before linearized reading' (duration: 428.613274ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:50.861603Z","caller":"traceutil/trace.go:171","msg":"trace[33520986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1890; }","duration":"405.816973ms","start":"2026-06-18T12:30:50.455776Z","end":"2026-06-18T12:30:50.861593Z","steps":["trace[33520986] 'agreement among raft nodes before linearized reading' (duration: 405.718226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:50.861634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:50.432863Z","time spent":"428.743213ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-18T12:30:50.861638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:50.455759Z","time spent":"405.870218ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-18T12:30:50.861237Z","caller":"traceutil/trace.go:171","msg":"trace[1668321198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1890; }","duration":"431.130487ms","start":"2026-06-18T12:30:50.430068Z","end":"2026-06-18T12:30:50.861198Z","steps":["trace[1668321198] 'agreement among raft nodes before linearized reading' (duration: 431.028399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:50.861778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:50.430049Z","time spent":"431.7176ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T12:30:50.86158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.690665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:30:50.862013Z","caller":"traceutil/trace.go:171","msg":"trace[229493823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1890; }","duration":"105.160849ms","start":"2026-06-18T12:30:50.756842Z","end":"2026-06-18T12:30:50.862002Z","steps":["trace[229493823] 'agreement among raft nodes before linearized reading' (duration: 104.707666ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:58.365265Z","caller":"traceutil/trace.go:171","msg":"trace[807838105] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"185.364497ms","start":"2026-06-18T12:30:58.179853Z","end":"2026-06-18T12:30:58.365218Z","steps":["trace[807838105] 'process raft request' (duration: 185.177643ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:58.454571Z","caller":"traceutil/trace.go:171","msg":"trace[1524265834] linearizableReadLoop","detail":"{readStateIndex:2047; appliedIndex:2044; }","duration":"244.265409ms","start":"2026-06-18T12:30:58.210276Z","end":"2026-06-18T12:30:58.454541Z","steps":["trace[1524265834] 'read index received' (duration: 154.815404ms)","trace[1524265834] 'applied index is now lower than readState.Index' (duration: 89.447524ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:30:58.454726Z","caller":"traceutil/trace.go:171","msg":"trace[243433038] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"265.674645ms","start":"2026-06-18T12:30:58.189004Z","end":"2026-06-18T12:30:58.454678Z","steps":["trace[243433038] 'process raft request' (duration: 265.33503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:58.454838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.5617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-06-18T12:30:58.454878Z","caller":"traceutil/trace.go:171","msg":"trace[2096892334] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1970; }","duration":"244.707251ms","start":"2026-06-18T12:30:58.210157Z","end":"2026-06-18T12:30:58.454865Z","steps":["trace[2096892334] 'agreement among raft nodes before linearized reading' (duration: 244.528148ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:58.454919Z","caller":"traceutil/trace.go:171","msg":"trace[1842606526] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"270.811935ms","start":"2026-06-18T12:30:58.183599Z","end":"2026-06-18T12:30:58.454411Z","steps":["trace[1842606526] 'process raft request' (duration: 269.459854ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:59.057124Z","caller":"traceutil/trace.go:171","msg":"trace[592782143] linearizableReadLoop","detail":"{readStateIndex:2050; appliedIndex:2049; }","duration":"144.938981ms","start":"2026-06-18T12:30:58.912153Z","end":"2026-06-18T12:30:59.057092Z","steps":["trace[592782143] 'read index received' (duration: 144.752738ms)","trace[592782143] 'applied index is now lower than readState.Index' (duration: 184.833µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:30:59.057316Z","caller":"traceutil/trace.go:171","msg":"trace[1953030149] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"153.779956ms","start":"2026-06-18T12:30:58.90352Z","end":"2026-06-18T12:30:59.0573Z","steps":["trace[1953030149] 'process raft request' (duration: 153.320022ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:59.057765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.59314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-18T12:30:59.057876Z","caller":"traceutil/trace.go:171","msg":"trace[1803269859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1973; }","duration":"145.724809ms","start":"2026-06-18T12:30:58.912119Z","end":"2026-06-18T12:30:59.057843Z","steps":["trace[1803269859] 'agreement among raft nodes before linearized reading' (duration: 145.530215ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:59.0576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.400465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-18T12:30:59.058058Z","caller":"traceutil/trace.go:171","msg":"trace[2110573154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1973; }","duration":"145.901133ms","start":"2026-06-18T12:30:58.912139Z","end":"2026-06-18T12:30:59.05804Z","steps":["trace[2110573154] 'agreement among raft nodes before linearized reading' (duration: 145.272046ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:59.058071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.188151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-18T12:30:59.058139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.745703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-18T12:30:59.058234Z","caller":"traceutil/trace.go:171","msg":"trace[1205016523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1973; }","duration":"138.379695ms","start":"2026-06-18T12:30:58.919829Z","end":"2026-06-18T12:30:59.058208Z","steps":["trace[1205016523] 'agreement among raft nodes before linearized reading' (duration: 137.930592ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:59.058266Z","caller":"traceutil/trace.go:171","msg":"trace[283888886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1973; }","duration":"143.916465ms","start":"2026-06-18T12:30:58.914313Z","end":"2026-06-18T12:30:59.05823Z","steps":["trace[283888886] 'agreement among raft nodes before linearized reading' (duration: 143.551798ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:59.845573Z","caller":"traceutil/trace.go:171","msg":"trace[2006762732] linearizableReadLoop","detail":"{readStateIndex:2055; appliedIndex:2054; }","duration":"224.754356ms","start":"2026-06-18T12:30:59.620787Z","end":"2026-06-18T12:30:59.845541Z","steps":["trace[2006762732] 'read index received' (duration: 224.68539ms)","trace[2006762732] 'applied index is now lower than readState.Index' (duration: 67.535µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:30:59.845713Z","caller":"traceutil/trace.go:171","msg":"trace[257169387] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"230.21142ms","start":"2026-06-18T12:30:59.615448Z","end":"2026-06-18T12:30:59.845659Z","steps":["trace[257169387] 'process raft request' (duration: 229.927499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:59.845936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.128954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-06-18T12:30:59.84602Z","caller":"traceutil/trace.go:171","msg":"trace[384658852] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1978; }","duration":"225.278966ms","start":"2026-06-18T12:30:59.620723Z","end":"2026-06-18T12:30:59.846002Z","steps":["trace[384658852] 'agreement among raft nodes before linearized reading' (duration: 224.971832ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:59.856428Z","caller":"traceutil/trace.go:171","msg":"trace[2085135671] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"231.349635ms","start":"2026-06-18T12:30:59.625047Z","end":"2026-06-18T12:30:59.856397Z","steps":["trace[2085135671] 'process raft request' (duration: 231.151171ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:31:04.308902Z","caller":"traceutil/trace.go:171","msg":"trace[46498612] linearizableReadLoop","detail":"{readStateIndex:2082; appliedIndex:2081; }","duration":"200.511854ms","start":"2026-06-18T12:31:04.108363Z","end":"2026-06-18T12:31:04.308875Z","steps":["trace[46498612] 'read index received' (duration: 200.288696ms)","trace[46498612] 'applied index is now lower than readState.Index' (duration: 221.768µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:31:04.308982Z","caller":"traceutil/trace.go:171","msg":"trace[957575350] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"413.753747ms","start":"2026-06-18T12:31:03.895191Z","end":"2026-06-18T12:31:04.308945Z","steps":["trace[957575350] 'process raft request' (duration: 413.467985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:04.309103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.731281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T12:31:04.309145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:03.895167Z","time spent":"413.890408ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5583,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:31:04.309182Z","caller":"traceutil/trace.go:171","msg":"trace[148278791] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2004; }","duration":"200.890523ms","start":"2026-06-18T12:31:04.108274Z","end":"2026-06-18T12:31:04.309164Z","steps":["trace[148278791] 'agreement among raft nodes before linearized reading' (duration: 200.747262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:10.811593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.457694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:31:10.811667Z","caller":"traceutil/trace.go:171","msg":"trace[1115568815] linearizableReadLoop","detail":"{readStateIndex:2134; appliedIndex:2133; }","duration":"137.333406ms","start":"2026-06-18T12:31:10.674323Z","end":"2026-06-18T12:31:10.811656Z","steps":["trace[1115568815] 'read index received' (duration: 37.833µs)","trace[1115568815] 'applied index is now lower than readState.Index' (duration: 137.294893ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:31:10.811746Z","caller":"traceutil/trace.go:171","msg":"trace[666756779] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"226.326338ms","start":"2026-06-18T12:31:10.585413Z","end":"2026-06-18T12:31:10.81174Z","steps":["trace[666756779] 'process raft request' (duration: 44.657568ms)","trace[666756779] 'compare' (duration: 181.053103ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:31:10.812037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.712626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:11253"} {"level":"info","ts":"2026-06-18T12:31:10.812057Z","caller":"traceutil/trace.go:171","msg":"trace[1825356006] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:2055; }","duration":"137.759899ms","start":"2026-06-18T12:31:10.674292Z","end":"2026-06-18T12:31:10.812052Z","steps":["trace[1825356006] 'agreement among raft nodes before linearized reading' (duration: 137.6441ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:31:25.076891Z","caller":"traceutil/trace.go:171","msg":"trace[1424542040] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"195.587022ms","start":"2026-06-18T12:31:24.881281Z","end":"2026-06-18T12:31:25.076868Z","steps":["trace[1424542040] 'process raft request' (duration: 195.454862ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:25.596127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.293334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:31:25.59623Z","caller":"traceutil/trace.go:171","msg":"trace[1166456044] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2134; }","duration":"299.409323ms","start":"2026-06-18T12:31:25.296799Z","end":"2026-06-18T12:31:25.596208Z","steps":["trace[1166456044] 'count revisions from in-memory index tree' (duration: 299.2485ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:25.596498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.966756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-18T12:31:25.596607Z","caller":"traceutil/trace.go:171","msg":"trace[750538394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2134; }","duration":"408.081134ms","start":"2026-06-18T12:31:25.188461Z","end":"2026-06-18T12:31:25.596542Z","steps":["trace[750538394] 'range keys from in-memory index tree' (duration: 407.728617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:25.596655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.788152ms","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-06-18T12:31:25.596661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.348529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-18T12:31:25.596715Z","caller":"traceutil/trace.go:171","msg":"trace[582179888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2134; }","duration":"403.870299ms","start":"2026-06-18T12:31:25.192832Z","end":"2026-06-18T12:31:25.596702Z","steps":["trace[582179888] 'range keys from in-memory index tree' (duration: 403.671604ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:31:25.596719Z","caller":"traceutil/trace.go:171","msg":"trace[356159667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2134; }","duration":"403.417454ms","start":"2026-06-18T12:31:25.193288Z","end":"2026-06-18T12:31:25.596705Z","steps":["trace[356159667] 'range keys from in-memory index tree' (duration: 403.262972ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:25.596749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:25.192826Z","time spent":"403.914233ms","remote":"127.0.0.1:48354","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-06-18T12:31:25.59675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:25.193282Z","time spent":"403.461248ms","remote":"127.0.0.1:48354","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-06-18T12:31:25.596704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:25.188436Z","time spent":"408.220195ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-18T12:31:25.596858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.353096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-18T12:31:25.596964Z","caller":"traceutil/trace.go:171","msg":"trace[510426238] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2134; }","duration":"408.509237ms","start":"2026-06-18T12:31:25.188436Z","end":"2026-06-18T12:31:25.596945Z","steps":["trace[510426238] 'range keys from in-memory index tree' (duration: 408.15557ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:25.596969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.502238ms","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-06-18T12:31:25.597049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:25.188413Z","time spent":"408.617556ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-18T12:31:25.597071Z","caller":"traceutil/trace.go:171","msg":"trace[1695971881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2134; }","duration":"404.65425ms","start":"2026-06-18T12:31:25.192399Z","end":"2026-06-18T12:31:25.597053Z","steps":["trace[1695971881] 'range keys from in-memory index tree' (duration: 404.327445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:25.597142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:25.192393Z","time spent":"404.711514ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-18T12:31:25.596996Z","caller":"traceutil/trace.go:171","msg":"trace[391818497] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"263.308304ms","start":"2026-06-18T12:31:25.333674Z","end":"2026-06-18T12:31:25.596983Z","steps":["trace[391818497] 'process raft request' (duration: 252.11554ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:25.966282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.233353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:31:25.966913Z","caller":"traceutil/trace.go:171","msg":"trace[308344504] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"366.035697ms","start":"2026-06-18T12:31:25.600857Z","end":"2026-06-18T12:31:25.966892Z","steps":["trace[308344504] 'process raft request' (duration: 365.917468ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:31:25.966984Z","caller":"traceutil/trace.go:171","msg":"trace[206398501] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"366.218651ms","start":"2026-06-18T12:31:25.600652Z","end":"2026-06-18T12:31:25.96687Z","steps":["trace[206398501] 'process raft request' (duration: 230.305375ms)","trace[206398501] 'compare' (duration: 135.016037ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:31:25.967231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:25.600626Z","time spent":"366.490982ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:31:25.967233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:25.60084Z","time spent":"366.2148ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:31:25.967419Z","caller":"traceutil/trace.go:171","msg":"trace[1252616972] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"366.155937ms","start":"2026-06-18T12:31:25.601224Z","end":"2026-06-18T12:31:25.96738Z","steps":["trace[1252616972] 'process raft request' (duration: 365.635576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:25.967636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:25.601215Z","time spent":"366.287447ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:31:25.967975Z","caller":"traceutil/trace.go:171","msg":"trace[1835618337] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"366.090022ms","start":"2026-06-18T12:31:25.601844Z","end":"2026-06-18T12:31:25.967934Z","steps":["trace[1835618337] 'process raft request' (duration: 365.232085ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:31:25.968234Z","caller":"traceutil/trace.go:171","msg":"trace[1837442595] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"365.980653ms","start":"2026-06-18T12:31:25.60222Z","end":"2026-06-18T12:31:25.968201Z","steps":["trace[1837442595] 'process raft request' (duration: 365.668889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:25.968366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:25.602189Z","time spent":"366.108353ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:31:25.968511Z","caller":"traceutil/trace.go:171","msg":"trace[125797320] linearizableReadLoop","detail":"{readStateIndex:2222; appliedIndex:2217; }","duration":"306.464237ms","start":"2026-06-18T12:31:25.661683Z","end":"2026-06-18T12:31:25.968148Z","steps":["trace[125797320] 'read index received' (duration: 169.345068ms)","trace[125797320] 'applied index is now lower than readState.Index' (duration: 137.117789ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:31:25.968606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.922373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-06-18T12:31:25.968642Z","caller":"traceutil/trace.go:171","msg":"trace[60860405] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2140; }","duration":"306.987108ms","start":"2026-06-18T12:31:25.661646Z","end":"2026-06-18T12:31:25.968633Z","steps":["trace[60860405] 'agreement among raft nodes before linearized reading' (duration: 306.914682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:25.968672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:25.661632Z","time spent":"307.034071ms","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-06-18T12:31:25.969147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.772008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-06-18T12:31:25.969188Z","caller":"traceutil/trace.go:171","msg":"trace[703534349] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2140; }","duration":"278.837643ms","start":"2026-06-18T12:31:25.690339Z","end":"2026-06-18T12:31:25.969176Z","steps":["trace[703534349] 'agreement among raft nodes before linearized reading' (duration: 278.701783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:25.969353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.86227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:120346"} {"level":"warn","ts":"2026-06-18T12:31:25.968767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:25.601824Z","time spent":"366.189599ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:31:25.969399Z","caller":"traceutil/trace.go:171","msg":"trace[1633182087] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2140; }","duration":"305.950327ms","start":"2026-06-18T12:31:25.663437Z","end":"2026-06-18T12:31:25.969387Z","steps":["trace[1633182087] 'agreement among raft nodes before linearized reading' (duration: 305.577438ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:25.969444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:25.663419Z","time spent":"306.018272ms","remote":"127.0.0.1:48196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":120369,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-06-18T12:31:27.34513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.824509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:31:27.345274Z","caller":"traceutil/trace.go:171","msg":"trace[1325978620] linearizableReadLoop","detail":"{readStateIndex:2225; appliedIndex:2224; }","duration":"586.757549ms","start":"2026-06-18T12:31:26.758491Z","end":"2026-06-18T12:31:27.345249Z","steps":["trace[1325978620] 'read index received' (duration: 251.66491ms)","trace[1325978620] 'applied index is now lower than readState.Index' (duration: 335.090349ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:31:27.345394Z","caller":"traceutil/trace.go:171","msg":"trace[962088779] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"715.407287ms","start":"2026-06-18T12:31:26.629964Z","end":"2026-06-18T12:31:27.345371Z","steps":["trace[962088779] 'process raft request' (duration: 380.239501ms)","trace[962088779] 'compare' (duration: 333.843054ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:31:27.345484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:26.629939Z","time spent":"715.501224ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:31:27.347129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.456886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-06-18T12:31:27.347221Z","caller":"traceutil/trace.go:171","msg":"trace[1575608102] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2143; }","duration":"370.587835ms","start":"2026-06-18T12:31:26.976604Z","end":"2026-06-18T12:31:27.347192Z","steps":["trace[1575608102] 'agreement among raft nodes before linearized reading' (duration: 370.378869ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:27.347295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:26.976583Z","time spent":"370.697274ms","remote":"127.0.0.1:45224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-18T12:31:27.347328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.785767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:31:27.347482Z","caller":"traceutil/trace.go:171","msg":"trace[1208863168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2143; }","duration":"588.996073ms","start":"2026-06-18T12:31:26.758442Z","end":"2026-06-18T12:31:27.347438Z","steps":["trace[1208863168] 'agreement among raft nodes before linearized reading' (duration: 587.167641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:27.347531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:26.758421Z","time spent":"589.10064ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T12:31:27.347716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.674574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18ba2cd09fac090e\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-18T12:31:27.34779Z","caller":"traceutil/trace.go:171","msg":"trace[1485632113] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18ba2cd09fac090e; range_end:; response_count:1; response_revision:2143; }","duration":"231.775371ms","start":"2026-06-18T12:31:27.115993Z","end":"2026-06-18T12:31:27.347768Z","steps":["trace[1485632113] 'agreement among raft nodes before linearized reading' (duration: 231.100299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:27.348371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.425351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-18T12:31:27.348596Z","caller":"traceutil/trace.go:171","msg":"trace[686032352] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2143; }","duration":"268.205282ms","start":"2026-06-18T12:31:27.080364Z","end":"2026-06-18T12:31:27.34857Z","steps":["trace[686032352] 'agreement among raft nodes before linearized reading' (duration: 266.748499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:28.248963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.162628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:31:28.249089Z","caller":"traceutil/trace.go:171","msg":"trace[1893701162] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"893.644381ms","start":"2026-06-18T12:31:27.35542Z","end":"2026-06-18T12:31:28.249064Z","steps":["trace[1893701162] 'process raft request' (duration: 384.321461ms)","trace[1893701162] 'compare' (duration: 509.023257ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:31:28.249152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:27.355413Z","time spent":"893.709876ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:31:28.593398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.328566ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-18T12:31:28.593631Z","caller":"traceutil/trace.go:171","msg":"trace[1601656880] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2226; }","duration":"828.691994ms","start":"2026-06-18T12:31:27.764902Z","end":"2026-06-18T12:31:28.593594Z","steps":["trace[1601656880] 'read index received' (duration: 84.441657ms)","trace[1601656880] 'applied index is now lower than readState.Index' (duration: 744.244737ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:31:28.593796Z","caller":"traceutil/trace.go:171","msg":"trace[1298870056] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"258.541587ms","start":"2026-06-18T12:31:28.335218Z","end":"2026-06-18T12:31:28.59376Z","steps":["trace[1298870056] 'process raft request' (duration: 258.32361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:28.593961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.03163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-06-18T12:31:28.594022Z","caller":"traceutil/trace.go:171","msg":"trace[1840628980] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2145; }","duration":"829.112366ms","start":"2026-06-18T12:31:27.764896Z","end":"2026-06-18T12:31:28.594008Z","steps":["trace[1840628980] 'agreement among raft nodes before linearized reading' (duration: 828.807153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:28.594066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:27.764844Z","time spent":"829.208844ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12406,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-18T12:31:28.594544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.139342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-06-18T12:31:28.594605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.388326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-18T12:31:28.594678Z","caller":"traceutil/trace.go:171","msg":"trace[1749056822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2147; }","duration":"621.524937ms","start":"2026-06-18T12:31:27.97314Z","end":"2026-06-18T12:31:28.594665Z","steps":["trace[1749056822] 'agreement among raft nodes before linearized reading' (duration: 621.333062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:28.594551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.949461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-18T12:31:28.594782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.940488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-06-18T12:31:28.594833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.081861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-18T12:31:28.594852Z","caller":"traceutil/trace.go:171","msg":"trace[631339943] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2147; }","duration":"239.029865ms","start":"2026-06-18T12:31:28.355803Z","end":"2026-06-18T12:31:28.594833Z","steps":["trace[631339943] 'agreement among raft nodes before linearized reading' (duration: 238.878613ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:31:28.594609Z","caller":"traceutil/trace.go:171","msg":"trace[119788618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2147; }","duration":"623.268952ms","start":"2026-06-18T12:31:27.971327Z","end":"2026-06-18T12:31:28.594595Z","steps":["trace[119788618] 'agreement among raft nodes before linearized reading' (duration: 623.053625ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:31:28.594875Z","caller":"traceutil/trace.go:171","msg":"trace[729669885] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2147; }","duration":"621.144716ms","start":"2026-06-18T12:31:27.973721Z","end":"2026-06-18T12:31:28.594866Z","steps":["trace[729669885] 'agreement among raft nodes before linearized reading' (duration: 621.031398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:28.594912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:27.973705Z","time spent":"621.19879ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-18T12:31:28.594813Z","caller":"traceutil/trace.go:171","msg":"trace[1148243628] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2147; }","duration":"306.254615ms","start":"2026-06-18T12:31:28.28852Z","end":"2026-06-18T12:31:28.594775Z","steps":["trace[1148243628] 'agreement among raft nodes before linearized reading' (duration: 305.934941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:28.594906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:27.971308Z","time spent":"623.581225ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-18T12:31:28.595049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.153425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-18T12:31:28.595084Z","caller":"traceutil/trace.go:171","msg":"trace[165355938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2147; }","duration":"622.199909ms","start":"2026-06-18T12:31:27.972872Z","end":"2026-06-18T12:31:28.595072Z","steps":["trace[165355938] 'agreement among raft nodes before linearized reading' (duration: 622.08409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:28.595114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:27.972866Z","time spent":"622.242412ms","remote":"127.0.0.1:48354","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-06-18T12:31:28.594726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:27.973128Z","time spent":"621.587012ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-18T12:31:28.59499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:28.288502Z","time spent":"306.456801ms","remote":"127.0.0.1:48440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":23,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-06-18T12:31:28.595892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.9704ms","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-06-18T12:31:28.596021Z","caller":"traceutil/trace.go:171","msg":"trace[1985345891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2147; }","duration":"623.201217ms","start":"2026-06-18T12:31:27.972803Z","end":"2026-06-18T12:31:28.596004Z","steps":["trace[1985345891] 'agreement among raft nodes before linearized reading' (duration: 622.244723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:31:28.596128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:31:27.972782Z","time spent":"623.308246ms","remote":"127.0.0.1:48354","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-06-18T12:32:20.898728Z","caller":"traceutil/trace.go:171","msg":"trace[1184536253] transaction","detail":"{read_only:false; response_revision:2475; number_of_response:1; }","duration":"118.699688ms","start":"2026-06-18T12:32:20.779992Z","end":"2026-06-18T12:32:20.898691Z","steps":["trace[1184536253] 'process raft request' (duration: 117.020673ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:32:29.083554Z","caller":"traceutil/trace.go:171","msg":"trace[546362122] transaction","detail":"{read_only:false; response_revision:2577; number_of_response:1; }","duration":"143.226559ms","start":"2026-06-18T12:32:28.940143Z","end":"2026-06-18T12:32:29.083369Z","steps":["trace[546362122] 'process raft request' (duration: 143.157382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:32:29.083567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.877536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-18T12:32:29.083643Z","caller":"traceutil/trace.go:171","msg":"trace[846057445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2577; }","duration":"144.00316ms","start":"2026-06-18T12:32:28.939624Z","end":"2026-06-18T12:32:29.083627Z","steps":["trace[846057445] 'agreement among raft nodes before linearized reading' (duration: 143.756202ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:32:29.083744Z","caller":"traceutil/trace.go:171","msg":"trace[2047127992] linearizableReadLoop","detail":"{readStateIndex:2673; appliedIndex:2672; }","duration":"143.709884ms","start":"2026-06-18T12:32:28.939663Z","end":"2026-06-18T12:32:29.083373Z","steps":["trace[2047127992] 'read index received' (duration: 138.956745ms)","trace[2047127992] 'applied index is now lower than readState.Index' (duration: 4.74909ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:32:29.083994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.819817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-18T12:32:29.08404Z","caller":"traceutil/trace.go:171","msg":"trace[1235764447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2577; }","duration":"136.95672ms","start":"2026-06-18T12:32:28.947069Z","end":"2026-06-18T12:32:29.084025Z","steps":["trace[1235764447] 'agreement among raft nodes before linearized reading' (duration: 136.75337ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:32:29.084085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.73732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-18T12:32:29.084105Z","caller":"traceutil/trace.go:171","msg":"trace[1000326237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2577; }","duration":"135.777548ms","start":"2026-06-18T12:32:28.948322Z","end":"2026-06-18T12:32:29.084099Z","steps":["trace[1000326237] 'agreement among raft nodes before linearized reading' (duration: 135.715821ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:32:29.084186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.997827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-18T12:32:29.084212Z","caller":"traceutil/trace.go:171","msg":"trace[532959444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2577; }","duration":"136.045575ms","start":"2026-06-18T12:32:28.948159Z","end":"2026-06-18T12:32:29.084205Z","steps":["trace[532959444] 'agreement among raft nodes before linearized reading' (duration: 135.959009ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:32:29.08432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.191078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-18T12:32:29.084371Z","caller":"traceutil/trace.go:171","msg":"trace[1307155662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2577; }","duration":"136.256314ms","start":"2026-06-18T12:32:28.948103Z","end":"2026-06-18T12:32:29.084359Z","steps":["trace[1307155662] 'agreement among raft nodes before linearized reading' (duration: 136.045044ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:32:29.083427Z","caller":"traceutil/trace.go:171","msg":"trace[67495462] transaction","detail":"{read_only:false; response_revision:2576; number_of_response:1; }","duration":"147.112987ms","start":"2026-06-18T12:32:28.936248Z","end":"2026-06-18T12:32:29.083361Z","steps":["trace[67495462] 'process raft request' (duration: 142.440454ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:32:29.674436Z","caller":"traceutil/trace.go:171","msg":"trace[892718407] transaction","detail":"{read_only:false; response_revision:2583; number_of_response:1; }","duration":"117.956565ms","start":"2026-06-18T12:32:29.556425Z","end":"2026-06-18T12:32:29.674382Z","steps":["trace[892718407] 'process raft request' (duration: 117.783764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:33:03.024919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.668782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-18T12:33:03.025013Z","caller":"traceutil/trace.go:171","msg":"trace[221206619] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2762; }","duration":"257.820358ms","start":"2026-06-18T12:33:02.76717Z","end":"2026-06-18T12:33:03.024991Z","steps":["trace[221206619] 'range keys from in-memory index tree' (duration: 257.483776ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:33:03.025246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.512455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ba2ce48c632aec\" ","response":"range_response_count:1 size:779"} {"level":"warn","ts":"2026-06-18T12:33:03.025321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.368235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-18T12:33:03.02572Z","caller":"traceutil/trace.go:171","msg":"trace[1224664679] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ba2ce48c632aec; range_end:; response_count:1; response_revision:2762; }","duration":"325.590374ms","start":"2026-06-18T12:33:02.699685Z","end":"2026-06-18T12:33:03.025275Z","steps":["trace[1224664679] 'range keys from in-memory index tree' (duration: 325.351829ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:33:03.025409Z","caller":"traceutil/trace.go:171","msg":"trace[1389292695] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2762; }","duration":"405.505231ms","start":"2026-06-18T12:33:02.61989Z","end":"2026-06-18T12:33:03.025395Z","steps":["trace[1389292695] 'range keys from in-memory index tree' (duration: 405.218238ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:33:03.025781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:33:02.619866Z","time spent":"405.904863ms","remote":"127.0.0.1:48364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-06-18T12:33:03.025776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:33:02.699667Z","time spent":"326.095482ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":802,"request content":"key:\"/registry/events/auth-system/keycloak-0.18ba2ce48c632aec\" "} {"level":"info","ts":"2026-06-18T12:33:03.142235Z","caller":"traceutil/trace.go:171","msg":"trace[884497442] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"114.478156ms","start":"2026-06-18T12:33:03.027734Z","end":"2026-06-18T12:33:03.142212Z","steps":["trace[884497442] 'process raft request' (duration: 114.345569ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:33:03.185202Z","caller":"traceutil/trace.go:171","msg":"trace[2046353040] transaction","detail":"{read_only:false; response_revision:2764; number_of_response:1; }","duration":"154.482841ms","start":"2026-06-18T12:33:03.030701Z","end":"2026-06-18T12:33:03.185184Z","steps":["trace[2046353040] 'process raft request' (duration: 154.364124ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:33:21.426891Z","caller":"traceutil/trace.go:171","msg":"trace[1504422389] transaction","detail":"{read_only:false; response_revision:2855; number_of_response:1; }","duration":"155.14799ms","start":"2026-06-18T12:33:21.271708Z","end":"2026-06-18T12:33:21.426856Z","steps":["trace[1504422389] 'process raft request' (duration: 154.948502ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:33:21.428607Z","caller":"traceutil/trace.go:171","msg":"trace[417325922] transaction","detail":"{read_only:false; response_revision:2856; number_of_response:1; }","duration":"154.05588ms","start":"2026-06-18T12:33:21.274533Z","end":"2026-06-18T12:33:21.428589Z","steps":["trace[417325922] 'process raft request' (duration: 153.899882ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:33:21.428853Z","caller":"traceutil/trace.go:171","msg":"trace[299086974] linearizableReadLoop","detail":"{readStateIndex:2964; appliedIndex:2962; }","duration":"144.957367ms","start":"2026-06-18T12:33:21.283803Z","end":"2026-06-18T12:33:21.428761Z","steps":["trace[299086974] 'read index received' (duration: 142.836617ms)","trace[299086974] 'applied index is now lower than readState.Index' (duration: 2.11781ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:33:21.429117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.301344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-18T12:33:21.429175Z","caller":"traceutil/trace.go:171","msg":"trace[571281906] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2857; }","duration":"145.406212ms","start":"2026-06-18T12:33:21.283753Z","end":"2026-06-18T12:33:21.42916Z","steps":["trace[571281906] 'agreement among raft nodes before linearized reading' (duration: 145.199284ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:33:21.429143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.380298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:33:21.42937Z","caller":"traceutil/trace.go:171","msg":"trace[1404020849] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2857; }","duration":"129.653864ms","start":"2026-06-18T12:33:21.299698Z","end":"2026-06-18T12:33:21.429352Z","steps":["trace[1404020849] 'agreement among raft nodes before linearized reading' (duration: 129.339318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:36:40.148764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.251064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-18T12:36:40.14887Z","caller":"traceutil/trace.go:171","msg":"trace[804115104] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4442; }","duration":"236.96855ms","start":"2026-06-18T12:36:39.911883Z","end":"2026-06-18T12:36:40.148851Z","steps":["trace[804115104] 'range keys from in-memory index tree' (duration: 235.523135ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:36:41.674359Z","caller":"traceutil/trace.go:171","msg":"trace[1219361580] transaction","detail":"{read_only:false; response_revision:4458; number_of_response:1; }","duration":"345.398038ms","start":"2026-06-18T12:36:41.328936Z","end":"2026-06-18T12:36:41.674334Z","steps":["trace[1219361580] 'process raft request' (duration: 345.275951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:36:41.6745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:36:41.328914Z","time spent":"345.513514ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4659,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:36:47.642871Z","caller":"traceutil/trace.go:171","msg":"trace[467265925] transaction","detail":"{read_only:false; response_revision:4639; number_of_response:1; }","duration":"129.904513ms","start":"2026-06-18T12:36:47.512937Z","end":"2026-06-18T12:36:47.642841Z","steps":["trace[467265925] 'process raft request' (duration: 129.682812ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:36:49.640263Z","caller":"traceutil/trace.go:171","msg":"trace[13710984] transaction","detail":"{read_only:false; response_revision:4662; number_of_response:1; }","duration":"368.436122ms","start":"2026-06-18T12:36:49.271801Z","end":"2026-06-18T12:36:49.640237Z","steps":["trace[13710984] 'process raft request' (duration: 368.301235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:36:49.64044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:36:49.271778Z","time spent":"368.558209ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:36:49.692075Z","caller":"traceutil/trace.go:171","msg":"trace[1795638285] linearizableReadLoop","detail":"{readStateIndex:4846; appliedIndex:4845; }","duration":"297.294233ms","start":"2026-06-18T12:36:49.394756Z","end":"2026-06-18T12:36:49.69205Z","steps":["trace[1795638285] 'read index received' (duration: 245.551108ms)","trace[1795638285] 'applied index is now lower than readState.Index' (duration: 51.742525ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:36:49.692189Z","caller":"traceutil/trace.go:171","msg":"trace[795963736] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"418.5161ms","start":"2026-06-18T12:36:49.273666Z","end":"2026-06-18T12:36:49.692182Z","steps":["trace[795963736] 'process raft request' (duration: 417.455595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:36:49.692277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:36:49.273654Z","time spent":"418.553612ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9927,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:36:49.692394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.636841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T12:36:49.692415Z","caller":"traceutil/trace.go:171","msg":"trace[1966699411] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:4663; }","duration":"297.696134ms","start":"2026-06-18T12:36:49.394713Z","end":"2026-06-18T12:36:49.692409Z","steps":["trace[1966699411] 'agreement among raft nodes before linearized reading' (duration: 297.653312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:36:49.692649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.457659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T12:36:49.692726Z","caller":"traceutil/trace.go:171","msg":"trace[1142501220] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:4663; }","duration":"199.590497ms","start":"2026-06-18T12:36:49.493117Z","end":"2026-06-18T12:36:49.692708Z","steps":["trace[1142501220] 'agreement among raft nodes before linearized reading' (duration: 199.419947ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:36:52.941985Z","caller":"traceutil/trace.go:171","msg":"trace[1639673975] linearizableReadLoop","detail":"{readStateIndex:4931; appliedIndex:4930; }","duration":"211.308947ms","start":"2026-06-18T12:36:52.73065Z","end":"2026-06-18T12:36:52.941959Z","steps":["trace[1639673975] 'read index received' (duration: 20.74512ms)","trace[1639673975] 'applied index is now lower than readState.Index' (duration: 190.562417ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:36:52.942232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.55648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-42b96850-d437-4b08-bf0e-1be586556d98\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-18T12:36:52.942278Z","caller":"traceutil/trace.go:171","msg":"trace[1066491959] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-42b96850-d437-4b08-bf0e-1be586556d98; range_end:; response_count:1; response_revision:4746; }","duration":"211.649084ms","start":"2026-06-18T12:36:52.730617Z","end":"2026-06-18T12:36:52.942266Z","steps":["trace[1066491959] 'agreement among raft nodes before linearized reading' (duration: 211.446563ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:36:52.942061Z","caller":"traceutil/trace.go:171","msg":"trace[1711015040] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"274.760412ms","start":"2026-06-18T12:36:52.667259Z","end":"2026-06-18T12:36:52.94202Z","steps":["trace[1711015040] 'process raft request' (duration: 274.510839ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:36:53.445795Z","caller":"traceutil/trace.go:171","msg":"trace[1936191192] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"142.80123ms","start":"2026-06-18T12:36:53.302965Z","end":"2026-06-18T12:36:53.445766Z","steps":["trace[1936191192] 'process raft request' (duration: 129.290591ms)","trace[1936191192] 'compare' (duration: 13.385692ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:36:54.7184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.292264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-06-18T12:36:54.718505Z","caller":"traceutil/trace.go:171","msg":"trace[152261717] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4766; }","duration":"162.442753ms","start":"2026-06-18T12:36:54.556039Z","end":"2026-06-18T12:36:54.718481Z","steps":["trace[152261717] 'range keys from in-memory index tree' (duration: 162.101614ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:36:54.718634Z","caller":"traceutil/trace.go:171","msg":"trace[2092439859] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"159.596792ms","start":"2026-06-18T12:36:54.559027Z","end":"2026-06-18T12:36:54.718624Z","steps":["trace[2092439859] 'process raft request' (duration: 157.629646ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:36:58.84911Z","caller":"traceutil/trace.go:171","msg":"trace[1611529580] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"108.238825ms","start":"2026-06-18T12:36:58.740842Z","end":"2026-06-18T12:36:58.849081Z","steps":["trace[1611529580] 'process raft request' (duration: 97.91408ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:37:05.97643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.467983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T12:37:05.976512Z","caller":"traceutil/trace.go:171","msg":"trace[697894968] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:4906; }","duration":"458.59791ms","start":"2026-06-18T12:37:05.517896Z","end":"2026-06-18T12:37:05.976494Z","steps":["trace[697894968] 'count revisions from in-memory index tree' (duration: 458.389569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:37:05.976874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:37:05.517878Z","time spent":"458.943099ms","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":78,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-06-18T12:37:05.977553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.728628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"warn","ts":"2026-06-18T12:37:05.977525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.798989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T12:37:05.977602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.480666ms","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-06-18T12:37:05.977626Z","caller":"traceutil/trace.go:171","msg":"trace[1731435433] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:4906; }","duration":"482.934156ms","start":"2026-06-18T12:37:05.494669Z","end":"2026-06-18T12:37:05.977603Z","steps":["trace[1731435433] 'count revisions from in-memory index tree' (duration: 482.691434ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:37:05.97768Z","caller":"traceutil/trace.go:171","msg":"trace[1818073707] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4906; }","duration":"541.633795ms","start":"2026-06-18T12:37:05.436028Z","end":"2026-06-18T12:37:05.977662Z","steps":["trace[1818073707] 'range keys from in-memory index tree' (duration: 541.287845ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:37:05.977697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:37:05.494615Z","time spent":"483.057663ms","remote":"127.0.0.1:53994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-06-18T12:37:05.977655Z","caller":"traceutil/trace.go:171","msg":"trace[212665280] linearizableReadLoop","detail":"{readStateIndex:5098; appliedIndex:5097; }","duration":"376.094547ms","start":"2026-06-18T12:37:05.601527Z","end":"2026-06-18T12:37:05.977621Z","steps":["trace[212665280] 'read index received' (duration: 356.441277ms)","trace[212665280] 'applied index is now lower than readState.Index' (duration: 19.6517ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:37:05.977725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:37:05.436015Z","time spent":"541.701498ms","remote":"127.0.0.1:48276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-06-18T12:37:05.977996Z","caller":"traceutil/trace.go:171","msg":"trace[919443195] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"400.042292ms","start":"2026-06-18T12:37:05.577933Z","end":"2026-06-18T12:37:05.977975Z","steps":["trace[919443195] 'process raft request' (duration: 380.021761ms)","trace[919443195] 'compare' (duration: 18.316267ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:37:05.978135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:37:05.577911Z","time spent":"400.139617ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:37:05.978426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.906511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-18T12:37:05.978468Z","caller":"traceutil/trace.go:171","msg":"trace[1206017136] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4907; }","duration":"376.985166ms","start":"2026-06-18T12:37:05.601471Z","end":"2026-06-18T12:37:05.978456Z","steps":["trace[1206017136] 'agreement among raft nodes before linearized reading' (duration: 376.850778ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:37:05.978498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:37:05.60145Z","time spent":"377.041298ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-18T12:37:05.977603Z","caller":"traceutil/trace.go:171","msg":"trace[1515983655] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4906; }","duration":"579.805693ms","start":"2026-06-18T12:37:05.397786Z","end":"2026-06-18T12:37:05.977592Z","steps":["trace[1515983655] 'range keys from in-memory index tree' (duration: 579.609291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:37:05.979067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:37:05.397772Z","time spent":"581.277383ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-18T12:37:05.998235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.956463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T12:37:05.998261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.005941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T12:37:05.998353Z","caller":"traceutil/trace.go:171","msg":"trace[55501601] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:4908; }","duration":"121.155139ms","start":"2026-06-18T12:37:05.877177Z","end":"2026-06-18T12:37:05.998332Z","steps":["trace[55501601] 'agreement among raft nodes before linearized reading' (duration: 120.958119ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:37:05.998235Z","caller":"traceutil/trace.go:171","msg":"trace[1291593989] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"155.912764ms","start":"2026-06-18T12:37:05.842297Z","end":"2026-06-18T12:37:05.99821Z","steps":["trace[1291593989] 'process raft request' (duration: 155.620208ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:37:05.998317Z","caller":"traceutil/trace.go:171","msg":"trace[421252793] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:4908; }","duration":"142.053968ms","start":"2026-06-18T12:37:05.856245Z","end":"2026-06-18T12:37:05.998299Z","steps":["trace[421252793] 'agreement among raft nodes before linearized reading' (duration: 141.914991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:37:06.939309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.93577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:37:06.939546Z","caller":"traceutil/trace.go:171","msg":"trace[1738103123] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"181.701177ms","start":"2026-06-18T12:37:06.757828Z","end":"2026-06-18T12:37:06.939529Z","steps":["trace[1738103123] 'process raft request' (duration: 181.664675ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:37:06.939867Z","caller":"traceutil/trace.go:171","msg":"trace[1097424563] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"696.719035ms","start":"2026-06-18T12:37:06.243135Z","end":"2026-06-18T12:37:06.939854Z","steps":["trace[1097424563] 'process raft request' (duration: 326.162882ms)","trace[1097424563] 'compare' (duration: 369.010928ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:37:06.939972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:37:06.243115Z","time spent":"696.788838ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:37:06.94003Z","caller":"traceutil/trace.go:171","msg":"trace[453443832] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"289.287858ms","start":"2026-06-18T12:37:06.650708Z","end":"2026-06-18T12:37:06.939995Z","steps":["trace[453443832] 'process raft request' (duration: 288.753509ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:37:06.940101Z","caller":"traceutil/trace.go:171","msg":"trace[449950265] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"688.562178ms","start":"2026-06-18T12:37:06.251529Z","end":"2026-06-18T12:37:06.940091Z","steps":["trace[449950265] 'process raft request' (duration: 687.873671ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:37:06.94015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:37:06.25151Z","time spent":"688.613382ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:37:06.94025Z","caller":"traceutil/trace.go:171","msg":"trace[329201070] linearizableReadLoop","detail":"{readStateIndex:5106; appliedIndex:5104; }","duration":"489.983781ms","start":"2026-06-18T12:37:06.450255Z","end":"2026-06-18T12:37:06.940239Z","steps":["trace[329201070] 'read index received' (duration: 118.986865ms)","trace[329201070] 'applied index is now lower than readState.Index' (duration: 370.995056ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:37:06.940444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.170791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-06-18T12:37:06.940485Z","caller":"traceutil/trace.go:171","msg":"trace[2019256242] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4917; }","duration":"490.259746ms","start":"2026-06-18T12:37:06.450213Z","end":"2026-06-18T12:37:06.940473Z","steps":["trace[2019256242] 'agreement among raft nodes before linearized reading' (duration: 490.107878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:37:06.940515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:37:06.450194Z","time spent":"490.313319ms","remote":"127.0.0.1:45082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-06-18T12:37:06.940551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.928072ms","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-06-18T12:37:06.940647Z","caller":"traceutil/trace.go:171","msg":"trace[1591660915] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4917; }","duration":"482.006066ms","start":"2026-06-18T12:37:06.458593Z","end":"2026-06-18T12:37:06.940599Z","steps":["trace[1591660915] 'agreement among raft nodes before linearized reading' (duration: 481.836817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:37:06.940704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:37:06.458586Z","time spent":"482.105081ms","remote":"127.0.0.1:48276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-18T12:37:06.940839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.492897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-6967dbccc8-dgvpg\" ","response":"range_response_count:1 size:11845"} {"level":"info","ts":"2026-06-18T12:37:06.940888Z","caller":"traceutil/trace.go:171","msg":"trace[1028638055] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-6967dbccc8-dgvpg; range_end:; response_count:1; response_revision:4917; }","duration":"199.573792ms","start":"2026-06-18T12:37:06.741301Z","end":"2026-06-18T12:37:06.940875Z","steps":["trace[1028638055] 'agreement among raft nodes before linearized reading' (duration: 199.426114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:37:06.940942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.876178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-18T12:37:06.940982Z","caller":"traceutil/trace.go:171","msg":"trace[878440957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4917; }","duration":"433.950022ms","start":"2026-06-18T12:37:06.507021Z","end":"2026-06-18T12:37:06.940971Z","steps":["trace[878440957] 'agreement among raft nodes before linearized reading' (duration: 433.840856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:37:06.940979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.616682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-18T12:37:06.941017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:37:06.507003Z","time spent":"434.004925ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-18T12:37:06.941067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.847863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T12:37:06.940991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.725013ms","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-06-18T12:37:06.941142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.574063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-18T12:37:06.941175Z","caller":"traceutil/trace.go:171","msg":"trace[2069471302] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4917; }","duration":"442.647008ms","start":"2026-06-18T12:37:06.498519Z","end":"2026-06-18T12:37:06.941166Z","steps":["trace[2069471302] 'agreement among raft nodes before linearized reading' (duration: 442.567833ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:37:06.94115Z","caller":"traceutil/trace.go:171","msg":"trace[1937206988] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4917; }","duration":"427.938084ms","start":"2026-06-18T12:37:06.513198Z","end":"2026-06-18T12:37:06.941136Z","steps":["trace[1937206988] 'agreement among raft nodes before linearized reading' (duration: 427.621897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:37:06.941206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:37:06.498498Z","time spent":"442.701242ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-18T12:37:06.941222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:37:06.513184Z","time spent":"428.02509ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-18T12:37:06.941106Z","caller":"traceutil/trace.go:171","msg":"trace[768058755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4917; }","duration":"183.911477ms","start":"2026-06-18T12:37:06.757184Z","end":"2026-06-18T12:37:06.941095Z","steps":["trace[768058755] 'agreement among raft nodes before linearized reading' (duration: 183.860094ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:37:06.943699Z","caller":"traceutil/trace.go:171","msg":"trace[586807933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4917; }","duration":"428.745899ms","start":"2026-06-18T12:37:06.512281Z","end":"2026-06-18T12:37:06.941027Z","steps":["trace[586807933] 'agreement among raft nodes before linearized reading' (duration: 428.524157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:37:06.94393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:37:06.51226Z","time spent":"431.632377ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-18T12:37:07.087674Z","caller":"traceutil/trace.go:171","msg":"trace[774340503] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"133.936559ms","start":"2026-06-18T12:37:06.953705Z","end":"2026-06-18T12:37:07.087641Z","steps":["trace[774340503] 'process raft request' (duration: 119.972476ms)","trace[774340503] 'compare' (duration: 13.796124ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:37:43.863475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.475849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:37:43.863589Z","caller":"traceutil/trace.go:171","msg":"trace[1776960697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5268; }","duration":"105.633998ms","start":"2026-06-18T12:37:43.75793Z","end":"2026-06-18T12:37:43.863564Z","steps":["trace[1776960697] 'range keys from in-memory index tree' (duration: 105.405885ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:37:55.531209Z","caller":"traceutil/trace.go:171","msg":"trace[361445292] transaction","detail":"{read_only:false; response_revision:5353; number_of_response:1; }","duration":"123.563037ms","start":"2026-06-18T12:37:55.407608Z","end":"2026-06-18T12:37:55.531171Z","steps":["trace[361445292] 'process raft request' (duration: 97.975482ms)","trace[361445292] 'compare' (duration: 25.453887ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:38:15.082999Z","caller":"traceutil/trace.go:171","msg":"trace[1990514945] linearizableReadLoop","detail":"{readStateIndex:5715; appliedIndex:5714; }","duration":"282.425297ms","start":"2026-06-18T12:38:14.800556Z","end":"2026-06-18T12:38:15.082981Z","steps":["trace[1990514945] 'read index received' (duration: 282.3061ms)","trace[1990514945] 'applied index is now lower than readState.Index' (duration: 118.527µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:38:15.083062Z","caller":"traceutil/trace.go:171","msg":"trace[167977780] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"533.154765ms","start":"2026-06-18T12:38:14.549877Z","end":"2026-06-18T12:38:15.083032Z","steps":["trace[167977780] 'process raft request' (duration: 532.985905ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:38:15.083162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.589197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T12:38:15.083198Z","caller":"traceutil/trace.go:171","msg":"trace[389042639] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5508; }","duration":"282.667151ms","start":"2026-06-18T12:38:14.800519Z","end":"2026-06-18T12:38:15.083187Z","steps":["trace[389042639] 'agreement among raft nodes before linearized reading' (duration: 282.596117ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:38:15.083287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:38:14.549854Z","time spent":"533.323434ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:38:15.608528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.477071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:38:15.608765Z","caller":"traceutil/trace.go:171","msg":"trace[1694766330] linearizableReadLoop","detail":"{readStateIndex:5718; appliedIndex:5715; }","duration":"236.775685ms","start":"2026-06-18T12:38:15.371973Z","end":"2026-06-18T12:38:15.608749Z","steps":["trace[1694766330] 'read index received' (duration: 197.210426ms)","trace[1694766330] 'applied index is now lower than readState.Index' (duration: 39.564279ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:38:15.608862Z","caller":"traceutil/trace.go:171","msg":"trace[1258497244] transaction","detail":"{read_only:false; response_revision:5510; number_of_response:1; }","duration":"324.495138ms","start":"2026-06-18T12:38:15.284357Z","end":"2026-06-18T12:38:15.608852Z","steps":["trace[1258497244] 'process raft request' (duration: 324.273415ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:38:15.608845Z","caller":"traceutil/trace.go:171","msg":"trace[1329657675] transaction","detail":"{read_only:false; response_revision:5509; number_of_response:1; }","duration":"392.468994ms","start":"2026-06-18T12:38:15.216338Z","end":"2026-06-18T12:38:15.608807Z","steps":["trace[1329657675] 'process raft request' (duration: 119.630301ms)","trace[1329657675] 'compare' (duration: 272.028784ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:38:15.608921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:38:15.284332Z","time spent":"324.554922ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:38:15.609061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:38:15.216319Z","time spent":"392.617343ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:38:15.609158Z","caller":"traceutil/trace.go:171","msg":"trace[345132424] transaction","detail":"{read_only:false; response_revision:5511; number_of_response:1; }","duration":"322.692069ms","start":"2026-06-18T12:38:15.286447Z","end":"2026-06-18T12:38:15.609139Z","steps":["trace[345132424] 'process raft request' (duration: 322.263193ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:38:15.609228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:38:15.286431Z","time spent":"322.762403ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:38:15.609371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.523863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-18T12:38:15.609442Z","caller":"traceutil/trace.go:171","msg":"trace[1177920232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5511; }","duration":"185.63977ms","start":"2026-06-18T12:38:15.423784Z","end":"2026-06-18T12:38:15.609424Z","steps":["trace[1177920232] 'agreement among raft nodes before linearized reading' (duration: 185.417517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:38:15.6095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.5752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-18T12:38:15.609768Z","caller":"traceutil/trace.go:171","msg":"trace[1319437493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5511; }","duration":"131.872108ms","start":"2026-06-18T12:38:15.477881Z","end":"2026-06-18T12:38:15.609753Z","steps":["trace[1319437493] 'agreement among raft nodes before linearized reading' (duration: 131.446622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:38:15.609773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.791475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-18T12:38:15.609796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.617108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-18T12:38:15.609814Z","caller":"traceutil/trace.go:171","msg":"trace[874402895] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5511; }","duration":"237.900262ms","start":"2026-06-18T12:38:15.371903Z","end":"2026-06-18T12:38:15.609803Z","steps":["trace[874402895] 'agreement among raft nodes before linearized reading' (duration: 237.617205ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:38:15.609923Z","caller":"traceutil/trace.go:171","msg":"trace[719655183] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5511; }","duration":"180.695023ms","start":"2026-06-18T12:38:15.429125Z","end":"2026-06-18T12:38:15.609821Z","steps":["trace[719655183] 'agreement among raft nodes before linearized reading' (duration: 180.596647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:38:15.996084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.889137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:38:15.996414Z","caller":"traceutil/trace.go:171","msg":"trace[1081208165] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"381.247729ms","start":"2026-06-18T12:38:15.615137Z","end":"2026-06-18T12:38:15.996384Z","steps":["trace[1081208165] 'process raft request' (duration: 104.65286ms)","trace[1081208165] 'compare' (duration: 275.680765ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:38:15.996672Z","caller":"traceutil/trace.go:171","msg":"trace[1829983202] transaction","detail":"{read_only:false; response_revision:5515; number_of_response:1; }","duration":"381.307953ms","start":"2026-06-18T12:38:15.615348Z","end":"2026-06-18T12:38:15.996656Z","steps":["trace[1829983202] 'process raft request' (duration: 381.08897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:38:15.996588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:38:15.615081Z","time spent":"381.396179ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:38:15.996947Z","caller":"traceutil/trace.go:171","msg":"trace[763893162] transaction","detail":"{read_only:false; response_revision:5516; number_of_response:1; }","duration":"381.572939ms","start":"2026-06-18T12:38:15.615357Z","end":"2026-06-18T12:38:15.99693Z","steps":["trace[763893162] 'process raft request' (duration: 381.25581ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:38:15.997032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:38:15.615342Z","time spent":"381.652254ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:38:15.99695Z","caller":"traceutil/trace.go:171","msg":"trace[41252107] linearizableReadLoop","detail":"{readStateIndex:5724; appliedIndex:5719; }","duration":"240.707751ms","start":"2026-06-18T12:38:15.756227Z","end":"2026-06-18T12:38:15.996935Z","steps":["trace[41252107] 'read index received' (duration: 50.713µs)","trace[41252107] 'applied index is now lower than readState.Index' (duration: 240.656218ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:38:15.997059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.836409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:38:15.997082Z","caller":"traceutil/trace.go:171","msg":"trace[483566695] transaction","detail":"{read_only:false; response_revision:5517; number_of_response:1; }","duration":"381.311543ms","start":"2026-06-18T12:38:15.615756Z","end":"2026-06-18T12:38:15.997068Z","steps":["trace[483566695] 'process raft request' (duration: 381.042017ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:38:15.997143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.44676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:38:15.997102Z","caller":"traceutil/trace.go:171","msg":"trace[2036802950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5517; }","duration":"241.000668ms","start":"2026-06-18T12:38:15.75609Z","end":"2026-06-18T12:38:15.997091Z","steps":["trace[2036802950] 'agreement among raft nodes before linearized reading' (duration: 240.892912ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:38:15.997176Z","caller":"traceutil/trace.go:171","msg":"trace[1070603749] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5517; }","duration":"193.508834ms","start":"2026-06-18T12:38:15.803657Z","end":"2026-06-18T12:38:15.997166Z","steps":["trace[1070603749] 'agreement among raft nodes before linearized reading' (duration: 193.459561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:38:15.997186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:38:15.615747Z","time spent":"381.398029ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:38:15.997152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:38:15.615336Z","time spent":"381.451471ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:38:15.996711Z","caller":"traceutil/trace.go:171","msg":"trace[847596961] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"381.357796ms","start":"2026-06-18T12:38:15.615263Z","end":"2026-06-18T12:38:15.996621Z","steps":["trace[847596961] 'process raft request' (duration: 381.043878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:38:15.997713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:38:15.615248Z","time spent":"382.168975ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:38:24.444897Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2880} {"level":"info","ts":"2026-06-18T12:38:24.57858Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2880,"took":"131.834475ms","hash":2845774599,"current-db-size-bytes":53821440,"current-db-size":"54 MB","current-db-size-in-use-bytes":29179904,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-18T12:38:24.578646Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2845774599,"revision":2880,"compact-revision":-1} {"level":"info","ts":"2026-06-18T12:40:16.241905Z","caller":"traceutil/trace.go:171","msg":"trace[1542179245] linearizableReadLoop","detail":"{readStateIndex:6485; appliedIndex:6484; }","duration":"107.477849ms","start":"2026-06-18T12:40:16.134398Z","end":"2026-06-18T12:40:16.241876Z","steps":["trace[1542179245] 'read index received' (duration: 107.203151ms)","trace[1542179245] 'applied index is now lower than readState.Index' (duration: 273.798µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:40:16.241968Z","caller":"traceutil/trace.go:171","msg":"trace[487165723] transaction","detail":"{read_only:false; response_revision:6249; number_of_response:1; }","duration":"140.843323ms","start":"2026-06-18T12:40:16.101081Z","end":"2026-06-18T12:40:16.241924Z","steps":["trace[487165723] 'process raft request' (duration: 140.615508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:40:16.24215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.720094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T12:40:16.242239Z","caller":"traceutil/trace.go:171","msg":"trace[1801034240] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:6249; }","duration":"107.877025ms","start":"2026-06-18T12:40:16.134341Z","end":"2026-06-18T12:40:16.242218Z","steps":["trace[1801034240] 'agreement among raft nodes before linearized reading' (duration: 107.661621ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:40:16.937902Z","caller":"traceutil/trace.go:171","msg":"trace[689899240] transaction","detail":"{read_only:false; response_revision:6254; number_of_response:1; }","duration":"125.82285ms","start":"2026-06-18T12:40:16.812056Z","end":"2026-06-18T12:40:16.937879Z","steps":["trace[689899240] 'process raft request' (duration: 125.691871ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:40:16.968118Z","caller":"traceutil/trace.go:171","msg":"trace[1607637461] transaction","detail":"{read_only:false; response_revision:6255; number_of_response:1; }","duration":"155.712287ms","start":"2026-06-18T12:40:16.812375Z","end":"2026-06-18T12:40:16.968087Z","steps":["trace[1607637461] 'process raft request' (duration: 155.29275ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:40:16.968116Z","caller":"traceutil/trace.go:171","msg":"trace[1144659727] transaction","detail":"{read_only:false; response_revision:6256; number_of_response:1; }","duration":"154.872673ms","start":"2026-06-18T12:40:16.813183Z","end":"2026-06-18T12:40:16.968055Z","steps":["trace[1144659727] 'process raft request' (duration: 154.709962ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:40:16.968462Z","caller":"traceutil/trace.go:171","msg":"trace[160298531] transaction","detail":"{read_only:false; response_revision:6257; number_of_response:1; }","duration":"154.261084ms","start":"2026-06-18T12:40:16.814101Z","end":"2026-06-18T12:40:16.968362Z","steps":["trace[160298531] 'process raft request' (duration: 153.89743ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:40:18.002686Z","caller":"traceutil/trace.go:171","msg":"trace[520508286] linearizableReadLoop","detail":"{readStateIndex:6494; appliedIndex:6493; }","duration":"246.151159ms","start":"2026-06-18T12:40:17.756502Z","end":"2026-06-18T12:40:18.002653Z","steps":["trace[520508286] 'read index received' (duration: 149.712792ms)","trace[520508286] 'applied index is now lower than readState.Index' (duration: 96.436086ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:40:18.00285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.33277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:40:18.002889Z","caller":"traceutil/trace.go:171","msg":"trace[2057035111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6257; }","duration":"246.437477ms","start":"2026-06-18T12:40:17.756439Z","end":"2026-06-18T12:40:18.002877Z","steps":["trace[2057035111] 'agreement among raft nodes before linearized reading' (duration: 246.33122ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:40:18.406442Z","caller":"traceutil/trace.go:171","msg":"trace[376632818] transaction","detail":"{read_only:false; response_revision:6260; number_of_response:1; }","duration":"115.469053ms","start":"2026-06-18T12:40:18.290939Z","end":"2026-06-18T12:40:18.406408Z","steps":["trace[376632818] 'process raft request' (duration: 115.158173ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:40:18.628283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.929524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-18T12:40:18.628389Z","caller":"traceutil/trace.go:171","msg":"trace[308179358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6260; }","duration":"147.046532ms","start":"2026-06-18T12:40:18.481313Z","end":"2026-06-18T12:40:18.62836Z","steps":["trace[308179358] 'range keys from in-memory index tree' (duration: 146.723941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:40:18.91387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.271501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-18T12:40:18.913972Z","caller":"traceutil/trace.go:171","msg":"trace[1049574752] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6261; }","duration":"107.431491ms","start":"2026-06-18T12:40:18.806516Z","end":"2026-06-18T12:40:18.913947Z","steps":["trace[1049574752] 'range keys from in-memory index tree' (duration: 107.043886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:40:18.914565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.116661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:40:18.914621Z","caller":"traceutil/trace.go:171","msg":"trace[901301020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6261; }","duration":"156.219778ms","start":"2026-06-18T12:40:18.758388Z","end":"2026-06-18T12:40:18.914608Z","steps":["trace[901301020] 'range keys from in-memory index tree' (duration: 155.975891ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:40:19.200584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.380752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:40:19.200835Z","caller":"traceutil/trace.go:171","msg":"trace[1886701112] transaction","detail":"{read_only:false; response_revision:6266; number_of_response:1; }","duration":"190.685699ms","start":"2026-06-18T12:40:19.010132Z","end":"2026-06-18T12:40:19.200817Z","steps":["trace[1886701112] 'process raft request' (duration: 190.641166ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:40:19.201175Z","caller":"traceutil/trace.go:171","msg":"trace[1870310729] transaction","detail":"{read_only:false; response_revision:6263; number_of_response:1; }","duration":"193.149259ms","start":"2026-06-18T12:40:19.008011Z","end":"2026-06-18T12:40:19.20116Z","steps":["trace[1870310729] 'process raft request' (duration: 36.112463ms)","trace[1870310729] 'compare' (duration: 156.263775ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:40:19.20133Z","caller":"traceutil/trace.go:171","msg":"trace[1100118957] transaction","detail":"{read_only:false; response_revision:6264; number_of_response:1; }","duration":"191.912919ms","start":"2026-06-18T12:40:19.009407Z","end":"2026-06-18T12:40:19.20132Z","steps":["trace[1100118957] 'process raft request' (duration: 191.274237ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:40:19.201458Z","caller":"traceutil/trace.go:171","msg":"trace[2057159044] transaction","detail":"{read_only:false; response_revision:6265; number_of_response:1; }","duration":"192.001625ms","start":"2026-06-18T12:40:19.009445Z","end":"2026-06-18T12:40:19.201447Z","steps":["trace[2057159044] 'process raft request' (duration: 191.288098ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:40:20.597249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.959731ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:40:20.597414Z","caller":"traceutil/trace.go:171","msg":"trace[1140901579] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:6268; }","duration":"201.135162ms","start":"2026-06-18T12:40:20.39626Z","end":"2026-06-18T12:40:20.597395Z","steps":["trace[1140901579] 'range keys from in-memory index tree' (duration: 200.94461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:40:20.59749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.662127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:40:20.597737Z","caller":"traceutil/trace.go:171","msg":"trace[643795627] linearizableReadLoop","detail":"{readStateIndex:6507; appliedIndex:6505; }","duration":"143.815929ms","start":"2026-06-18T12:40:20.4539Z","end":"2026-06-18T12:40:20.597716Z","steps":["trace[643795627] 'read index received' (duration: 34.870103ms)","trace[643795627] 'applied index is now lower than readState.Index' (duration: 108.944586ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:40:20.597737Z","caller":"traceutil/trace.go:171","msg":"trace[509596841] transaction","detail":"{read_only:false; response_revision:6269; number_of_response:1; }","duration":"227.934802ms","start":"2026-06-18T12:40:20.369787Z","end":"2026-06-18T12:40:20.597721Z","steps":["trace[509596841] 'process raft request' (duration: 118.860089ms)","trace[509596841] 'compare' (duration: 108.392949ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:40:20.597896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.98878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T12:40:20.597915Z","caller":"traceutil/trace.go:171","msg":"trace[1528998744] transaction","detail":"{read_only:false; response_revision:6270; number_of_response:1; }","duration":"184.62984ms","start":"2026-06-18T12:40:20.413268Z","end":"2026-06-18T12:40:20.597898Z","steps":["trace[1528998744] 'process raft request' (duration: 184.350192ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:40:20.597945Z","caller":"traceutil/trace.go:171","msg":"trace[1134768484] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:6270; }","duration":"144.039303ms","start":"2026-06-18T12:40:20.453888Z","end":"2026-06-18T12:40:20.597927Z","steps":["trace[1134768484] 'agreement among raft nodes before linearized reading' (duration: 143.893374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:40:20.984274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.751302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:40:20.985086Z","caller":"traceutil/trace.go:171","msg":"trace[1256465497] linearizableReadLoop","detail":"{readStateIndex:6508; appliedIndex:6507; }","duration":"234.52729ms","start":"2026-06-18T12:40:20.750538Z","end":"2026-06-18T12:40:20.985065Z","steps":["trace[1256465497] 'read index received' (duration: 122.815115ms)","trace[1256465497] 'applied index is now lower than readState.Index' (duration: 111.709855ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:40:20.985195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.644507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:40:20.985252Z","caller":"traceutil/trace.go:171","msg":"trace[429817319] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:6271; }","duration":"234.751554ms","start":"2026-06-18T12:40:20.750487Z","end":"2026-06-18T12:40:20.985238Z","steps":["trace[429817319] 'agreement among raft nodes before linearized reading' (duration: 234.644557ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:40:20.985231Z","caller":"traceutil/trace.go:171","msg":"trace[157376666] transaction","detail":"{read_only:false; response_revision:6271; number_of_response:1; }","duration":"298.614282ms","start":"2026-06-18T12:40:20.686514Z","end":"2026-06-18T12:40:20.985128Z","steps":["trace[157376666] 'process raft request' (duration: 186.913728ms)","trace[157376666] 'compare' (duration: 110.474865ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:40:20.985496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.29562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:40:20.98559Z","caller":"traceutil/trace.go:171","msg":"trace[186964200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6271; }","duration":"229.502373ms","start":"2026-06-18T12:40:20.756068Z","end":"2026-06-18T12:40:20.985571Z","steps":["trace[186964200] 'agreement among raft nodes before linearized reading' (duration: 229.213494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:40:21.245288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.25783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:40:21.24542Z","caller":"traceutil/trace.go:171","msg":"trace[1659972562] transaction","detail":"{read_only:false; response_revision:6272; number_of_response:1; }","duration":"233.551102ms","start":"2026-06-18T12:40:21.011856Z","end":"2026-06-18T12:40:21.245407Z","steps":["trace[1659972562] 'process raft request' (duration: 81.12053ms)","trace[1659972562] 'compare' (duration: 152.171005ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:40:27.897462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.650497ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-18T12:40:27.897577Z","caller":"traceutil/trace.go:171","msg":"trace[1685725054] linearizableReadLoop","detail":"{readStateIndex:6646; appliedIndex:6645; }","duration":"139.18702ms","start":"2026-06-18T12:40:27.758376Z","end":"2026-06-18T12:40:27.897563Z","steps":["trace[1685725054] 'read index received' (duration: 27.381022ms)","trace[1685725054] 'applied index is now lower than readState.Index' (duration: 111.804108ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:40:27.897641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.257495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:40:27.897667Z","caller":"traceutil/trace.go:171","msg":"trace[327385923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6406; }","duration":"139.309879ms","start":"2026-06-18T12:40:27.758349Z","end":"2026-06-18T12:40:27.897659Z","steps":["trace[327385923] 'agreement among raft nodes before linearized reading' (duration: 139.249185ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:40:54.972975Z","caller":"traceutil/trace.go:171","msg":"trace[1133602054] transaction","detail":"{read_only:false; response_revision:6624; number_of_response:1; }","duration":"155.75727ms","start":"2026-06-18T12:40:54.817186Z","end":"2026-06-18T12:40:54.972943Z","steps":["trace[1133602054] 'process raft request' (duration: 140.349695ms)","trace[1133602054] 'compare' (duration: 15.016743ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:40:59.65016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.151581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-18T12:40:59.650237Z","caller":"traceutil/trace.go:171","msg":"trace[8823351] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6644; }","duration":"166.292918ms","start":"2026-06-18T12:40:59.483927Z","end":"2026-06-18T12:40:59.65022Z","steps":["trace[8823351] 'range keys from in-memory index tree' (duration: 165.969917ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:40:59.991529Z","caller":"traceutil/trace.go:171","msg":"trace[1124070399] transaction","detail":"{read_only:false; response_revision:6645; number_of_response:1; }","duration":"335.537613ms","start":"2026-06-18T12:40:59.655971Z","end":"2026-06-18T12:40:59.991509Z","steps":["trace[1124070399] 'process raft request' (duration: 335.397867ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:40:59.991774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:40:59.655931Z","time spent":"335.65577ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:41:00.014748Z","caller":"traceutil/trace.go:171","msg":"trace[1709127059] linearizableReadLoop","detail":"{readStateIndex:6895; appliedIndex:6893; }","duration":"354.185795ms","start":"2026-06-18T12:40:59.660536Z","end":"2026-06-18T12:41:00.014721Z","steps":["trace[1709127059] 'read index received' (duration: 330.819774ms)","trace[1709127059] 'applied index is now lower than readState.Index' (duration: 23.365111ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:41:00.015076Z","caller":"traceutil/trace.go:171","msg":"trace[844189836] transaction","detail":"{read_only:false; response_revision:6646; number_of_response:1; }","duration":"358.892344ms","start":"2026-06-18T12:40:59.656171Z","end":"2026-06-18T12:41:00.015063Z","steps":["trace[844189836] 'process raft request' (duration: 358.430777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:41:00.015181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:40:59.656163Z","time spent":"358.950272ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:41:00.015922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.745364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-18T12:41:00.016001Z","caller":"traceutil/trace.go:171","msg":"trace[973632135] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6646; }","duration":"351.837052ms","start":"2026-06-18T12:40:59.66415Z","end":"2026-06-18T12:41:00.015987Z","steps":["trace[973632135] 'agreement among raft nodes before linearized reading' (duration: 351.663207ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:41:00.016034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:40:59.664132Z","time spent":"351.89548ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-18T12:41:00.016188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.147037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:41:00.01622Z","caller":"traceutil/trace.go:171","msg":"trace[631972207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6646; }","duration":"258.201186ms","start":"2026-06-18T12:40:59.75801Z","end":"2026-06-18T12:41:00.016211Z","steps":["trace[631972207] 'agreement among raft nodes before linearized reading' (duration: 258.155787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:41:00.016404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.385008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-18T12:41:00.016451Z","caller":"traceutil/trace.go:171","msg":"trace[35126768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6646; }","duration":"348.444347ms","start":"2026-06-18T12:40:59.667999Z","end":"2026-06-18T12:41:00.016443Z","steps":["trace[35126768] 'agreement among raft nodes before linearized reading' (duration: 348.3457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:41:00.016478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:40:59.667992Z","time spent":"348.479736ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-18T12:41:00.01677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.20675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-18T12:41:00.016839Z","caller":"traceutil/trace.go:171","msg":"trace[1695944866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6646; }","duration":"356.292727ms","start":"2026-06-18T12:40:59.660531Z","end":"2026-06-18T12:41:00.016824Z","steps":["trace[1695944866] 'agreement among raft nodes before linearized reading' (duration: 354.885866ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:41:00.017531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:40:59.660498Z","time spent":"356.369015ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-18T12:41:02.695628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.325076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:41:02.695742Z","caller":"traceutil/trace.go:171","msg":"trace[1470618379] transaction","detail":"{read_only:false; response_revision:6663; number_of_response:1; }","duration":"149.289461ms","start":"2026-06-18T12:41:02.546442Z","end":"2026-06-18T12:41:02.695732Z","steps":["trace[1470618379] 'process raft request' (duration: 149.253442ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:41:02.695807Z","caller":"traceutil/trace.go:171","msg":"trace[1149765694] transaction","detail":"{read_only:false; response_revision:6662; number_of_response:1; }","duration":"181.294998ms","start":"2026-06-18T12:41:02.514479Z","end":"2026-06-18T12:41:02.695774Z","steps":["trace[1149765694] 'process raft request' (duration: 68.771818ms)","trace[1149765694] 'compare' (duration: 112.18886ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:41:07.164541Z","caller":"traceutil/trace.go:171","msg":"trace[1679649582] linearizableReadLoop","detail":"{readStateIndex:6931; appliedIndex:6930; }","duration":"409.08503ms","start":"2026-06-18T12:41:06.755438Z","end":"2026-06-18T12:41:07.164523Z","steps":["trace[1679649582] 'read index received' (duration: 409.018801ms)","trace[1679649582] 'applied index is now lower than readState.Index' (duration: 63.139µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:41:07.164637Z","caller":"traceutil/trace.go:171","msg":"trace[408924186] transaction","detail":"{read_only:false; response_revision:6681; number_of_response:1; }","duration":"454.109448ms","start":"2026-06-18T12:41:06.710519Z","end":"2026-06-18T12:41:07.164628Z","steps":["trace[408924186] 'process raft request' (duration: 453.727857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:41:07.164826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.373482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:41:07.1649Z","caller":"traceutil/trace.go:171","msg":"trace[636882079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6681; }","duration":"409.486729ms","start":"2026-06-18T12:41:06.755396Z","end":"2026-06-18T12:41:07.164883Z","steps":["trace[636882079] 'agreement among raft nodes before linearized reading' (duration: 409.375602ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:41:07.164926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:41:06.755377Z","time spent":"409.543399ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T12:41:07.165721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:41:06.710494Z","time spent":"454.162366ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:41:07.166514Z","caller":"traceutil/trace.go:171","msg":"trace[2058942799] transaction","detail":"{read_only:false; response_revision:6682; number_of_response:1; }","duration":"312.340739ms","start":"2026-06-18T12:41:06.854163Z","end":"2026-06-18T12:41:07.166504Z","steps":["trace[2058942799] 'process raft request' (duration: 311.841522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:41:07.166575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:41:06.854139Z","time spent":"312.408978ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:41:07.166689Z","caller":"traceutil/trace.go:171","msg":"trace[1839254976] transaction","detail":"{read_only:false; response_revision:6683; number_of_response:1; }","duration":"148.284928ms","start":"2026-06-18T12:41:07.018395Z","end":"2026-06-18T12:41:07.16668Z","steps":["trace[1839254976] 'process raft request' (duration: 147.682644ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:43:24.452221Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5559} {"level":"info","ts":"2026-06-18T12:43:24.542026Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5559,"took":"87.289935ms","hash":1000249427,"current-db-size-bytes":53821440,"current-db-size":"54 MB","current-db-size-in-use-bytes":18223104,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-18T12:43:24.542101Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1000249427,"revision":5559,"compact-revision":2880}