{"level":"warn","ts":"2026-06-10T14:58:16.595896Z","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-10T14:58:16.596101Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-10T14:58:16.596268Z","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-10T14:58:16.596305Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-06-10T14:58:16.596437Z","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-10T14:58:16.597397Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-06-10T14:58:16.597578Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-10T14:58:16.60127Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.365716ms"} {"level":"info","ts":"2026-06-10T14:58:16.606198Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-06-10T14:58:16.606357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-06-10T14:58:16.606434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-06-10T14:58:16.6065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-10T14:58:16.606551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-06-10T14:58:16.606633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-06-10T14:58:16.611993Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-10T14:58:16.613361Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-10T14:58:16.61458Z","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-10T14:58:16.616606Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-10T14:58:16.616972Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-10T14:58:16.616937Z","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-10T14:58:16.617087Z","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-10T14:58:16.61712Z","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-10T14:58:16.618459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-06-10T14:58:16.619364Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-06-10T14:58:16.622207Z","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-10T14:58:16.622419Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-06-10T14:58:16.622486Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-06-10T14:58:16.622736Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-10T14:58:16.622821Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-10T14:58:16.907218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-06-10T14:58:16.907285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-06-10T14:58:16.907333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-06-10T14:58:16.907354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-06-10T14:58:16.907366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-06-10T14:58:16.907382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-06-10T14:58:16.907396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-06-10T14:58:16.90855Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-06-10T14:58:16.908553Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T14:58:16.908577Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T14:58:16.908961Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-10T14:58:16.909129Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-10T14:58:16.90925Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T14:58:16.912681Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T14:58:16.913264Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T14:58:16.913371Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T14:58:16.912782Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-06-10T14:58:16.913031Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-10T14:58:35.36903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.656896ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-10T14:58:35.369184Z","caller":"traceutil/trace.go:171","msg":"trace[148157880] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"196.367865ms","start":"2026-06-10T14:58:35.172792Z","end":"2026-06-10T14:58:35.36916Z","steps":["trace[148157880] 'read index received' (duration: 34.26758ms)","trace[148157880] 'applied index is now lower than readState.Index' (duration: 162.098755ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T14:58:35.369294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.491938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T14:58:35.369393Z","caller":"traceutil/trace.go:171","msg":"trace[2069013999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"196.603261ms","start":"2026-06-10T14:58:35.172775Z","end":"2026-06-10T14:58:35.369378Z","steps":["trace[2069013999] 'agreement among raft nodes before linearized reading' (duration: 196.453907ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T14:58:36.12044Z","caller":"traceutil/trace.go:171","msg":"trace[1253328169] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"741.455451ms","start":"2026-06-10T14:58:35.378946Z","end":"2026-06-10T14:58:36.120401Z","steps":["trace[1253328169] 'process raft request' (duration: 741.178235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T14:58:36.121353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T14:58:35.378929Z","time spent":"741.616534ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T14:59:01.564663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.620178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-10T14:59:01.564829Z","caller":"traceutil/trace.go:171","msg":"trace[127647130] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"226.37795ms","start":"2026-06-10T14:59:01.338403Z","end":"2026-06-10T14:59:01.564781Z","steps":["trace[127647130] 'process raft request' (duration: 26.548218ms)","trace[127647130] 'compare' (duration: 199.470525ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T14:59:02.204643Z","caller":"traceutil/trace.go:171","msg":"trace[1136958380] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"342.207714ms","start":"2026-06-10T14:59:01.862408Z","end":"2026-06-10T14:59:02.204615Z","steps":["trace[1136958380] 'process raft request' (duration: 342.03561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T14:59:02.204822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T14:59:01.862378Z","time spent":"342.349116ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T14:59:02.333743Z","caller":"traceutil/trace.go:171","msg":"trace[477844590] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"159.700479ms","start":"2026-06-10T14:59:02.174017Z","end":"2026-06-10T14:59:02.333717Z","steps":["trace[477844590] 'read index received' (duration: 30.635348ms)","trace[477844590] 'applied index is now lower than readState.Index' (duration: 129.064431ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T14:59:02.333837Z","caller":"traceutil/trace.go:171","msg":"trace[1247778420] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"330.129117ms","start":"2026-06-10T14:59:02.003662Z","end":"2026-06-10T14:59:02.333791Z","steps":["trace[1247778420] 'process raft request' (duration: 319.01967ms)","trace[1247778420] 'compare' (duration: 10.612948ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T14:59:02.333918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.886413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T14:59:02.334003Z","caller":"traceutil/trace.go:171","msg":"trace[1764479459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"160.034146ms","start":"2026-06-10T14:59:02.173945Z","end":"2026-06-10T14:59:02.333979Z","steps":["trace[1764479459] 'agreement among raft nodes before linearized reading' (duration: 159.864753ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T14:59:02.334027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T14:59:02.003627Z","time spent":"330.300281ms","remote":"127.0.0.1:37908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T14:59:17.385215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.773255ms","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-10T14:59:17.385348Z","caller":"traceutil/trace.go:171","msg":"trace[1041183911] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"255.971239ms","start":"2026-06-10T14:59:17.129348Z","end":"2026-06-10T14:59:17.38532Z","steps":["trace[1041183911] 'range keys from in-memory index tree' (duration: 255.55154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T14:59:17.385221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.263987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T14:59:17.385585Z","caller":"traceutil/trace.go:171","msg":"trace[748401278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"211.687185ms","start":"2026-06-10T14:59:17.173874Z","end":"2026-06-10T14:59:17.385561Z","steps":["trace[748401278] 'range keys from in-memory index tree' (duration: 211.195695ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:00:35.679461Z","caller":"traceutil/trace.go:171","msg":"trace[417396123] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"313.634234ms","start":"2026-06-10T15:00:35.365805Z","end":"2026-06-10T15:00:35.67944Z","steps":["trace[417396123] 'process raft request' (duration: 313.47538ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:00:35.679612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:00:35.365794Z","time spent":"313.741607ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:00:35.697018Z","caller":"traceutil/trace.go:171","msg":"trace[1435339397] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1965; }","duration":"197.518757ms","start":"2026-06-10T15:00:35.499466Z","end":"2026-06-10T15:00:35.696984Z","steps":["trace[1435339397] 'read index received' (duration: 179.831065ms)","trace[1435339397] 'applied index is now lower than readState.Index' (duration: 17.686512ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:00:35.697072Z","caller":"traceutil/trace.go:171","msg":"trace[2037234316] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"242.708625ms","start":"2026-06-10T15:00:35.454327Z","end":"2026-06-10T15:00:35.697036Z","steps":["trace[2037234316] 'process raft request' (duration: 242.4253ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:00:35.697127Z","caller":"traceutil/trace.go:171","msg":"trace[1971588270] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"118.564967ms","start":"2026-06-10T15:00:35.57853Z","end":"2026-06-10T15:00:35.697094Z","steps":["trace[1971588270] 'process raft request' (duration: 118.407244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:00:35.6972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.713491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T15:00:35.697246Z","caller":"traceutil/trace.go:171","msg":"trace[1288405620] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1894; }","duration":"197.828553ms","start":"2026-06-10T15:00:35.499403Z","end":"2026-06-10T15:00:35.697231Z","steps":["trace[1288405620] 'agreement among raft nodes before linearized reading' (duration: 197.728901ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:00:36.092241Z","caller":"traceutil/trace.go:171","msg":"trace[959253577] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"144.161546ms","start":"2026-06-10T15:00:35.948033Z","end":"2026-06-10T15:00:36.092194Z","steps":["trace[959253577] 'process raft request' (duration: 143.916802ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:00:52.098078Z","caller":"traceutil/trace.go:171","msg":"trace[552037073] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"339.54684ms","start":"2026-06-10T15:00:51.758504Z","end":"2026-06-10T15:00:52.098051Z","steps":["trace[552037073] 'process raft request' (duration: 339.361786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:00:52.098247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:00:51.758486Z","time spent":"339.669002ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:00:52.127662Z","caller":"traceutil/trace.go:171","msg":"trace[1794210801] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"367.418463ms","start":"2026-06-10T15:00:51.760219Z","end":"2026-06-10T15:00:52.127637Z","steps":["trace[1794210801] 'process raft request' (duration: 367.26942ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:00:52.127833Z","caller":"traceutil/trace.go:171","msg":"trace[481384806] linearizableReadLoop","detail":"{readStateIndex:2104; appliedIndex:2102; }","duration":"230.559343ms","start":"2026-06-10T15:00:51.897243Z","end":"2026-06-10T15:00:52.127802Z","steps":["trace[481384806] 'read index received' (duration: 200.661768ms)","trace[481384806] 'applied index is now lower than readState.Index' (duration: 29.895194ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:00:52.127862Z","caller":"traceutil/trace.go:171","msg":"trace[1446732353] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"173.903287ms","start":"2026-06-10T15:00:51.953935Z","end":"2026-06-10T15:00:52.127838Z","steps":["trace[1446732353] 'process raft request' (duration: 173.667243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:00:52.127898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:00:51.760191Z","time spent":"367.539216ms","remote":"127.0.0.1:38078","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-10T15:00:52.128131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.843258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11987"} {"level":"info","ts":"2026-06-10T15:00:52.128216Z","caller":"traceutil/trace.go:171","msg":"trace[1134648535] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2027; }","duration":"230.993011ms","start":"2026-06-10T15:00:51.897205Z","end":"2026-06-10T15:00:52.128198Z","steps":["trace[1134648535] 'agreement among raft nodes before linearized reading' (duration: 230.736436ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:01:30.372925Z","caller":"traceutil/trace.go:171","msg":"trace[622918785] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"127.960184ms","start":"2026-06-10T15:01:30.244941Z","end":"2026-06-10T15:01:30.372901Z","steps":["trace[622918785] 'process raft request' (duration: 127.77046ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:01:30.458866Z","caller":"traceutil/trace.go:171","msg":"trace[1125998439] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"198.103746ms","start":"2026-06-10T15:01:30.260728Z","end":"2026-06-10T15:01:30.458832Z","steps":["trace[1125998439] 'process raft request' (duration: 190.84888ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:02:47.082229Z","caller":"traceutil/trace.go:171","msg":"trace[283318776] linearizableReadLoop","detail":"{readStateIndex:2954; appliedIndex:2953; }","duration":"132.738288ms","start":"2026-06-10T15:02:46.949458Z","end":"2026-06-10T15:02:47.082196Z","steps":["trace[283318776] 'read index received' (duration: 132.496063ms)","trace[283318776] 'applied index is now lower than readState.Index' (duration: 240.905µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:02:47.082358Z","caller":"traceutil/trace.go:171","msg":"trace[1758688632] transaction","detail":"{read_only:false; response_revision:2845; number_of_response:1; }","duration":"135.242869ms","start":"2026-06-10T15:02:46.947066Z","end":"2026-06-10T15:02:47.082309Z","steps":["trace[1758688632] 'process raft request' (duration: 134.948993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:02:47.08259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.109286ms","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-10T15:02:47.082656Z","caller":"traceutil/trace.go:171","msg":"trace[378845006] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2845; }","duration":"133.240128ms","start":"2026-06-10T15:02:46.9494Z","end":"2026-06-10T15:02:47.08264Z","steps":["trace[378845006] 'agreement among raft nodes before linearized reading' (duration: 132.978392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:02:47.082778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.218366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T15:02:47.082947Z","caller":"traceutil/trace.go:171","msg":"trace[773826812] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2845; }","duration":"109.493861ms","start":"2026-06-10T15:02:46.973423Z","end":"2026-06-10T15:02:47.082916Z","steps":["trace[773826812] 'agreement among raft nodes before linearized reading' (duration: 109.091123ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:02:47.260414Z","caller":"traceutil/trace.go:171","msg":"trace[1665398748] linearizableReadLoop","detail":"{readStateIndex:2955; appliedIndex:2954; }","duration":"170.405225ms","start":"2026-06-10T15:02:47.089976Z","end":"2026-06-10T15:02:47.260381Z","steps":["trace[1665398748] 'read index received' (duration: 128.164476ms)","trace[1665398748] 'applied index is now lower than readState.Index' (duration: 42.239229ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:02:47.260438Z","caller":"traceutil/trace.go:171","msg":"trace[1346178556] transaction","detail":"{read_only:false; response_revision:2846; number_of_response:1; }","duration":"170.491417ms","start":"2026-06-10T15:02:47.089894Z","end":"2026-06-10T15:02:47.260385Z","steps":["trace[1346178556] 'process raft request' (duration: 128.35727ms)","trace[1346178556] 'compare' (duration: 41.993994ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:02:47.260758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.720662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-06-10T15:02:47.260893Z","caller":"traceutil/trace.go:171","msg":"trace[1502577217] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2846; }","duration":"170.948277ms","start":"2026-06-10T15:02:47.089919Z","end":"2026-06-10T15:02:47.260867Z","steps":["trace[1502577217] 'agreement among raft nodes before linearized reading' (duration: 170.566019ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:05:28.377172Z","caller":"traceutil/trace.go:171","msg":"trace[1341486232] transaction","detail":"{read_only:false; response_revision:3897; number_of_response:1; }","duration":"148.843737ms","start":"2026-06-10T15:05:28.2283Z","end":"2026-06-10T15:05:28.377144Z","steps":["trace[1341486232] 'process raft request' (duration: 148.676284ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:07.765967Z","caller":"traceutil/trace.go:171","msg":"trace[1104022665] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"111.22487ms","start":"2026-06-10T15:07:07.654703Z","end":"2026-06-10T15:07:07.765928Z","steps":["trace[1104022665] 'process raft request' (duration: 47.46304ms)","trace[1104022665] 'compare' (duration: 63.399312ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:07:07.766048Z","caller":"traceutil/trace.go:171","msg":"trace[1180919046] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"110.941794ms","start":"2026-06-10T15:07:07.655055Z","end":"2026-06-10T15:07:07.765997Z","steps":["trace[1180919046] 'process raft request' (duration: 110.790091ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:07.766069Z","caller":"traceutil/trace.go:171","msg":"trace[1935970683] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"110.658807ms","start":"2026-06-10T15:07:07.655374Z","end":"2026-06-10T15:07:07.766033Z","steps":["trace[1935970683] 'process raft request' (duration: 110.563755ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:07.766216Z","caller":"traceutil/trace.go:171","msg":"trace[1468239035] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"110.737749ms","start":"2026-06-10T15:07:07.655453Z","end":"2026-06-10T15:07:07.766191Z","steps":["trace[1468239035] 'process raft request' (duration: 110.514065ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:07.766393Z","caller":"traceutil/trace.go:171","msg":"trace[1126912493] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"110.766779ms","start":"2026-06-10T15:07:07.655617Z","end":"2026-06-10T15:07:07.766384Z","steps":["trace[1126912493] 'process raft request' (duration: 110.393052ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:07.766413Z","caller":"traceutil/trace.go:171","msg":"trace[1284577683] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"110.2532ms","start":"2026-06-10T15:07:07.656143Z","end":"2026-06-10T15:07:07.766396Z","steps":["trace[1284577683] 'process raft request' (duration: 109.904752ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:07.766427Z","caller":"traceutil/trace.go:171","msg":"trace[816683345] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"103.227733ms","start":"2026-06-10T15:07:07.663181Z","end":"2026-06-10T15:07:07.766409Z","steps":["trace[816683345] 'process raft request' (duration: 102.901946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:07:12.373488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.851756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T15:07:12.373622Z","caller":"traceutil/trace.go:171","msg":"trace[76218751] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5175; }","duration":"116.065911ms","start":"2026-06-10T15:07:12.25753Z","end":"2026-06-10T15:07:12.373596Z","steps":["trace[76218751] 'range keys from in-memory index tree' (duration: 115.719303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:07:12.373753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.432176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b41e04d6-1d03-4d87-9bcd-b47ca2aa5a8e\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-06-10T15:07:12.37388Z","caller":"traceutil/trace.go:171","msg":"trace[2044498760] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b41e04d6-1d03-4d87-9bcd-b47ca2aa5a8e; range_end:; response_count:1; response_revision:5175; }","duration":"185.61052ms","start":"2026-06-10T15:07:12.188239Z","end":"2026-06-10T15:07:12.37385Z","steps":["trace[2044498760] 'range keys from in-memory index tree' (duration: 185.253323ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:07:12.374105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.767438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-6zj5c-tls\" ","response":"range_response_count:1 size:1966"} {"level":"info","ts":"2026-06-10T15:07:12.374197Z","caller":"traceutil/trace.go:171","msg":"trace[2040284109] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-6zj5c-tls; range_end:; response_count:1; response_revision:5175; }","duration":"119.907521ms","start":"2026-06-10T15:07:12.254264Z","end":"2026-06-10T15:07:12.374171Z","steps":["trace[2040284109] 'range keys from in-memory index tree' (duration: 119.618964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:07:27.626805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.809876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-10T15:07:27.626815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.655351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T15:07:27.626879Z","caller":"traceutil/trace.go:171","msg":"trace[739340335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5466; }","duration":"198.942099ms","start":"2026-06-10T15:07:27.427923Z","end":"2026-06-10T15:07:27.626865Z","steps":["trace[739340335] 'range keys from in-memory index tree' (duration: 198.586082ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:27.626909Z","caller":"traceutil/trace.go:171","msg":"trace[1058536085] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5466; }","duration":"183.839264ms","start":"2026-06-10T15:07:27.443043Z","end":"2026-06-10T15:07:27.626882Z","steps":["trace[1058536085] 'count revisions from in-memory index tree' (duration: 183.288023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:07:27.626972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.929006ms","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-10T15:07:27.627063Z","caller":"traceutil/trace.go:171","msg":"trace[870479541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5466; }","duration":"195.087979ms","start":"2026-06-10T15:07:27.431953Z","end":"2026-06-10T15:07:27.627041Z","steps":["trace[870479541] 'range keys from in-memory index tree' (duration: 194.587409ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:35.128452Z","caller":"traceutil/trace.go:171","msg":"trace[1208972615] linearizableReadLoop","detail":"{readStateIndex:5846; appliedIndex:5845; }","duration":"242.255052ms","start":"2026-06-10T15:07:34.886149Z","end":"2026-06-10T15:07:35.128405Z","steps":["trace[1208972615] 'read index received' (duration: 228.380683ms)","trace[1208972615] 'applied index is now lower than readState.Index' (duration: 13.872439ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:07:35.128564Z","caller":"traceutil/trace.go:171","msg":"trace[1143924212] transaction","detail":"{read_only:false; response_revision:5636; number_of_response:1; }","duration":"290.281294ms","start":"2026-06-10T15:07:34.838222Z","end":"2026-06-10T15:07:35.128504Z","steps":["trace[1143924212] 'process raft request' (duration: 276.341603ms)","trace[1143924212] 'compare' (duration: 13.638225ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:07:35.128886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.715812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-10T15:07:35.128987Z","caller":"traceutil/trace.go:171","msg":"trace[1052257453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5636; }","duration":"242.880745ms","start":"2026-06-10T15:07:34.886087Z","end":"2026-06-10T15:07:35.128968Z","steps":["trace[1052257453] 'agreement among raft nodes before linearized reading' (duration: 242.551679ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:35.134801Z","caller":"traceutil/trace.go:171","msg":"trace[822266153] transaction","detail":"{read_only:false; response_revision:5637; number_of_response:1; }","duration":"167.022063ms","start":"2026-06-10T15:07:34.967756Z","end":"2026-06-10T15:07:35.134778Z","steps":["trace[822266153] 'process raft request' (duration: 166.83221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:07:35.13525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.949506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6d314166-07d8-4d9b-9692-559a7d2d93ae\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-06-10T15:07:35.135297Z","caller":"traceutil/trace.go:171","msg":"trace[877082207] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6d314166-07d8-4d9b-9692-559a7d2d93ae; range_end:; response_count:1; response_revision:5637; }","duration":"196.292703ms","start":"2026-06-10T15:07:34.938991Z","end":"2026-06-10T15:07:35.135284Z","steps":["trace[877082207] 'agreement among raft nodes before linearized reading' (duration: 195.963046ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:07:35.452179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.682429ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-10T15:07:35.452724Z","caller":"traceutil/trace.go:171","msg":"trace[1980595423] linearizableReadLoop","detail":"{readStateIndex:5849; appliedIndex:5848; }","duration":"142.293847ms","start":"2026-06-10T15:07:35.310394Z","end":"2026-06-10T15:07:35.452688Z","steps":["trace[1980595423] 'read index received' (duration: 76.532µs)","trace[1980595423] 'applied index is now lower than readState.Index' (duration: 142.213755ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:07:35.452922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.508191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T15:07:35.453031Z","caller":"traceutil/trace.go:171","msg":"trace[1697670063] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:5638; }","duration":"142.688984ms","start":"2026-06-10T15:07:35.310319Z","end":"2026-06-10T15:07:35.453008Z","steps":["trace[1697670063] 'agreement among raft nodes before linearized reading' (duration: 142.511491ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:08:16.951968Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3010} {"level":"info","ts":"2026-06-10T15:08:17.034946Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3010,"took":"81.555041ms","hash":5286690,"current-db-size-bytes":60764160,"current-db-size":"61 MB","current-db-size-in-use-bytes":34074624,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-10T15:08:17.035039Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":5286690,"revision":3010,"compact-revision":-1} {"level":"info","ts":"2026-06-10T15:13:16.958151Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6046} {"level":"info","ts":"2026-06-10T15:13:17.05883Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6046,"took":"98.728423ms","hash":2855617721,"current-db-size-bytes":60764160,"current-db-size":"61 MB","current-db-size-in-use-bytes":22065152,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-10T15:13:17.058889Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2855617721,"revision":6046,"compact-revision":3010} {"level":"info","ts":"2026-06-10T15:13:37.640201Z","caller":"traceutil/trace.go:171","msg":"trace[1867994393] transaction","detail":"{read_only:false; response_revision:8264; number_of_response:1; }","duration":"215.659962ms","start":"2026-06-10T15:13:37.424509Z","end":"2026-06-10T15:13:37.640169Z","steps":["trace[1867994393] 'process raft request' (duration: 215.469559ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:13:41.108835Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000218b.wal"} {"level":"warn","ts":"2026-06-10T15:13:45.399403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.786954ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-10T15:13:45.399581Z","caller":"traceutil/trace.go:171","msg":"trace[2063172082] linearizableReadLoop","detail":"{readStateIndex:8742; appliedIndex:8741; }","duration":"176.600886ms","start":"2026-06-10T15:13:45.222957Z","end":"2026-06-10T15:13:45.399558Z","steps":["trace[2063172082] 'read index received' (duration: 71.567157ms)","trace[2063172082] 'applied index is now lower than readState.Index' (duration: 105.031999ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:13:45.399678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.721269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T15:13:45.399707Z","caller":"traceutil/trace.go:171","msg":"trace[1014078759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8441; }","duration":"176.79987ms","start":"2026-06-10T15:13:45.222895Z","end":"2026-06-10T15:13:45.399695Z","steps":["trace[1014078759] 'agreement among raft nodes before linearized reading' (duration: 176.720368ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:13:58.156828Z","caller":"traceutil/trace.go:171","msg":"trace[1377125020] transaction","detail":"{read_only:false; response_revision:8509; number_of_response:1; }","duration":"240.59835ms","start":"2026-06-10T15:13:57.916174Z","end":"2026-06-10T15:13:58.156772Z","steps":["trace[1377125020] 'process raft request' (duration: 240.302964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:14:27.226252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.539426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-10T15:14:27.226345Z","caller":"traceutil/trace.go:171","msg":"trace[1951028245] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8715; }","duration":"341.694719ms","start":"2026-06-10T15:14:26.884631Z","end":"2026-06-10T15:14:27.226326Z","steps":["trace[1951028245] 'range keys from in-memory index tree' (duration: 341.441444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:14:27.226381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:14:26.884619Z","time spent":"341.75269ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T15:14:27.226977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.608901ms","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-10T15:14:27.227023Z","caller":"traceutil/trace.go:171","msg":"trace[201637338] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8715; }","duration":"131.698522ms","start":"2026-06-10T15:14:27.095311Z","end":"2026-06-10T15:14:27.22701Z","steps":["trace[201637338] 'count revisions from in-memory index tree' (duration: 131.536178ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:14:29.403967Z","caller":"traceutil/trace.go:171","msg":"trace[2103384203] linearizableReadLoop","detail":"{readStateIndex:9039; appliedIndex:9038; }","duration":"107.763245ms","start":"2026-06-10T15:14:29.296175Z","end":"2026-06-10T15:14:29.403938Z","steps":["trace[2103384203] 'read index received' (duration: 107.647912ms)","trace[2103384203] 'applied index is now lower than readState.Index' (duration: 113.663µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:14:29.404081Z","caller":"traceutil/trace.go:171","msg":"trace[1072470593] transaction","detail":"{read_only:false; response_revision:8727; number_of_response:1; }","duration":"115.025122ms","start":"2026-06-10T15:14:29.289003Z","end":"2026-06-10T15:14:29.404028Z","steps":["trace[1072470593] 'process raft request' (duration: 114.748137ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:14:29.404245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.04393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-06-10T15:14:29.404296Z","caller":"traceutil/trace.go:171","msg":"trace[984808178] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8727; }","duration":"108.159133ms","start":"2026-06-10T15:14:29.29612Z","end":"2026-06-10T15:14:29.404279Z","steps":["trace[984808178] 'agreement among raft nodes before linearized reading' (duration: 107.985379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:14:29.40438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.0141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wqhhf\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-06-10T15:14:29.404497Z","caller":"traceutil/trace.go:171","msg":"trace[1339424650] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wqhhf; range_end:; response_count:1; response_revision:8727; }","duration":"108.168962ms","start":"2026-06-10T15:14:29.2963Z","end":"2026-06-10T15:14:29.404469Z","steps":["trace[1339424650] 'agreement among raft nodes before linearized reading' (duration: 107.836286ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:14:29.80115Z","caller":"traceutil/trace.go:171","msg":"trace[1541417840] transaction","detail":"{read_only:false; response_revision:8729; number_of_response:1; }","duration":"137.873398ms","start":"2026-06-10T15:14:29.663237Z","end":"2026-06-10T15:14:29.801111Z","steps":["trace[1541417840] 'process raft request' (duration: 137.695104ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:14:29.985244Z","caller":"traceutil/trace.go:171","msg":"trace[431147090] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"256.901371ms","start":"2026-06-10T15:14:29.728317Z","end":"2026-06-10T15:14:29.985219Z","steps":["trace[431147090] 'process raft request' (duration: 256.734518ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:14:40.163724Z","caller":"traceutil/trace.go:171","msg":"trace[1116294695] linearizableReadLoop","detail":"{readStateIndex:9142; appliedIndex:9141; }","duration":"128.598159ms","start":"2026-06-10T15:14:40.035099Z","end":"2026-06-10T15:14:40.163697Z","steps":["trace[1116294695] 'read index received' (duration: 49.483758ms)","trace[1116294695] 'applied index is now lower than readState.Index' (duration: 79.112961ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:14:40.164019Z","caller":"traceutil/trace.go:171","msg":"trace[1220808136] transaction","detail":"{read_only:false; response_revision:8825; number_of_response:1; }","duration":"137.001501ms","start":"2026-06-10T15:14:40.026987Z","end":"2026-06-10T15:14:40.163989Z","steps":["trace[1220808136] 'process raft request' (duration: 57.692656ms)","trace[1220808136] 'compare' (duration: 78.830945ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:14:40.164064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.945916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T15:14:40.164148Z","caller":"traceutil/trace.go:171","msg":"trace[1518229753] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:8825; }","duration":"129.089148ms","start":"2026-06-10T15:14:40.035041Z","end":"2026-06-10T15:14:40.16413Z","steps":["trace[1518229753] 'agreement among raft nodes before linearized reading' (duration: 128.952396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:15:58.206773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.428026ms","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-10T15:15:58.206885Z","caller":"traceutil/trace.go:171","msg":"trace[253447169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9574; }","duration":"127.62512ms","start":"2026-06-10T15:15:58.07924Z","end":"2026-06-10T15:15:58.206865Z","steps":["trace[253447169] 'range keys from in-memory index tree' (duration: 127.12719ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:16:08.312125Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T15:16:08.318127Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-10T15:16:08.318254Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-10T15:18:16.97157Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8114} {"level":"info","ts":"2026-06-10T15:18:17.033211Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8114,"took":"59.094506ms","hash":3577167034,"current-db-size-bytes":60764160,"current-db-size":"61 MB","current-db-size-in-use-bytes":28745728,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-10T15:18:17.033269Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3577167034,"revision":8114,"compact-revision":6046} {"level":"warn","ts":"2026-06-10T15:21:40.55676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.535329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T15:21:40.556888Z","caller":"traceutil/trace.go:171","msg":"trace[1660204210] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:12021; }","duration":"145.722682ms","start":"2026-06-10T15:21:40.411145Z","end":"2026-06-10T15:21:40.556867Z","steps":["trace[1660204210] 'count revisions from in-memory index tree' (duration: 145.428336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:21:40.556968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.279064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-06-10T15:21:40.557032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.740054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-10T15:21:40.55705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.356045ms","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-10T15:21:40.557118Z","caller":"traceutil/trace.go:171","msg":"trace[1840525416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12021; }","duration":"151.873087ms","start":"2026-06-10T15:21:40.405226Z","end":"2026-06-10T15:21:40.5571Z","steps":["trace[1840525416] 'range keys from in-memory index tree' (duration: 151.55049ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:21:40.557131Z","caller":"traceutil/trace.go:171","msg":"trace[400774291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12021; }","duration":"149.512039ms","start":"2026-06-10T15:21:40.4076Z","end":"2026-06-10T15:21:40.557112Z","steps":["trace[400774291] 'range keys from in-memory index tree' (duration: 149.169712ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:21:40.557062Z","caller":"traceutil/trace.go:171","msg":"trace[1577182997] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12021; }","duration":"199.441557ms","start":"2026-06-10T15:21:40.357599Z","end":"2026-06-10T15:21:40.55704Z","steps":["trace[1577182997] 'range keys from in-memory index tree' (duration: 199.07909ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:21:40.72985Z","caller":"traceutil/trace.go:171","msg":"trace[1195222821] transaction","detail":"{read_only:false; response_revision:12025; number_of_response:1; }","duration":"106.971902ms","start":"2026-06-10T15:21:40.622843Z","end":"2026-06-10T15:21:40.729815Z","steps":["trace[1195222821] 'process raft request' (duration: 36.242569ms)","trace[1195222821] 'compare' (duration: 70.565069ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:23:16.977914Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10507} {"level":"info","ts":"2026-06-10T15:23:17.059581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10507,"took":"78.72839ms","hash":2820473493,"current-db-size-bytes":60764160,"current-db-size":"61 MB","current-db-size-in-use-bytes":27070464,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-10T15:23:17.059639Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2820473493,"revision":10507,"compact-revision":8114} {"level":"info","ts":"2026-06-10T15:27:42.336546Z","caller":"traceutil/trace.go:171","msg":"trace[998149960] transaction","detail":"{read_only:false; response_revision:14466; number_of_response:1; }","duration":"101.453874ms","start":"2026-06-10T15:27:42.235064Z","end":"2026-06-10T15:27:42.336518Z","steps":["trace[998149960] 'process raft request' (duration: 93.876709ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:28:01.949408Z","caller":"traceutil/trace.go:171","msg":"trace[1431866939] transaction","detail":"{read_only:false; response_revision:14573; number_of_response:1; }","duration":"207.278449ms","start":"2026-06-10T15:28:01.7421Z","end":"2026-06-10T15:28:01.949379Z","steps":["trace[1431866939] 'process raft request' (duration: 207.150176ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:28:16.985545Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12666} {"level":"info","ts":"2026-06-10T15:28:17.072608Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12666,"took":"84.345175ms","hash":1721810855,"current-db-size-bytes":60764160,"current-db-size":"61 MB","current-db-size-in-use-bytes":27934720,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-10T15:28:17.072668Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1721810855,"revision":12666,"compact-revision":10507} {"level":"info","ts":"2026-06-10T15:28:18.872049Z","caller":"traceutil/trace.go:171","msg":"trace[3169988] linearizableReadLoop","detail":"{readStateIndex:15189; appliedIndex:15188; }","duration":"108.168531ms","start":"2026-06-10T15:28:18.763858Z","end":"2026-06-10T15:28:18.872027Z","steps":["trace[3169988] 'read index received' (duration: 108.045809ms)","trace[3169988] 'applied index is now lower than readState.Index' (duration: 121.352µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:28:18.872111Z","caller":"traceutil/trace.go:171","msg":"trace[2023557098] transaction","detail":"{read_only:false; response_revision:14675; number_of_response:1; }","duration":"108.399936ms","start":"2026-06-10T15:28:18.76367Z","end":"2026-06-10T15:28:18.87207Z","steps":["trace[2023557098] 'process raft request' (duration: 108.213802ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:28:18.872311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.420416ms","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-10T15:28:18.872349Z","caller":"traceutil/trace.go:171","msg":"trace[433481941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14675; }","duration":"108.509099ms","start":"2026-06-10T15:28:18.763832Z","end":"2026-06-10T15:28:18.872341Z","steps":["trace[433481941] 'agreement among raft nodes before linearized reading' (duration: 108.337385ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:28:18.87256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.052408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-10T15:28:18.872664Z","caller":"traceutil/trace.go:171","msg":"trace[1879262274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14675; }","duration":"107.215172ms","start":"2026-06-10T15:28:18.765429Z","end":"2026-06-10T15:28:18.872644Z","steps":["trace[1879262274] 'agreement among raft nodes before linearized reading' (duration: 106.920426ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:28:19.090219Z","caller":"traceutil/trace.go:171","msg":"trace[1340309634] linearizableReadLoop","detail":"{readStateIndex:15191; appliedIndex:15189; }","duration":"169.776141ms","start":"2026-06-10T15:28:18.920412Z","end":"2026-06-10T15:28:19.090188Z","steps":["trace[1340309634] 'read index received' (duration: 141.403651ms)","trace[1340309634] 'applied index is now lower than readState.Index' (duration: 28.3714ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:28:19.090334Z","caller":"traceutil/trace.go:171","msg":"trace[1359633055] transaction","detail":"{read_only:false; response_revision:14676; number_of_response:1; }","duration":"213.351931ms","start":"2026-06-10T15:28:18.876934Z","end":"2026-06-10T15:28:19.090286Z","steps":["trace[1359633055] 'process raft request' (duration: 184.692135ms)","trace[1359633055] 'compare' (duration: 28.315669ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:28:19.090664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.22065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T15:28:19.090708Z","caller":"traceutil/trace.go:171","msg":"trace[1722431562] transaction","detail":"{read_only:false; response_revision:14677; number_of_response:1; }","duration":"213.478454ms","start":"2026-06-10T15:28:18.877193Z","end":"2026-06-10T15:28:19.090672Z","steps":["trace[1722431562] 'process raft request' (duration: 212.941973ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:28:19.090848Z","caller":"traceutil/trace.go:171","msg":"trace[1277993700] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14677; }","duration":"170.411944ms","start":"2026-06-10T15:28:18.920358Z","end":"2026-06-10T15:28:19.09077Z","steps":["trace[1277993700] 'agreement among raft nodes before linearized reading' (duration: 170.061397ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:28:42.468275Z","caller":"traceutil/trace.go:171","msg":"trace[446879801] transaction","detail":"{read_only:false; response_revision:14989; number_of_response:1; }","duration":"301.809959ms","start":"2026-06-10T15:28:42.16644Z","end":"2026-06-10T15:28:42.46825Z","steps":["trace[446879801] 'process raft request' (duration: 301.670176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:28:42.468457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:28:42.166393Z","time spent":"301.970582ms","remote":"127.0.0.1:38078","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-10T15:28:42.468721Z","caller":"traceutil/trace.go:171","msg":"trace[1977682101] linearizableReadLoop","detail":"{readStateIndex:15516; appliedIndex:15516; }","duration":"294.938529ms","start":"2026-06-10T15:28:42.173723Z","end":"2026-06-10T15:28:42.468661Z","steps":["trace[1977682101] 'read index received' (duration: 294.918949ms)","trace[1977682101] 'applied index is now lower than readState.Index' (duration: 15.55µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:28:42.468834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.099622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T15:28:42.468894Z","caller":"traceutil/trace.go:171","msg":"trace[1563397034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14989; }","duration":"295.162514ms","start":"2026-06-10T15:28:42.173718Z","end":"2026-06-10T15:28:42.468881Z","steps":["trace[1563397034] 'agreement among raft nodes before linearized reading' (duration: 295.047981ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:30:09.203972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.340363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T15:30:09.20421Z","caller":"traceutil/trace.go:171","msg":"trace[509101275] transaction","detail":"{read_only:false; response_revision:15797; number_of_response:1; }","duration":"282.812634ms","start":"2026-06-10T15:30:08.92138Z","end":"2026-06-10T15:30:09.204193Z","steps":["trace[509101275] 'process raft request' (duration: 76.969381ms)","trace[509101275] 'compare' (duration: 205.084128ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:30:09.204462Z","caller":"traceutil/trace.go:171","msg":"trace[1181318281] linearizableReadLoop","detail":"{readStateIndex:16347; appliedIndex:16346; }","duration":"241.372153ms","start":"2026-06-10T15:30:08.962688Z","end":"2026-06-10T15:30:09.204061Z","steps":["trace[1181318281] 'read index received' (duration: 35.568821ms)","trace[1181318281] 'applied index is now lower than readState.Index' (duration: 205.801772ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:30:09.204556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.871183ms","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-10T15:30:09.204581Z","caller":"traceutil/trace.go:171","msg":"trace[1832368939] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15797; }","duration":"241.935025ms","start":"2026-06-10T15:30:08.962639Z","end":"2026-06-10T15:30:09.204574Z","steps":["trace[1832368939] 'agreement among raft nodes before linearized reading' (duration: 241.877103ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:33:16.995192Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14651} {"level":"info","ts":"2026-06-10T15:33:17.076559Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14651,"took":"76.86493ms","hash":3607596748,"current-db-size-bytes":60764160,"current-db-size":"61 MB","current-db-size-in-use-bytes":34607104,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-10T15:33:17.076654Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3607596748,"revision":14651,"compact-revision":12666} {"level":"info","ts":"2026-06-10T15:36:04.714134Z","caller":"traceutil/trace.go:171","msg":"trace[43609265] transaction","detail":"{read_only:false; response_revision:18412; number_of_response:1; }","duration":"149.328927ms","start":"2026-06-10T15:36:04.564779Z","end":"2026-06-10T15:36:04.714108Z","steps":["trace[43609265] 'process raft request' (duration: 149.136343ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:36:11.165336Z","caller":"traceutil/trace.go:171","msg":"trace[265928348] linearizableReadLoop","detail":"{readStateIndex:19090; appliedIndex:19089; }","duration":"146.403197ms","start":"2026-06-10T15:36:11.018898Z","end":"2026-06-10T15:36:11.165301Z","steps":["trace[265928348] 'read index received' (duration: 146.122982ms)","trace[265928348] 'applied index is now lower than readState.Index' (duration: 278.525µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:36:11.165512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.583441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T15:36:11.165482Z","caller":"traceutil/trace.go:171","msg":"trace[592629026] transaction","detail":"{read_only:false; response_revision:18453; number_of_response:1; }","duration":"201.808933ms","start":"2026-06-10T15:36:10.96361Z","end":"2026-06-10T15:36:11.165419Z","steps":["trace[592629026] 'process raft request' (duration: 201.494037ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:36:11.165553Z","caller":"traceutil/trace.go:171","msg":"trace[714162615] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:18453; }","duration":"146.705894ms","start":"2026-06-10T15:36:11.018834Z","end":"2026-06-10T15:36:11.16554Z","steps":["trace[714162615] 'agreement among raft nodes before linearized reading' (duration: 146.593822ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:36:11.485627Z","caller":"traceutil/trace.go:171","msg":"trace[22589416] linearizableReadLoop","detail":"{readStateIndex:19092; appliedIndex:19091; }","duration":"121.652919ms","start":"2026-06-10T15:36:11.363945Z","end":"2026-06-10T15:36:11.485597Z","steps":["trace[22589416] 'read index received' (duration: 121.608528ms)","trace[22589416] 'applied index is now lower than readState.Index' (duration: 42.891µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:36:11.485743Z","caller":"traceutil/trace.go:171","msg":"trace[1468374224] transaction","detail":"{read_only:false; response_revision:18455; number_of_response:1; }","duration":"247.520884ms","start":"2026-06-10T15:36:11.238182Z","end":"2026-06-10T15:36:11.485702Z","steps":["trace[1468374224] 'process raft request' (duration: 247.254329ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:36:11.485967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.004507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-10T15:36:11.48608Z","caller":"traceutil/trace.go:171","msg":"trace[554298481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18455; }","duration":"122.165621ms","start":"2026-06-10T15:36:11.363887Z","end":"2026-06-10T15:36:11.486053Z","steps":["trace[554298481] 'agreement among raft nodes before linearized reading' (duration: 121.874864ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:36:11.506401Z","caller":"traceutil/trace.go:171","msg":"trace[1740797159] transaction","detail":"{read_only:false; response_revision:18456; number_of_response:1; }","duration":"104.857902ms","start":"2026-06-10T15:36:11.401508Z","end":"2026-06-10T15:36:11.506366Z","steps":["trace[1740797159] 'process raft request' (duration: 104.619918ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:36:11.50675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.836022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-9tcr9\" ","response":"range_response_count:1 size:22018"} {"level":"info","ts":"2026-06-10T15:36:11.506908Z","caller":"traceutil/trace.go:171","msg":"trace[617197937] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-9tcr9; range_end:; response_count:1; response_revision:18456; }","duration":"108.093987ms","start":"2026-06-10T15:36:11.398796Z","end":"2026-06-10T15:36:11.50689Z","steps":["trace[617197937] 'agreement among raft nodes before linearized reading' (duration: 107.582417ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:36:11.698711Z","caller":"traceutil/trace.go:171","msg":"trace[1538807480] transaction","detail":"{read_only:false; response_revision:18459; number_of_response:1; }","duration":"163.383459ms","start":"2026-06-10T15:36:11.535285Z","end":"2026-06-10T15:36:11.698669Z","steps":["trace[1538807480] 'process raft request' (duration: 161.578464ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:36:11.70825Z","caller":"traceutil/trace.go:171","msg":"trace[1271443765] transaction","detail":"{read_only:false; response_revision:18460; number_of_response:1; }","duration":"172.353571ms","start":"2026-06-10T15:36:11.535862Z","end":"2026-06-10T15:36:11.708215Z","steps":["trace[1271443765] 'process raft request' (duration: 171.718439ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:37:39.848053Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T15:37:39.854533Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-10T15:37:39.8547Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-10T15:38:17.001305Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17306} {"level":"info","ts":"2026-06-10T15:38:17.104217Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17306,"took":"97.499717ms","hash":4048538134,"current-db-size-bytes":60764160,"current-db-size":"61 MB","current-db-size-in-use-bytes":35766272,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-10T15:38:17.104278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4048538134,"revision":17306,"compact-revision":14651} {"level":"info","ts":"2026-06-10T15:42:07.104326Z","caller":"traceutil/trace.go:171","msg":"trace[675996359] transaction","detail":"{read_only:false; response_revision:21178; number_of_response:1; }","duration":"144.278887ms","start":"2026-06-10T15:42:06.960021Z","end":"2026-06-10T15:42:07.1043Z","steps":["trace[675996359] 'process raft request' (duration: 144.120674ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:42:07.109711Z","caller":"traceutil/trace.go:171","msg":"trace[1473724194] transaction","detail":"{read_only:false; response_revision:21179; number_of_response:1; }","duration":"139.93266ms","start":"2026-06-10T15:42:06.969741Z","end":"2026-06-10T15:42:07.109674Z","steps":["trace[1473724194] 'process raft request' (duration: 139.736596ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:42:07.109957Z","caller":"traceutil/trace.go:171","msg":"trace[804116544] transaction","detail":"{read_only:false; response_revision:21180; number_of_response:1; }","duration":"139.118363ms","start":"2026-06-10T15:42:06.970814Z","end":"2026-06-10T15:42:07.109932Z","steps":["trace[804116544] 'process raft request' (duration: 138.812397ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:42:13.271946Z","caller":"traceutil/trace.go:171","msg":"trace[1882745513] transaction","detail":"{read_only:false; response_revision:21209; number_of_response:1; }","duration":"138.025151ms","start":"2026-06-10T15:42:13.133893Z","end":"2026-06-10T15:42:13.271918Z","steps":["trace[1882745513] 'process raft request' (duration: 137.835197ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:42:13.288114Z","caller":"traceutil/trace.go:171","msg":"trace[600222785] linearizableReadLoop","detail":"{readStateIndex:21939; appliedIndex:21938; }","duration":"114.008248ms","start":"2026-06-10T15:42:13.174074Z","end":"2026-06-10T15:42:13.288082Z","steps":["trace[600222785] 'read index received' (duration: 98.25152ms)","trace[600222785] 'applied index is now lower than readState.Index' (duration: 15.754928ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:42:13.288267Z","caller":"traceutil/trace.go:171","msg":"trace[386909759] transaction","detail":"{read_only:false; response_revision:21210; number_of_response:1; }","duration":"152.524935ms","start":"2026-06-10T15:42:13.135691Z","end":"2026-06-10T15:42:13.288216Z","steps":["trace[386909759] 'process raft request' (duration: 152.226129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:42:13.288322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.231462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:42:13.288559Z","caller":"traceutil/trace.go:171","msg":"trace[1905492043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21210; }","duration":"114.506847ms","start":"2026-06-10T15:42:13.174001Z","end":"2026-06-10T15:42:13.288508Z","steps":["trace[1905492043] 'agreement among raft nodes before linearized reading' (duration: 114.243412ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:42:13.565855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.373331ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:42:13.566052Z","caller":"traceutil/trace.go:171","msg":"trace[385279606] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:21210; }","duration":"153.597165ms","start":"2026-06-10T15:42:13.41243Z","end":"2026-06-10T15:42:13.566027Z","steps":["trace[385279606] 'range keys from in-memory index tree' (duration: 153.346431ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:42:13.57447Z","caller":"traceutil/trace.go:171","msg":"trace[88727975] transaction","detail":"{read_only:false; response_revision:21211; number_of_response:1; }","duration":"279.591524ms","start":"2026-06-10T15:42:13.294846Z","end":"2026-06-10T15:42:13.574437Z","steps":["trace[88727975] 'process raft request' (duration: 279.41627ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:42:13.729242Z","caller":"traceutil/trace.go:171","msg":"trace[954620407] transaction","detail":"{read_only:false; response_revision:21212; number_of_response:1; }","duration":"135.696434ms","start":"2026-06-10T15:42:13.593485Z","end":"2026-06-10T15:42:13.729182Z","steps":["trace[954620407] 'process raft request' (duration: 133.050861ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:43:17.006626Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19625} {"level":"info","ts":"2026-06-10T15:43:17.124052Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19625,"took":"113.073928ms","hash":3444610683,"current-db-size-bytes":60764160,"current-db-size":"61 MB","current-db-size-in-use-bytes":30633984,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-10T15:43:17.124129Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3444610683,"revision":19625,"compact-revision":17306} {"level":"warn","ts":"2026-06-10T15:46:14.6491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.174187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:46:14.649218Z","caller":"traceutil/trace.go:171","msg":"trace[1057395014] linearizableReadLoop","detail":"{readStateIndex:24493; appliedIndex:24492; }","duration":"336.233969ms","start":"2026-06-10T15:46:14.312963Z","end":"2026-06-10T15:46:14.649197Z","steps":["trace[1057395014] 'read index received' (duration: 59.723505ms)","trace[1057395014] 'applied index is now lower than readState.Index' (duration: 276.509634ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:46:14.649324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.363231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T15:46:14.64936Z","caller":"traceutil/trace.go:171","msg":"trace[393229638] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:23689; }","duration":"336.447933ms","start":"2026-06-10T15:46:14.312893Z","end":"2026-06-10T15:46:14.649341Z","steps":["trace[393229638] 'agreement among raft nodes before linearized reading' (duration: 336.349362ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:14.649393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:14.312869Z","time spent":"336.516234ms","remote":"127.0.0.1:41206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":10,"response size":31,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-06-10T15:46:14.649696Z","caller":"traceutil/trace.go:171","msg":"trace[359123267] transaction","detail":"{read_only:false; response_revision:23689; number_of_response:1; }","duration":"353.65496ms","start":"2026-06-10T15:46:14.296031Z","end":"2026-06-10T15:46:14.649686Z","steps":["trace[359123267] 'process raft request' (duration: 76.79851ms)","trace[359123267] 'compare' (duration: 275.8177ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:46:14.649755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:14.296001Z","time spent":"353.724502ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:48:17.016021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21808} {"level":"info","ts":"2026-06-10T15:48:17.136045Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21808,"took":"110.594873ms","hash":3105367584,"current-db-size-bytes":60764160,"current-db-size":"61 MB","current-db-size-in-use-bytes":43683840,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-06-10T15:48:17.136122Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3105367584,"revision":21808,"compact-revision":19625} {"level":"info","ts":"2026-06-10T15:48:59.569114Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000661c.wal"} {"level":"info","ts":"2026-06-10T15:49:54.277787Z","caller":"traceutil/trace.go:171","msg":"trace[1444947394] linearizableReadLoop","detail":"{readStateIndex:26698; appliedIndex:26697; }","duration":"102.908487ms","start":"2026-06-10T15:49:54.17485Z","end":"2026-06-10T15:49:54.277759Z","steps":["trace[1444947394] 'read index received' (duration: 102.602711ms)","trace[1444947394] 'applied index is now lower than readState.Index' (duration: 304.836µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:49:54.277864Z","caller":"traceutil/trace.go:171","msg":"trace[301151668] transaction","detail":"{read_only:false; response_revision:25837; number_of_response:1; }","duration":"103.058431ms","start":"2026-06-10T15:49:54.174768Z","end":"2026-06-10T15:49:54.277826Z","steps":["trace[301151668] 'process raft request' (duration: 102.777555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:49:54.27796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.079151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:49:54.278001Z","caller":"traceutil/trace.go:171","msg":"trace[122889503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25837; }","duration":"103.198894ms","start":"2026-06-10T15:49:54.174792Z","end":"2026-06-10T15:49:54.277991Z","steps":["trace[122889503] 'agreement among raft nodes before linearized reading' (duration: 103.096992ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:49:59.157504Z","caller":"traceutil/trace.go:171","msg":"trace[752086187] transaction","detail":"{read_only:false; response_revision:25881; number_of_response:1; }","duration":"125.678294ms","start":"2026-06-10T15:49:59.031797Z","end":"2026-06-10T15:49:59.157476Z","steps":["trace[752086187] 'process raft request' (duration: 125.49065ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:49:59.877649Z","caller":"traceutil/trace.go:171","msg":"trace[732217589] transaction","detail":"{read_only:false; response_revision:25883; number_of_response:1; }","duration":"291.731974ms","start":"2026-06-10T15:49:59.585891Z","end":"2026-06-10T15:49:59.877623Z","steps":["trace[732217589] 'process raft request' (duration: 291.412058ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:00.155891Z","caller":"traceutil/trace.go:171","msg":"trace[97203322] transaction","detail":"{read_only:false; response_revision:25884; number_of_response:1; }","duration":"508.013138ms","start":"2026-06-10T15:49:59.647858Z","end":"2026-06-10T15:50:00.155871Z","steps":["trace[97203322] 'process raft request' (duration: 507.045298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:00.15601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:49:59.647833Z","time spent":"508.11974ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:50:00.15631Z","caller":"traceutil/trace.go:171","msg":"trace[1440210273] transaction","detail":"{read_only:false; response_revision:25885; number_of_response:1; }","duration":"507.525267ms","start":"2026-06-10T15:49:59.648777Z","end":"2026-06-10T15:50:00.156302Z","steps":["trace[1440210273] 'process raft request' (duration: 507.038087ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:00.156343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:49:59.64875Z","time spent":"507.574388ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:50:00.156636Z","caller":"traceutil/trace.go:171","msg":"trace[1513398120] transaction","detail":"{read_only:false; response_revision:25886; number_of_response:1; }","duration":"442.947195ms","start":"2026-06-10T15:49:59.713641Z","end":"2026-06-10T15:50:00.156588Z","steps":["trace[1513398120] 'process raft request' (duration: 442.71745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:00.157185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:49:59.713613Z","time spent":"443.443964ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:50:00.164607Z","caller":"traceutil/trace.go:171","msg":"trace[405650961] transaction","detail":"{read_only:false; response_revision:25887; number_of_response:1; }","duration":"192.910451ms","start":"2026-06-10T15:49:59.971675Z","end":"2026-06-10T15:50:00.164585Z","steps":["trace[405650961] 'process raft request' (duration: 192.515613ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:00.16472Z","caller":"traceutil/trace.go:171","msg":"trace[533977380] transaction","detail":"{read_only:false; response_revision:25888; number_of_response:1; }","duration":"107.759005ms","start":"2026-06-10T15:50:00.056955Z","end":"2026-06-10T15:50:00.164714Z","steps":["trace[533977380] 'process raft request' (duration: 107.323026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:03.518952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.300234ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:50:03.519097Z","caller":"traceutil/trace.go:171","msg":"trace[1777162382] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25926; }","duration":"106.463798ms","start":"2026-06-10T15:50:03.412613Z","end":"2026-06-10T15:50:03.519076Z","steps":["trace[1777162382] 'range keys from in-memory index tree' (duration: 106.280144ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:04.438014Z","caller":"traceutil/trace.go:171","msg":"trace[1316567901] transaction","detail":"{read_only:false; response_revision:25928; number_of_response:1; }","duration":"261.401262ms","start":"2026-06-10T15:50:04.176579Z","end":"2026-06-10T15:50:04.437981Z","steps":["trace[1316567901] 'process raft request' (duration: 261.053835ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:04.696041Z","caller":"traceutil/trace.go:171","msg":"trace[1750928197] linearizableReadLoop","detail":"{readStateIndex:26795; appliedIndex:26790; }","duration":"351.872217ms","start":"2026-06-10T15:50:04.344141Z","end":"2026-06-10T15:50:04.696013Z","steps":["trace[1750928197] 'read index received' (duration: 93.442784ms)","trace[1750928197] 'applied index is now lower than readState.Index' (duration: 258.428583ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:04.696114Z","caller":"traceutil/trace.go:171","msg":"trace[2129142666] transaction","detail":"{read_only:false; response_revision:25930; number_of_response:1; }","duration":"513.853335ms","start":"2026-06-10T15:50:04.182225Z","end":"2026-06-10T15:50:04.696078Z","steps":["trace[2129142666] 'process raft request' (duration: 513.650891ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:04.696265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:04.182216Z","time spent":"513.972018ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:50:04.696245Z","caller":"traceutil/trace.go:171","msg":"trace[307536023] transaction","detail":"{read_only:false; response_revision:25932; number_of_response:1; }","duration":"511.537768ms","start":"2026-06-10T15:50:04.184666Z","end":"2026-06-10T15:50:04.696204Z","steps":["trace[307536023] 'process raft request' (duration: 511.307033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:04.696322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.162564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-10T15:50:04.696361Z","caller":"traceutil/trace.go:171","msg":"trace[1432212385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25932; }","duration":"352.280296ms","start":"2026-06-10T15:50:04.344071Z","end":"2026-06-10T15:50:04.696351Z","steps":["trace[1432212385] 'agreement among raft nodes before linearized reading' (duration: 352.086853ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:04.696346Z","caller":"traceutil/trace.go:171","msg":"trace[1736683217] transaction","detail":"{read_only:false; response_revision:25929; number_of_response:1; }","duration":"515.966218ms","start":"2026-06-10T15:50:04.180334Z","end":"2026-06-10T15:50:04.6963Z","steps":["trace[1736683217] 'process raft request' (duration: 514.839525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:04.696397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:04.344045Z","time spent":"352.343007ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-10T15:50:04.696251Z","caller":"traceutil/trace.go:171","msg":"trace[2127502376] transaction","detail":"{read_only:false; response_revision:25931; number_of_response:1; }","duration":"513.664712ms","start":"2026-06-10T15:50:04.182549Z","end":"2026-06-10T15:50:04.696214Z","steps":["trace[2127502376] 'process raft request' (duration: 513.371455ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:04.696446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:04.184658Z","time spent":"511.694442ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:50:04.696566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:04.182519Z","time spent":"513.964478ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:50:04.696585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:04.180322Z","time spent":"516.126601ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:50:04.922667Z","caller":"traceutil/trace.go:171","msg":"trace[10475529] transaction","detail":"{read_only:false; response_revision:25933; number_of_response:1; }","duration":"432.235419ms","start":"2026-06-10T15:50:04.4904Z","end":"2026-06-10T15:50:04.922635Z","steps":["trace[10475529] 'process raft request' (duration: 377.621618ms)","trace[10475529] 'compare' (duration: 54.345326ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:50:04.922811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:04.490357Z","time spent":"432.379981ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:50:04.922787Z","caller":"traceutil/trace.go:171","msg":"trace[1813982109] transaction","detail":"{read_only:false; response_revision:25934; number_of_response:1; }","duration":"222.318744ms","start":"2026-06-10T15:50:04.70044Z","end":"2026-06-10T15:50:04.922759Z","steps":["trace[1813982109] 'process raft request' (duration: 222.185772ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:04.922794Z","caller":"traceutil/trace.go:171","msg":"trace[646660021] linearizableReadLoop","detail":"{readStateIndex:26796; appliedIndex:26795; }","duration":"226.639471ms","start":"2026-06-10T15:50:04.696127Z","end":"2026-06-10T15:50:04.922766Z","steps":["trace[646660021] 'read index received' (duration: 171.909117ms)","trace[646660021] 'applied index is now lower than readState.Index' (duration: 54.728273ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:04.922989Z","caller":"traceutil/trace.go:171","msg":"trace[1843069150] transaction","detail":"{read_only:false; response_revision:25935; number_of_response:1; }","duration":"164.208262ms","start":"2026-06-10T15:50:04.758763Z","end":"2026-06-10T15:50:04.922971Z","steps":["trace[1843069150] 'process raft request' (duration: 163.952437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:04.923034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.981988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:50:04.923071Z","caller":"traceutil/trace.go:171","msg":"trace[1438893687] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25936; }","duration":"114.084761ms","start":"2026-06-10T15:50:04.808975Z","end":"2026-06-10T15:50:04.92306Z","steps":["trace[1438893687] 'agreement among raft nodes before linearized reading' (duration: 113.978978ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:04.923541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.596995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-06-10T15:50:04.923564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.687618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-10T15:50:04.923608Z","caller":"traceutil/trace.go:171","msg":"trace[1443050525] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25936; }","duration":"369.719548ms","start":"2026-06-10T15:50:04.553874Z","end":"2026-06-10T15:50:04.923594Z","steps":["trace[1443050525] 'agreement among raft nodes before linearized reading' (duration: 369.027183ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:04.923629Z","caller":"traceutil/trace.go:171","msg":"trace[1435733935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25936; }","duration":"377.78354ms","start":"2026-06-10T15:50:04.545828Z","end":"2026-06-10T15:50:04.923611Z","steps":["trace[1435733935] 'agreement among raft nodes before linearized reading' (duration: 377.612817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:04.923651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:04.553855Z","time spent":"369.781569ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T15:50:04.923663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:04.545804Z","time spent":"377.850521ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T15:50:05.069912Z","caller":"traceutil/trace.go:171","msg":"trace[610856016] transaction","detail":"{read_only:false; response_revision:25937; number_of_response:1; }","duration":"142.282081ms","start":"2026-06-10T15:50:04.9276Z","end":"2026-06-10T15:50:05.069882Z","steps":["trace[610856016] 'process raft request' (duration: 141.913423ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:05.084513Z","caller":"traceutil/trace.go:171","msg":"trace[2119395782] transaction","detail":"{read_only:false; response_revision:25938; number_of_response:1; }","duration":"156.769223ms","start":"2026-06-10T15:50:04.927711Z","end":"2026-06-10T15:50:05.08448Z","steps":["trace[2119395782] 'process raft request' (duration: 156.534418ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:05.084776Z","caller":"traceutil/trace.go:171","msg":"trace[47367457] transaction","detail":"{read_only:false; response_revision:25939; number_of_response:1; }","duration":"157.008447ms","start":"2026-06-10T15:50:04.927746Z","end":"2026-06-10T15:50:05.084754Z","steps":["trace[47367457] 'process raft request' (duration: 156.689701ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:05.085232Z","caller":"traceutil/trace.go:171","msg":"trace[2048962369] transaction","detail":"{read_only:false; response_revision:25940; number_of_response:1; }","duration":"156.732071ms","start":"2026-06-10T15:50:04.928484Z","end":"2026-06-10T15:50:05.085216Z","steps":["trace[2048962369] 'process raft request' (duration: 156.205471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:05.370969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.955044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:50:05.371135Z","caller":"traceutil/trace.go:171","msg":"trace[1207778441] transaction","detail":"{read_only:false; response_revision:25941; number_of_response:1; }","duration":"189.011752ms","start":"2026-06-10T15:50:05.182087Z","end":"2026-06-10T15:50:05.371099Z","steps":["trace[1207778441] 'process raft request' (duration: 42.785422ms)","trace[1207778441] 'compare' (duration: 145.386313ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:50:05.710615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.800232ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-10T15:50:06.329856Z","caller":"traceutil/trace.go:171","msg":"trace[1829755430] linearizableReadLoop","detail":"{readStateIndex:26807; appliedIndex:26806; }","duration":"157.244141ms","start":"2026-06-10T15:50:06.172574Z","end":"2026-06-10T15:50:06.329818Z","steps":["trace[1829755430] 'read index received' (duration: 156.981306ms)","trace[1829755430] 'applied index is now lower than readState.Index' (duration: 261.065µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:06.330007Z","caller":"traceutil/trace.go:171","msg":"trace[892151523] transaction","detail":"{read_only:false; response_revision:25943; number_of_response:1; }","duration":"170.368647ms","start":"2026-06-10T15:50:06.159588Z","end":"2026-06-10T15:50:06.329956Z","steps":["trace[892151523] 'process raft request' (duration: 169.947888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:06.330075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.480446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:50:06.330189Z","caller":"traceutil/trace.go:171","msg":"trace[2040788874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25943; }","duration":"157.65372ms","start":"2026-06-10T15:50:06.17251Z","end":"2026-06-10T15:50:06.330164Z","steps":["trace[2040788874] 'agreement among raft nodes before linearized reading' (duration: 157.468266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:06.65715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.059322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T15:50:06.657278Z","caller":"traceutil/trace.go:171","msg":"trace[1318432090] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25943; }","duration":"232.271256ms","start":"2026-06-10T15:50:06.42498Z","end":"2026-06-10T15:50:06.657252Z","steps":["trace[1318432090] 'count revisions from in-memory index tree' (duration: 231.959379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:06.657993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.302118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-10T15:50:06.658045Z","caller":"traceutil/trace.go:171","msg":"trace[399559780] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25943; }","duration":"135.40208ms","start":"2026-06-10T15:50:06.52263Z","end":"2026-06-10T15:50:06.658032Z","steps":["trace[399559780] 'range keys from in-memory index tree' (duration: 135.065244ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:06.858374Z","caller":"traceutil/trace.go:171","msg":"trace[1625864630] linearizableReadLoop","detail":"{readStateIndex:26811; appliedIndex:26807; }","duration":"131.150654ms","start":"2026-06-10T15:50:06.727194Z","end":"2026-06-10T15:50:06.858344Z","steps":["trace[1625864630] 'read index received' (duration: 23.707397ms)","trace[1625864630] 'applied index is now lower than readState.Index' (duration: 107.441787ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:06.858484Z","caller":"traceutil/trace.go:171","msg":"trace[663179910] transaction","detail":"{read_only:false; response_revision:25947; number_of_response:1; }","duration":"155.409944ms","start":"2026-06-10T15:50:06.703034Z","end":"2026-06-10T15:50:06.858444Z","steps":["trace[663179910] 'process raft request' (duration: 155.255911ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:06.85849Z","caller":"traceutil/trace.go:171","msg":"trace[877115191] transaction","detail":"{read_only:false; response_revision:25945; number_of_response:1; }","duration":"156.309442ms","start":"2026-06-10T15:50:06.702051Z","end":"2026-06-10T15:50:06.85836Z","steps":["trace[877115191] 'process raft request' (duration: 147.049945ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:06.858515Z","caller":"traceutil/trace.go:171","msg":"trace[335700018] transaction","detail":"{read_only:false; response_revision:25946; number_of_response:1; }","duration":"156.372174ms","start":"2026-06-10T15:50:06.702082Z","end":"2026-06-10T15:50:06.858454Z","steps":["trace[335700018] 'process raft request' (duration: 156.161849ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:06.858559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.348678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:50:06.858781Z","caller":"traceutil/trace.go:171","msg":"trace[428216988] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25947; }","duration":"131.569602ms","start":"2026-06-10T15:50:06.727176Z","end":"2026-06-10T15:50:06.858746Z","steps":["trace[428216988] 'agreement among raft nodes before linearized reading' (duration: 131.294737ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:07.036092Z","caller":"traceutil/trace.go:171","msg":"trace[1793679388] transaction","detail":"{read_only:false; response_revision:25948; number_of_response:1; }","duration":"107.720583ms","start":"2026-06-10T15:50:06.928347Z","end":"2026-06-10T15:50:07.036068Z","steps":["trace[1793679388] 'process raft request' (duration: 107.56475ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:07.036927Z","caller":"traceutil/trace.go:171","msg":"trace[1158881721] transaction","detail":"{read_only:false; response_revision:25950; number_of_response:1; }","duration":"106.164312ms","start":"2026-06-10T15:50:06.930737Z","end":"2026-06-10T15:50:07.036902Z","steps":["trace[1158881721] 'process raft request' (duration: 106.036449ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:07.03692Z","caller":"traceutil/trace.go:171","msg":"trace[1677406916] transaction","detail":"{read_only:false; response_revision:25949; number_of_response:1; }","duration":"107.413606ms","start":"2026-06-10T15:50:06.92947Z","end":"2026-06-10T15:50:07.036884Z","steps":["trace[1677406916] 'process raft request' (duration: 107.12333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:07.305071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.877429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:50:07.305199Z","caller":"traceutil/trace.go:171","msg":"trace[662823103] linearizableReadLoop","detail":"{readStateIndex:26815; appliedIndex:26814; }","duration":"216.416705ms","start":"2026-06-10T15:50:07.088761Z","end":"2026-06-10T15:50:07.305178Z","steps":["trace[662823103] 'read index received' (duration: 92.311392ms)","trace[662823103] 'applied index is now lower than readState.Index' (duration: 124.103413ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:07.305299Z","caller":"traceutil/trace.go:171","msg":"trace[883346260] transaction","detail":"{read_only:false; response_revision:25951; number_of_response:1; }","duration":"226.618891ms","start":"2026-06-10T15:50:07.078667Z","end":"2026-06-10T15:50:07.305286Z","steps":["trace[883346260] 'process raft request' (duration: 102.427636ms)","trace[883346260] 'compare' (duration: 123.284887ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:50:07.305858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.819889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-10T15:50:07.3059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.105199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-10T15:50:07.305995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.082768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-10T15:50:07.305998Z","caller":"traceutil/trace.go:171","msg":"trace[2040533580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25951; }","duration":"132.015163ms","start":"2026-06-10T15:50:07.173959Z","end":"2026-06-10T15:50:07.305974Z","steps":["trace[2040533580] 'agreement among raft nodes before linearized reading' (duration: 131.821849ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:07.306115Z","caller":"traceutil/trace.go:171","msg":"trace[888411584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25951; }","duration":"217.252442ms","start":"2026-06-10T15:50:07.088835Z","end":"2026-06-10T15:50:07.306088Z","steps":["trace[888411584] 'agreement among raft nodes before linearized reading' (duration: 216.919985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:07.306307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.937075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-10T15:50:07.306413Z","caller":"traceutil/trace.go:171","msg":"trace[350008932] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25951; }","duration":"217.064598ms","start":"2026-06-10T15:50:07.089326Z","end":"2026-06-10T15:50:07.30639Z","steps":["trace[350008932] 'agreement among raft nodes before linearized reading' (duration: 216.846604ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:07.306024Z","caller":"traceutil/trace.go:171","msg":"trace[2045522294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25951; }","duration":"217.281692ms","start":"2026-06-10T15:50:07.088716Z","end":"2026-06-10T15:50:07.305998Z","steps":["trace[2045522294] 'agreement among raft nodes before linearized reading' (duration: 216.882034ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:07.66597Z","caller":"traceutil/trace.go:171","msg":"trace[968833164] transaction","detail":"{read_only:false; response_revision:25956; number_of_response:1; }","duration":"285.895858ms","start":"2026-06-10T15:50:07.380049Z","end":"2026-06-10T15:50:07.665945Z","steps":["trace[968833164] 'process raft request' (duration: 285.848077ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:07.666208Z","caller":"traceutil/trace.go:171","msg":"trace[1519907042] transaction","detail":"{read_only:false; response_revision:25955; number_of_response:1; }","duration":"288.403098ms","start":"2026-06-10T15:50:07.377765Z","end":"2026-06-10T15:50:07.666168Z","steps":["trace[1519907042] 'process raft request' (duration: 288.105322ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:07.66625Z","caller":"traceutil/trace.go:171","msg":"trace[1473069239] transaction","detail":"{read_only:false; response_revision:25953; number_of_response:1; }","duration":"354.793887ms","start":"2026-06-10T15:50:07.311423Z","end":"2026-06-10T15:50:07.666217Z","steps":["trace[1473069239] 'process raft request' (duration: 302.718726ms)","trace[1473069239] 'compare' (duration: 51.570141ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:07.666411Z","caller":"traceutil/trace.go:171","msg":"trace[153996276] transaction","detail":"{read_only:false; response_revision:25954; number_of_response:1; }","duration":"354.872399ms","start":"2026-06-10T15:50:07.311504Z","end":"2026-06-10T15:50:07.666376Z","steps":["trace[153996276] 'process raft request' (duration: 354.317688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:07.666557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:07.311407Z","time spent":"355.076242ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:50:07.666577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:07.311498Z","time spent":"354.996421ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:50:07.944465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.016173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:50:07.944592Z","caller":"traceutil/trace.go:171","msg":"trace[4885653] transaction","detail":"{read_only:false; response_revision:25957; number_of_response:1; }","duration":"274.540488ms","start":"2026-06-10T15:50:07.670024Z","end":"2026-06-10T15:50:07.944565Z","steps":["trace[4885653] 'process raft request' (duration: 143.340052ms)","trace[4885653] 'compare' (duration: 130.88291ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:09.086555Z","caller":"traceutil/trace.go:171","msg":"trace[1483713144] transaction","detail":"{read_only:false; response_revision:25959; number_of_response:1; }","duration":"221.876295ms","start":"2026-06-10T15:50:08.864649Z","end":"2026-06-10T15:50:09.086525Z","steps":["trace[1483713144] 'process raft request' (duration: 210.72692ms)","trace[1483713144] 'compare' (duration: 11.039453ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:50:09.505837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.739417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:50:09.506076Z","caller":"traceutil/trace.go:171","msg":"trace[492601398] transaction","detail":"{read_only:false; response_revision:25961; number_of_response:1; }","duration":"640.926679ms","start":"2026-06-10T15:50:08.86513Z","end":"2026-06-10T15:50:09.506057Z","steps":["trace[492601398] 'process raft request' (duration: 640.821626ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:09.506078Z","caller":"traceutil/trace.go:171","msg":"trace[1931777263] transaction","detail":"{read_only:false; response_revision:25960; number_of_response:1; }","duration":"641.286664ms","start":"2026-06-10T15:50:08.864752Z","end":"2026-06-10T15:50:09.506039Z","steps":["trace[1931777263] 'process raft request' (duration: 501.254641ms)","trace[1931777263] 'compare' (duration: 139.322209ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:50:09.506149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:08.865116Z","time spent":"640.99523ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:50:09.506237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:08.864721Z","time spent":"641.432509ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:50:09.50647Z","caller":"traceutil/trace.go:171","msg":"trace[104193846] transaction","detail":"{read_only:false; response_revision:25963; number_of_response:1; }","duration":"464.760475ms","start":"2026-06-10T15:50:09.041697Z","end":"2026-06-10T15:50:09.506457Z","steps":["trace[104193846] 'process raft request' (duration: 464.553761ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:09.506468Z","caller":"traceutil/trace.go:171","msg":"trace[657140838] linearizableReadLoop","detail":"{readStateIndex:26827; appliedIndex:26822; }","duration":"464.383067ms","start":"2026-06-10T15:50:09.042057Z","end":"2026-06-10T15:50:09.50644Z","steps":["trace[657140838] 'read index received' (duration: 33.409024ms)","trace[657140838] 'applied index is now lower than readState.Index' (duration: 430.972783ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:50:09.506536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:09.041669Z","time spent":"464.830466ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:50:09.506613Z","caller":"traceutil/trace.go:171","msg":"trace[863101519] transaction","detail":"{read_only:false; response_revision:25964; number_of_response:1; }","duration":"463.189554ms","start":"2026-06-10T15:50:09.043411Z","end":"2026-06-10T15:50:09.5066Z","steps":["trace[863101519] 'process raft request' (duration: 462.979099ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:09.506538Z","caller":"traceutil/trace.go:171","msg":"trace[1642326389] transaction","detail":"{read_only:false; response_revision:25962; number_of_response:1; }","duration":"509.358064ms","start":"2026-06-10T15:50:08.997165Z","end":"2026-06-10T15:50:09.506523Z","steps":["trace[1642326389] 'process raft request' (duration: 508.852433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:09.506678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:08.997135Z","time spent":"509.510467ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:50:09.506726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:09.043393Z","time spent":"463.251105ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:50:09.506826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.836464ms","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-10T15:50:09.506758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.917657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:50:09.506903Z","caller":"traceutil/trace.go:171","msg":"trace[1988313237] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:25964; }","duration":"340.104651ms","start":"2026-06-10T15:50:09.166775Z","end":"2026-06-10T15:50:09.50688Z","steps":["trace[1988313237] 'agreement among raft nodes before linearized reading' (duration: 339.875637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:09.506969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.921368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-10T15:50:09.506982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:09.16676Z","time spent":"340.206743ms","remote":"127.0.0.1:48552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true "} {"level":"info","ts":"2026-06-10T15:50:09.507014Z","caller":"traceutil/trace.go:171","msg":"trace[2007865469] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25964; }","duration":"465.00904ms","start":"2026-06-10T15:50:09.041996Z","end":"2026-06-10T15:50:09.507005Z","steps":["trace[2007865469] 'agreement among raft nodes before linearized reading' (duration: 464.860137ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:09.507045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:09.041971Z","time spent":"465.067631ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T15:50:09.507113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.169526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-10T15:50:09.506848Z","caller":"traceutil/trace.go:171","msg":"trace[706843982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25964; }","duration":"159.909905ms","start":"2026-06-10T15:50:09.346931Z","end":"2026-06-10T15:50:09.506841Z","steps":["trace[706843982] 'agreement among raft nodes before linearized reading' (duration: 159.813733ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:09.507163Z","caller":"traceutil/trace.go:171","msg":"trace[42424670] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25964; }","duration":"196.256998ms","start":"2026-06-10T15:50:09.310892Z","end":"2026-06-10T15:50:09.507149Z","steps":["trace[42424670] 'agreement among raft nodes before linearized reading' (duration: 196.120656ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:09.507203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.966126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:50:09.507254Z","caller":"traceutil/trace.go:171","msg":"trace[1288501207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25964; }","duration":"334.061598ms","start":"2026-06-10T15:50:09.173177Z","end":"2026-06-10T15:50:09.507238Z","steps":["trace[1288501207] 'agreement among raft nodes before linearized reading' (duration: 333.978877ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:09.507295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:09.17315Z","time spent":"334.13865ms","remote":"127.0.0.1:37824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T15:50:14.918276Z","caller":"traceutil/trace.go:171","msg":"trace[1074227790] transaction","detail":"{read_only:false; response_revision:26012; number_of_response:1; }","duration":"125.088452ms","start":"2026-06-10T15:50:14.793163Z","end":"2026-06-10T15:50:14.918251Z","steps":["trace[1074227790] 'process raft request' (duration: 124.951839ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:15.65905Z","caller":"traceutil/trace.go:171","msg":"trace[1677893165] transaction","detail":"{read_only:false; response_revision:26017; number_of_response:1; }","duration":"121.498191ms","start":"2026-06-10T15:50:15.53753Z","end":"2026-06-10T15:50:15.659028Z","steps":["trace[1677893165] 'process raft request' (duration: 121.356048ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:15.65905Z","caller":"traceutil/trace.go:171","msg":"trace[507068669] transaction","detail":"{read_only:false; response_revision:26016; number_of_response:1; }","duration":"121.630834ms","start":"2026-06-10T15:50:15.537371Z","end":"2026-06-10T15:50:15.659002Z","steps":["trace[507068669] 'process raft request' (duration: 121.324908ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:15.659073Z","caller":"traceutil/trace.go:171","msg":"trace[976531274] transaction","detail":"{read_only:false; response_revision:26019; number_of_response:1; }","duration":"121.288406ms","start":"2026-06-10T15:50:15.537754Z","end":"2026-06-10T15:50:15.659043Z","steps":["trace[976531274] 'process raft request' (duration: 121.223215ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:15.659166Z","caller":"traceutil/trace.go:171","msg":"trace[1420971360] transaction","detail":"{read_only:false; response_revision:26018; number_of_response:1; }","duration":"121.457899ms","start":"2026-06-10T15:50:15.537646Z","end":"2026-06-10T15:50:15.659104Z","steps":["trace[1420971360] 'process raft request' (duration: 121.284576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:15.992239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.18782ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-10T15:50:15.992613Z","caller":"traceutil/trace.go:171","msg":"trace[692301758] linearizableReadLoop","detail":"{readStateIndex:26885; appliedIndex:26880; }","duration":"446.872504ms","start":"2026-06-10T15:50:15.545715Z","end":"2026-06-10T15:50:15.992587Z","steps":["trace[692301758] 'read index received' (duration: 112.971829ms)","trace[692301758] 'applied index is now lower than readState.Index' (duration: 333.898655ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:50:15.993118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.348283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-10T15:50:15.993168Z","caller":"traceutil/trace.go:171","msg":"trace[1998567602] transaction","detail":"{read_only:false; response_revision:26020; number_of_response:1; }","duration":"301.419929ms","start":"2026-06-10T15:50:15.691675Z","end":"2026-06-10T15:50:15.993095Z","steps":["trace[1998567602] 'process raft request' (duration: 300.726755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:15.993379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:15.691638Z","time spent":"301.620643ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:50:15.99351Z","caller":"traceutil/trace.go:171","msg":"trace[2018675309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26019; }","duration":"447.540978ms","start":"2026-06-10T15:50:15.54568Z","end":"2026-06-10T15:50:15.993221Z","steps":["trace[2018675309] 'agreement among raft nodes before linearized reading' (duration: 447.182491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:15.993646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:15.545663Z","time spent":"447.936955ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T15:50:16.142468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.87914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:50:16.142643Z","caller":"traceutil/trace.go:171","msg":"trace[734527132] linearizableReadLoop","detail":"{readStateIndex:26887; appliedIndex:26886; }","duration":"149.954655ms","start":"2026-06-10T15:50:15.992671Z","end":"2026-06-10T15:50:16.142626Z","steps":["trace[734527132] 'read index received' (duration: 12.840609ms)","trace[734527132] 'applied index is now lower than readState.Index' (duration: 137.112526ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:50:16.142813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.014823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-10T15:50:16.142801Z","caller":"traceutil/trace.go:171","msg":"trace[201813973] transaction","detail":"{read_only:false; response_revision:26021; number_of_response:1; }","duration":"442.232821ms","start":"2026-06-10T15:50:15.700519Z","end":"2026-06-10T15:50:16.142752Z","steps":["trace[201813973] 'process raft request' (duration: 304.999423ms)","trace[201813973] 'compare' (duration: 136.756407ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:16.142934Z","caller":"traceutil/trace.go:171","msg":"trace[519386779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26021; }","duration":"597.123645ms","start":"2026-06-10T15:50:15.545757Z","end":"2026-06-10T15:50:16.142881Z","steps":["trace[519386779] 'agreement among raft nodes before linearized reading' (duration: 596.930991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:16.143013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:15.545746Z","time spent":"597.253918ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T15:50:16.143044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:15.700503Z","time spent":"442.413194ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:50:16.142933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.24762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-10T15:50:16.143065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.221562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-10T15:50:16.14314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.25707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:50:16.143174Z","caller":"traceutil/trace.go:171","msg":"trace[1141050668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26021; }","duration":"438.517046ms","start":"2026-06-10T15:50:15.704599Z","end":"2026-06-10T15:50:16.143116Z","steps":["trace[1141050668] 'agreement among raft nodes before linearized reading' (duration: 438.119468ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:16.14321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.050667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-06-10T15:50:16.14329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:15.704573Z","time spent":"438.69887ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-10T15:50:16.143297Z","caller":"traceutil/trace.go:171","msg":"trace[1391882273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26021; }","duration":"439.146939ms","start":"2026-06-10T15:50:15.704128Z","end":"2026-06-10T15:50:16.143274Z","steps":["trace[1391882273] 'agreement among raft nodes before linearized reading' (duration: 438.882134ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:16.143257Z","caller":"traceutil/trace.go:171","msg":"trace[1716515557] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:26021; }","duration":"436.362022ms","start":"2026-06-10T15:50:15.706821Z","end":"2026-06-10T15:50:16.143183Z","steps":["trace[1716515557] 'agreement among raft nodes before linearized reading' (duration: 436.228159ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:16.143278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.453761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-10T15:50:16.143349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:15.704088Z","time spent":"439.25093ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-10T15:50:16.143141Z","caller":"traceutil/trace.go:171","msg":"trace[148761893] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:26021; }","duration":"452.356555ms","start":"2026-06-10T15:50:15.690769Z","end":"2026-06-10T15:50:16.143126Z","steps":["trace[148761893] 'agreement among raft nodes before linearized reading' (duration: 452.177511ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:16.143375Z","caller":"traceutil/trace.go:171","msg":"trace[1951093848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26021; }","duration":"595.575233ms","start":"2026-06-10T15:50:15.54778Z","end":"2026-06-10T15:50:16.143355Z","steps":["trace[1951093848] 'agreement among raft nodes before linearized reading' (duration: 595.291348ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:16.143416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:15.547774Z","time spent":"595.631924ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T15:50:16.143419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:15.690738Z","time spent":"452.661451ms","remote":"127.0.0.1:38050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":78,"response size":31,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-06-10T15:50:16.143375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:15.70681Z","time spent":"436.543405ms","remote":"127.0.0.1:35352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-10T15:50:16.146047Z","caller":"traceutil/trace.go:171","msg":"trace[745935267] transaction","detail":"{read_only:false; response_revision:26022; number_of_response:1; }","duration":"147.699119ms","start":"2026-06-10T15:50:15.998313Z","end":"2026-06-10T15:50:16.146012Z","steps":["trace[745935267] 'process raft request' (duration: 147.520886ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:18.334293Z","caller":"traceutil/trace.go:171","msg":"trace[1041106200] transaction","detail":"{read_only:false; response_revision:26038; number_of_response:1; }","duration":"125.754597ms","start":"2026-06-10T15:50:18.208509Z","end":"2026-06-10T15:50:18.334263Z","steps":["trace[1041106200] 'process raft request' (duration: 124.516271ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:18.362705Z","caller":"traceutil/trace.go:171","msg":"trace[1606971663] transaction","detail":"{read_only:false; response_revision:26039; number_of_response:1; }","duration":"154.008617ms","start":"2026-06-10T15:50:18.208676Z","end":"2026-06-10T15:50:18.362685Z","steps":["trace[1606971663] 'process raft request' (duration: 153.868194ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:18.362897Z","caller":"traceutil/trace.go:171","msg":"trace[955982199] transaction","detail":"{read_only:false; response_revision:26040; number_of_response:1; }","duration":"154.123649ms","start":"2026-06-10T15:50:18.208754Z","end":"2026-06-10T15:50:18.362877Z","steps":["trace[955982199] 'process raft request' (duration: 153.886234ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:18.36305Z","caller":"traceutil/trace.go:171","msg":"trace[982082575] transaction","detail":"{read_only:false; response_revision:26042; number_of_response:1; }","duration":"154.212841ms","start":"2026-06-10T15:50:18.208829Z","end":"2026-06-10T15:50:18.363041Z","steps":["trace[982082575] 'process raft request' (duration: 154.16419ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:18.36313Z","caller":"traceutil/trace.go:171","msg":"trace[266266574] transaction","detail":"{read_only:false; response_revision:26041; number_of_response:1; }","duration":"154.284932ms","start":"2026-06-10T15:50:18.208836Z","end":"2026-06-10T15:50:18.363121Z","steps":["trace[266266574] 'process raft request' (duration: 153.981816ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:21.325136Z","caller":"traceutil/trace.go:171","msg":"trace[784215560] transaction","detail":"{read_only:false; response_revision:26068; number_of_response:1; }","duration":"128.3808ms","start":"2026-06-10T15:50:21.196727Z","end":"2026-06-10T15:50:21.325108Z","steps":["trace[784215560] 'process raft request' (duration: 128.217196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:27.050891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.63143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18b7c30a18898adb\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-06-10T15:50:27.051038Z","caller":"traceutil/trace.go:171","msg":"trace[831176168] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18b7c30a18898adb; range_end:; response_count:1; response_revision:26167; }","duration":"167.790044ms","start":"2026-06-10T15:50:26.883224Z","end":"2026-06-10T15:50:27.051014Z","steps":["trace[831176168] 'range keys from in-memory index tree' (duration: 167.437417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:27.051343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.747766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-087ab6de-59c5-49e6-b5b5-420e32e2f6e1\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-06-10T15:50:27.051442Z","caller":"traceutil/trace.go:171","msg":"trace[25258108] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-087ab6de-59c5-49e6-b5b5-420e32e2f6e1; range_end:; response_count:1; response_revision:26167; }","duration":"242.360879ms","start":"2026-06-10T15:50:26.809053Z","end":"2026-06-10T15:50:27.051414Z","steps":["trace[25258108] 'range keys from in-memory index tree' (duration: 241.519121ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:27.199075Z","caller":"traceutil/trace.go:171","msg":"trace[486081941] transaction","detail":"{read_only:false; response_revision:26169; number_of_response:1; }","duration":"145.25201ms","start":"2026-06-10T15:50:27.053793Z","end":"2026-06-10T15:50:27.199045Z","steps":["trace[486081941] 'process raft request' (duration: 131.314049ms)","trace[486081941] 'compare' (duration: 13.609455ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:27.591523Z","caller":"traceutil/trace.go:171","msg":"trace[1406258770] linearizableReadLoop","detail":"{readStateIndex:27039; appliedIndex:27038; }","duration":"266.573927ms","start":"2026-06-10T15:50:27.324922Z","end":"2026-06-10T15:50:27.591496Z","steps":["trace[1406258770] 'read index received' (duration: 266.369683ms)","trace[1406258770] 'applied index is now lower than readState.Index' (duration: 203.564µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:27.5918Z","caller":"traceutil/trace.go:171","msg":"trace[1249693958] transaction","detail":"{read_only:false; response_revision:26170; number_of_response:1; }","duration":"387.333253ms","start":"2026-06-10T15:50:27.204457Z","end":"2026-06-10T15:50:27.591791Z","steps":["trace[1249693958] 'process raft request' (duration: 386.894574ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:27.591893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:27.204434Z","time spent":"387.389365ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:50:27.592122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.209751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-10T15:50:27.592148Z","caller":"traceutil/trace.go:171","msg":"trace[1240608725] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26170; }","duration":"267.236011ms","start":"2026-06-10T15:50:27.324905Z","end":"2026-06-10T15:50:27.592141Z","steps":["trace[1240608725] 'agreement among raft nodes before linearized reading' (duration: 267.130609ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:27.624074Z","caller":"traceutil/trace.go:171","msg":"trace[1485113436] transaction","detail":"{read_only:false; response_revision:26171; number_of_response:1; }","duration":"280.027368ms","start":"2026-06-10T15:50:27.344024Z","end":"2026-06-10T15:50:27.624052Z","steps":["trace[1485113436] 'process raft request' (duration: 279.885296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:27.624209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.57082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:50:27.62429Z","caller":"traceutil/trace.go:171","msg":"trace[1733728153] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:26171; }","duration":"108.699073ms","start":"2026-06-10T15:50:27.515559Z","end":"2026-06-10T15:50:27.624258Z","steps":["trace[1733728153] 'agreement among raft nodes before linearized reading' (duration: 108.58252ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:27.932128Z","caller":"traceutil/trace.go:171","msg":"trace[1070883839] transaction","detail":"{read_only:false; response_revision:26172; number_of_response:1; }","duration":"232.097242ms","start":"2026-06-10T15:50:27.699998Z","end":"2026-06-10T15:50:27.932095Z","steps":["trace[1070883839] 'process raft request' (duration: 231.914628ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:27.955991Z","caller":"traceutil/trace.go:171","msg":"trace[1622604758] transaction","detail":"{read_only:false; response_revision:26173; number_of_response:1; }","duration":"255.014905ms","start":"2026-06-10T15:50:27.700915Z","end":"2026-06-10T15:50:27.95593Z","steps":["trace[1622604758] 'process raft request' (duration: 254.585526ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:27.957248Z","caller":"traceutil/trace.go:171","msg":"trace[1985708004] linearizableReadLoop","detail":"{readStateIndex:27042; appliedIndex:27040; }","duration":"252.545824ms","start":"2026-06-10T15:50:27.704673Z","end":"2026-06-10T15:50:27.957219Z","steps":["trace[1985708004] 'read index received' (duration: 227.304175ms)","trace[1985708004] 'applied index is now lower than readState.Index' (duration: 25.240389ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:27.957339Z","caller":"traceutil/trace.go:171","msg":"trace[1400885202] transaction","detail":"{read_only:false; response_revision:26175; number_of_response:1; }","duration":"241.93571ms","start":"2026-06-10T15:50:27.715375Z","end":"2026-06-10T15:50:27.957311Z","steps":["trace[1400885202] 'process raft request' (duration: 241.791237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:27.957452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.944833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-10T15:50:27.957445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.740798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:50:27.957555Z","caller":"traceutil/trace.go:171","msg":"trace[2073496927] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26175; }","duration":"252.932052ms","start":"2026-06-10T15:50:27.704599Z","end":"2026-06-10T15:50:27.957531Z","steps":["trace[2073496927] 'agreement among raft nodes before linearized reading' (duration: 252.739648ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:27.957576Z","caller":"traceutil/trace.go:171","msg":"trace[1250565791] transaction","detail":"{read_only:false; response_revision:26174; number_of_response:1; }","duration":"250.151386ms","start":"2026-06-10T15:50:27.707415Z","end":"2026-06-10T15:50:27.957566Z","steps":["trace[1250565791] 'process raft request' (duration: 249.637486ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:27.9575Z","caller":"traceutil/trace.go:171","msg":"trace[632917126] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:26175; }","duration":"197.060954ms","start":"2026-06-10T15:50:27.760426Z","end":"2026-06-10T15:50:27.957487Z","steps":["trace[632917126] 'agreement among raft nodes before linearized reading' (duration: 196.948992ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:27.95767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.02174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-06-10T15:50:27.957708Z","caller":"traceutil/trace.go:171","msg":"trace[950748561] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26175; }","duration":"172.084531ms","start":"2026-06-10T15:50:27.785613Z","end":"2026-06-10T15:50:27.957698Z","steps":["trace[950748561] 'agreement among raft nodes before linearized reading' (duration: 171.919768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:30.893288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.977342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:50:30.893397Z","caller":"traceutil/trace.go:171","msg":"trace[801804077] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:26204; }","duration":"130.160355ms","start":"2026-06-10T15:50:30.763212Z","end":"2026-06-10T15:50:30.893372Z","steps":["trace[801804077] 'count revisions from in-memory index tree' (duration: 129.848268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:30.893429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.39207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-06-10T15:50:30.893551Z","caller":"traceutil/trace.go:171","msg":"trace[1538107662] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:26204; }","duration":"129.553673ms","start":"2026-06-10T15:50:30.763974Z","end":"2026-06-10T15:50:30.893527Z","steps":["trace[1538107662] 'range keys from in-memory index tree' (duration: 129.228556ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:34.168447Z","caller":"traceutil/trace.go:171","msg":"trace[38043146] transaction","detail":"{read_only:false; response_revision:26245; number_of_response:1; }","duration":"104.061189ms","start":"2026-06-10T15:50:34.064354Z","end":"2026-06-10T15:50:34.168415Z","steps":["trace[38043146] 'process raft request' (duration: 92.666279ms)","trace[38043146] 'compare' (duration: 11.135714ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:34.411122Z","caller":"traceutil/trace.go:171","msg":"trace[528152934] transaction","detail":"{read_only:false; response_revision:26246; number_of_response:1; }","duration":"152.942435ms","start":"2026-06-10T15:50:34.258148Z","end":"2026-06-10T15:50:34.411091Z","steps":["trace[528152934] 'process raft request' (duration: 59.099012ms)","trace[528152934] 'compare' (duration: 93.732941ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:34.707975Z","caller":"traceutil/trace.go:171","msg":"trace[306153168] transaction","detail":"{read_only:false; response_revision:26248; number_of_response:1; }","duration":"195.84996ms","start":"2026-06-10T15:50:34.512098Z","end":"2026-06-10T15:50:34.707948Z","steps":["trace[306153168] 'process raft request' (duration: 168.954877ms)","trace[306153168] 'compare' (duration: 26.350042ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:34.71069Z","caller":"traceutil/trace.go:171","msg":"trace[1571983890] transaction","detail":"{read_only:false; response_revision:26249; number_of_response:1; }","duration":"197.156298ms","start":"2026-06-10T15:50:34.513492Z","end":"2026-06-10T15:50:34.710648Z","steps":["trace[1571983890] 'process raft request' (duration: 196.839481ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:34.71095Z","caller":"traceutil/trace.go:171","msg":"trace[1658583318] transaction","detail":"{read_only:false; response_revision:26250; number_of_response:1; }","duration":"197.352801ms","start":"2026-06-10T15:50:34.513576Z","end":"2026-06-10T15:50:34.710929Z","steps":["trace[1658583318] 'process raft request' (duration: 197.001974ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:34.711369Z","caller":"traceutil/trace.go:171","msg":"trace[1609194536] transaction","detail":"{read_only:false; response_revision:26251; number_of_response:1; }","duration":"197.218848ms","start":"2026-06-10T15:50:34.51414Z","end":"2026-06-10T15:50:34.711359Z","steps":["trace[1609194536] 'process raft request' (duration: 196.722118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:58.308237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.629887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:50:58.308527Z","caller":"traceutil/trace.go:171","msg":"trace[729271405] linearizableReadLoop","detail":"{readStateIndex:27316; appliedIndex:27313; }","duration":"237.835347ms","start":"2026-06-10T15:50:58.070657Z","end":"2026-06-10T15:50:58.308492Z","steps":["trace[729271405] 'read index received' (duration: 54.996459ms)","trace[729271405] 'applied index is now lower than readState.Index' (duration: 182.836548ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:58.30855Z","caller":"traceutil/trace.go:171","msg":"trace[1224785816] transaction","detail":"{read_only:false; response_revision:26439; number_of_response:1; }","duration":"242.495791ms","start":"2026-06-10T15:50:58.066006Z","end":"2026-06-10T15:50:58.308502Z","steps":["trace[1224785816] 'process raft request' (duration: 94.493807ms)","trace[1224785816] 'compare' (duration: 147.482184ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:58.308531Z","caller":"traceutil/trace.go:171","msg":"trace[1566371881] transaction","detail":"{read_only:false; response_revision:26440; number_of_response:1; }","duration":"242.176804ms","start":"2026-06-10T15:50:58.066319Z","end":"2026-06-10T15:50:58.308496Z","steps":["trace[1566371881] 'process raft request' (duration: 242.038901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:58.308735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.876221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-10T15:50:58.308776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.094883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-06-10T15:50:58.308793Z","caller":"traceutil/trace.go:171","msg":"trace[521047994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26441; }","duration":"134.968043ms","start":"2026-06-10T15:50:58.173796Z","end":"2026-06-10T15:50:58.308764Z","steps":["trace[521047994] 'agreement among raft nodes before linearized reading' (duration: 134.875171ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:58.308824Z","caller":"traceutil/trace.go:171","msg":"trace[493399531] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26441; }","duration":"238.191654ms","start":"2026-06-10T15:50:58.070624Z","end":"2026-06-10T15:50:58.308816Z","steps":["trace[493399531] 'agreement among raft nodes before linearized reading' (duration: 237.983499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:58.553216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.547243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:50:58.553313Z","caller":"traceutil/trace.go:171","msg":"trace[1803100987] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:26441; }","duration":"130.684336ms","start":"2026-06-10T15:50:58.422608Z","end":"2026-06-10T15:50:58.553292Z","steps":["trace[1803100987] 'count revisions from in-memory index tree' (duration: 130.462502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:58.8148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.784901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-10T15:50:58.814901Z","caller":"traceutil/trace.go:171","msg":"trace[1306636038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26442; }","duration":"152.005985ms","start":"2026-06-10T15:50:58.662872Z","end":"2026-06-10T15:50:58.814878Z","steps":["trace[1306636038] 'range keys from in-memory index tree' (duration: 151.336693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:59.091455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.986602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:50:59.091623Z","caller":"traceutil/trace.go:171","msg":"trace[1430884902] linearizableReadLoop","detail":"{readStateIndex:27320; appliedIndex:27319; }","duration":"258.557265ms","start":"2026-06-10T15:50:58.833003Z","end":"2026-06-10T15:50:59.091561Z","steps":["trace[1430884902] 'read index received' (duration: 127.411869ms)","trace[1430884902] 'applied index is now lower than readState.Index' (duration: 131.144016ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:59.091713Z","caller":"traceutil/trace.go:171","msg":"trace[1589295480] transaction","detail":"{read_only:false; response_revision:26444; number_of_response:1; }","duration":"260.378872ms","start":"2026-06-10T15:50:58.831309Z","end":"2026-06-10T15:50:59.091688Z","steps":["trace[1589295480] 'process raft request' (duration: 129.061043ms)","trace[1589295480] 'compare' (duration: 130.668106ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:50:59.091897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.885672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-10T15:50:59.091963Z","caller":"traceutil/trace.go:171","msg":"trace[1197378682] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26444; }","duration":"258.950493ms","start":"2026-06-10T15:50:58.832975Z","end":"2026-06-10T15:50:59.091926Z","steps":["trace[1197378682] 'agreement among raft nodes before linearized reading' (duration: 258.746869ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:59.120592Z","caller":"traceutil/trace.go:171","msg":"trace[349838427] transaction","detail":"{read_only:false; response_revision:26445; number_of_response:1; }","duration":"287.085931ms","start":"2026-06-10T15:50:58.833447Z","end":"2026-06-10T15:50:59.120533Z","steps":["trace[349838427] 'process raft request' (duration: 286.795395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:59.120666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.102906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-10T15:50:59.120686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.795285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-10T15:50:59.120752Z","caller":"traceutil/trace.go:171","msg":"trace[438827480] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:26445; }","duration":"260.224709ms","start":"2026-06-10T15:50:58.860483Z","end":"2026-06-10T15:50:59.120708Z","steps":["trace[438827480] 'agreement among raft nodes before linearized reading' (duration: 260.102707ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:59.120792Z","caller":"traceutil/trace.go:171","msg":"trace[1397348429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26445; }","duration":"286.928017ms","start":"2026-06-10T15:50:58.833835Z","end":"2026-06-10T15:50:59.120763Z","steps":["trace[1397348429] 'agreement among raft nodes before linearized reading' (duration: 286.637862ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:59.32796Z","caller":"traceutil/trace.go:171","msg":"trace[1808601078] transaction","detail":"{read_only:false; response_revision:26446; number_of_response:1; }","duration":"231.438718ms","start":"2026-06-10T15:50:59.09648Z","end":"2026-06-10T15:50:59.327919Z","steps":["trace[1808601078] 'process raft request' (duration: 148.528786ms)","trace[1808601078] 'compare' (duration: 82.646087ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:59.364099Z","caller":"traceutil/trace.go:171","msg":"trace[751815419] linearizableReadLoop","detail":"{readStateIndex:27324; appliedIndex:27321; }","duration":"173.838436ms","start":"2026-06-10T15:50:59.190232Z","end":"2026-06-10T15:50:59.36407Z","steps":["trace[751815419] 'read index received' (duration: 54.798306ms)","trace[751815419] 'applied index is now lower than readState.Index' (duration: 119.03813ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:59.3642Z","caller":"traceutil/trace.go:171","msg":"trace[1789441903] transaction","detail":"{read_only:false; response_revision:26447; number_of_response:1; }","duration":"238.610673ms","start":"2026-06-10T15:50:59.12553Z","end":"2026-06-10T15:50:59.36414Z","steps":["trace[1789441903] 'process raft request' (duration: 238.309237ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:59.364358Z","caller":"traceutil/trace.go:171","msg":"trace[1857858920] transaction","detail":"{read_only:false; response_revision:26448; number_of_response:1; }","duration":"194.249137ms","start":"2026-06-10T15:50:59.170072Z","end":"2026-06-10T15:50:59.364321Z","steps":["trace[1857858920] 'process raft request' (duration: 193.90446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:59.364713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.462228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418399"} {"level":"info","ts":"2026-06-10T15:50:59.364772Z","caller":"traceutil/trace.go:171","msg":"trace[255302262] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26448; }","duration":"174.572492ms","start":"2026-06-10T15:50:59.190185Z","end":"2026-06-10T15:50:59.364758Z","steps":["trace[255302262] 'agreement among raft nodes before linearized reading' (duration: 173.99948ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:50:59.871746Z","caller":"traceutil/trace.go:171","msg":"trace[192489182] linearizableReadLoop","detail":"{readStateIndex:27325; appliedIndex:27324; }","duration":"129.836428ms","start":"2026-06-10T15:50:59.741879Z","end":"2026-06-10T15:50:59.871715Z","steps":["trace[192489182] 'read index received' (duration: 129.653225ms)","trace[192489182] 'applied index is now lower than readState.Index' (duration: 182.063µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:50:59.871821Z","caller":"traceutil/trace.go:171","msg":"trace[2071288497] transaction","detail":"{read_only:false; response_revision:26449; number_of_response:1; }","duration":"136.88103ms","start":"2026-06-10T15:50:59.734893Z","end":"2026-06-10T15:50:59.871774Z","steps":["trace[2071288497] 'process raft request' (duration: 136.624935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:50:59.871889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.984383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:50:59.871949Z","caller":"traceutil/trace.go:171","msg":"trace[1119389891] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:26449; }","duration":"130.121515ms","start":"2026-06-10T15:50:59.741813Z","end":"2026-06-10T15:50:59.871934Z","steps":["trace[1119389891] 'agreement among raft nodes before linearized reading' (duration: 130.018543ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:00.10226Z","caller":"traceutil/trace.go:171","msg":"trace[26811707] transaction","detail":"{read_only:false; response_revision:26450; number_of_response:1; }","duration":"355.003121ms","start":"2026-06-10T15:50:59.747205Z","end":"2026-06-10T15:51:00.102208Z","steps":["trace[26811707] 'process raft request' (duration: 316.216738ms)","trace[26811707] 'compare' (duration: 38.489247ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:51:00.102483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:59.74718Z","time spent":"355.177065ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:51:00.102549Z","caller":"traceutil/trace.go:171","msg":"trace[320916841] linearizableReadLoop","detail":"{readStateIndex:27327; appliedIndex:27325; }","duration":"165.642311ms","start":"2026-06-10T15:50:59.936877Z","end":"2026-06-10T15:51:00.10252Z","steps":["trace[320916841] 'read index received' (duration: 126.484551ms)","trace[320916841] 'applied index is now lower than readState.Index' (duration: 39.15619ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:51:00.102719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.823514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:51:00.102755Z","caller":"traceutil/trace.go:171","msg":"trace[1290672971] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26451; }","duration":"165.888436ms","start":"2026-06-10T15:50:59.936855Z","end":"2026-06-10T15:51:00.102743Z","steps":["trace[1290672971] 'agreement among raft nodes before linearized reading' (duration: 165.770743ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:00.102966Z","caller":"traceutil/trace.go:171","msg":"trace[1824002402] transaction","detail":"{read_only:false; response_revision:26451; number_of_response:1; }","duration":"301.802717ms","start":"2026-06-10T15:50:59.801138Z","end":"2026-06-10T15:51:00.102941Z","steps":["trace[1824002402] 'process raft request' (duration: 301.036312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:51:00.103917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:50:59.801117Z","time spent":"301.90762ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418457,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:51:00.39019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.436703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:51:00.390317Z","caller":"traceutil/trace.go:171","msg":"trace[1698457062] linearizableReadLoop","detail":"{readStateIndex:27329; appliedIndex:27328; }","duration":"217.386775ms","start":"2026-06-10T15:51:00.172911Z","end":"2026-06-10T15:51:00.390298Z","steps":["trace[1698457062] 'read index received' (duration: 81.660277ms)","trace[1698457062] 'applied index is now lower than readState.Index' (duration: 135.724848ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:51:00.390419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.506488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:51:00.390453Z","caller":"traceutil/trace.go:171","msg":"trace[1067050449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26453; }","duration":"217.578979ms","start":"2026-06-10T15:51:00.172865Z","end":"2026-06-10T15:51:00.390444Z","steps":["trace[1067050449] 'agreement among raft nodes before linearized reading' (duration: 217.511718ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:00.39041Z","caller":"traceutil/trace.go:171","msg":"trace[1920442134] transaction","detail":"{read_only:false; response_revision:26453; number_of_response:1; }","duration":"259.130057ms","start":"2026-06-10T15:51:00.131246Z","end":"2026-06-10T15:51:00.390376Z","steps":["trace[1920442134] 'process raft request' (duration: 123.403049ms)","trace[1920442134] 'compare' (duration: 134.985822ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:51:00.445289Z","caller":"traceutil/trace.go:171","msg":"trace[2076820921] transaction","detail":"{read_only:false; response_revision:26455; number_of_response:1; }","duration":"129.792837ms","start":"2026-06-10T15:51:00.315464Z","end":"2026-06-10T15:51:00.445257Z","steps":["trace[2076820921] 'process raft request' (duration: 129.192735ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:00.445571Z","caller":"traceutil/trace.go:171","msg":"trace[1833233275] transaction","detail":"{read_only:false; response_revision:26456; number_of_response:1; }","duration":"129.981341ms","start":"2026-06-10T15:51:00.315563Z","end":"2026-06-10T15:51:00.445545Z","steps":["trace[1833233275] 'process raft request' (duration: 129.264457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:51:00.445758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.560862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:51:00.445804Z","caller":"traceutil/trace.go:171","msg":"trace[2136472292] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:26456; }","duration":"179.668524ms","start":"2026-06-10T15:51:00.266122Z","end":"2026-06-10T15:51:00.44579Z","steps":["trace[2136472292] 'agreement among raft nodes before linearized reading' (duration: 179.581682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:51:00.446047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.637876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-06-10T15:51:00.446082Z","caller":"traceutil/trace.go:171","msg":"trace[120385869] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:26456; }","duration":"198.722448ms","start":"2026-06-10T15:51:00.247351Z","end":"2026-06-10T15:51:00.446074Z","steps":["trace[120385869] 'agreement among raft nodes before linearized reading' (duration: 198.599165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:51:00.647049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.191712ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-06-10T15:51:00.905578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.979841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:51:00.905827Z","caller":"traceutil/trace.go:171","msg":"trace[1887055267] linearizableReadLoop","detail":"{readStateIndex:27336; appliedIndex:27333; }","duration":"240.084423ms","start":"2026-06-10T15:51:00.665721Z","end":"2026-06-10T15:51:00.905805Z","steps":["trace[1887055267] 'read index received' (duration: 106.824494ms)","trace[1887055267] 'applied index is now lower than readState.Index' (duration: 133.258979ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:51:00.905838Z","caller":"traceutil/trace.go:171","msg":"trace[322951042] transaction","detail":"{read_only:false; response_revision:26457; number_of_response:1; }","duration":"345.640093ms","start":"2026-06-10T15:51:00.560155Z","end":"2026-06-10T15:51:00.905795Z","steps":["trace[322951042] 'process raft request' (duration: 212.357084ms)","trace[322951042] 'compare' (duration: 132.842819ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:51:00.905923Z","caller":"traceutil/trace.go:171","msg":"trace[986126127] transaction","detail":"{read_only:false; response_revision:26459; number_of_response:1; }","duration":"257.744469ms","start":"2026-06-10T15:51:00.648165Z","end":"2026-06-10T15:51:00.90591Z","steps":["trace[986126127] 'process raft request' (duration: 257.593746ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:51:00.906066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:51:00.560132Z","time spent":"345.838537ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:51:00.90609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.282935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:51:00.906134Z","caller":"traceutil/trace.go:171","msg":"trace[2128603414] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26459; }","duration":"226.374036ms","start":"2026-06-10T15:51:00.679748Z","end":"2026-06-10T15:51:00.906122Z","steps":["trace[2128603414] 'agreement among raft nodes before linearized reading' (duration: 226.284644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:51:00.906216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.493321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18541"} {"level":"info","ts":"2026-06-10T15:51:00.906263Z","caller":"traceutil/trace.go:171","msg":"trace[765836763] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26459; }","duration":"240.586323ms","start":"2026-06-10T15:51:00.665669Z","end":"2026-06-10T15:51:00.906256Z","steps":["trace[765836763] 'agreement among raft nodes before linearized reading' (duration: 240.415309ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:00.905861Z","caller":"traceutil/trace.go:171","msg":"trace[985950036] transaction","detail":"{read_only:false; response_revision:26458; number_of_response:1; }","duration":"321.650268ms","start":"2026-06-10T15:51:00.584181Z","end":"2026-06-10T15:51:00.905831Z","steps":["trace[985950036] 'process raft request' (duration: 321.514646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:51:00.906349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:51:00.584152Z","time spent":"322.161299ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:51:01.270013Z","caller":"traceutil/trace.go:171","msg":"trace[271139600] linearizableReadLoop","detail":"{readStateIndex:27338; appliedIndex:27337; }","duration":"145.365922ms","start":"2026-06-10T15:51:01.124614Z","end":"2026-06-10T15:51:01.26998Z","steps":["trace[271139600] 'read index received' (duration: 145.179369ms)","trace[271139600] 'applied index is now lower than readState.Index' (duration: 184.813µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:51:01.270115Z","caller":"traceutil/trace.go:171","msg":"trace[857477637] transaction","detail":"{read_only:false; response_revision:26461; number_of_response:1; }","duration":"167.08958ms","start":"2026-06-10T15:51:01.102985Z","end":"2026-06-10T15:51:01.270075Z","steps":["trace[857477637] 'process raft request' (duration: 166.794324ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:51:01.270321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.689659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-10T15:51:01.270378Z","caller":"traceutil/trace.go:171","msg":"trace[818228550] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26461; }","duration":"145.813851ms","start":"2026-06-10T15:51:01.124549Z","end":"2026-06-10T15:51:01.270363Z","steps":["trace[818228550] 'agreement among raft nodes before linearized reading' (duration: 145.616247ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:01.564425Z","caller":"traceutil/trace.go:171","msg":"trace[123550805] linearizableReadLoop","detail":"{readStateIndex:27340; appliedIndex:27339; }","duration":"195.982753ms","start":"2026-06-10T15:51:01.368411Z","end":"2026-06-10T15:51:01.564394Z","steps":["trace[123550805] 'read index received' (duration: 107.387786ms)","trace[123550805] 'applied index is now lower than readState.Index' (duration: 88.593267ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:51:01.564729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.298819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-10T15:51:01.564764Z","caller":"traceutil/trace.go:171","msg":"trace[1177218853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26463; }","duration":"196.381791ms","start":"2026-06-10T15:51:01.368371Z","end":"2026-06-10T15:51:01.564752Z","steps":["trace[1177218853] 'agreement among raft nodes before linearized reading' (duration: 196.167217ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:01.56491Z","caller":"traceutil/trace.go:171","msg":"trace[572705436] transaction","detail":"{read_only:false; response_revision:26463; number_of_response:1; }","duration":"227.176432ms","start":"2026-06-10T15:51:01.337717Z","end":"2026-06-10T15:51:01.564894Z","steps":["trace[572705436] 'process raft request' (duration: 137.947922ms)","trace[572705436] 'compare' (duration: 88.331722ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:51:01.565191Z","caller":"traceutil/trace.go:171","msg":"trace[856820469] transaction","detail":"{read_only:false; response_revision:26464; number_of_response:1; }","duration":"194.983613ms","start":"2026-06-10T15:51:01.370178Z","end":"2026-06-10T15:51:01.565161Z","steps":["trace[856820469] 'process raft request' (duration: 194.84359ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:01.991665Z","caller":"traceutil/trace.go:171","msg":"trace[1763194507] transaction","detail":"{read_only:false; response_revision:26466; number_of_response:1; }","duration":"112.725144ms","start":"2026-06-10T15:51:01.878911Z","end":"2026-06-10T15:51:01.991636Z","steps":["trace[1763194507] 'process raft request' (duration: 111.984099ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:02.301955Z","caller":"traceutil/trace.go:171","msg":"trace[1537029641] linearizableReadLoop","detail":"{readStateIndex:27344; appliedIndex:27343; }","duration":"166.746845ms","start":"2026-06-10T15:51:02.135174Z","end":"2026-06-10T15:51:02.301921Z","steps":["trace[1537029641] 'read index received' (duration: 166.50434ms)","trace[1537029641] 'applied index is now lower than readState.Index' (duration: 241.505µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:51:02.302151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.824187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:51:02.30212Z","caller":"traceutil/trace.go:171","msg":"trace[2107240250] transaction","detail":"{read_only:false; response_revision:26467; number_of_response:1; }","duration":"188.765547ms","start":"2026-06-10T15:51:02.113296Z","end":"2026-06-10T15:51:02.302061Z","steps":["trace[2107240250] 'process raft request' (duration: 188.460191ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:02.302211Z","caller":"traceutil/trace.go:171","msg":"trace[272516065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26467; }","duration":"126.94404ms","start":"2026-06-10T15:51:02.175251Z","end":"2026-06-10T15:51:02.302195Z","steps":["trace[272516065] 'agreement among raft nodes before linearized reading' (duration: 126.831038ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:51:02.302197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.999089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:51:02.302333Z","caller":"traceutil/trace.go:171","msg":"trace[1429620010] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:26467; }","duration":"167.206624ms","start":"2026-06-10T15:51:02.135075Z","end":"2026-06-10T15:51:02.302282Z","steps":["trace[1429620010] 'agreement among raft nodes before linearized reading' (duration: 167.00474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:51:02.647256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.171047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:51:02.647577Z","caller":"traceutil/trace.go:171","msg":"trace[1349637117] linearizableReadLoop","detail":"{readStateIndex:27348; appliedIndex:27344; }","duration":"101.744842ms","start":"2026-06-10T15:51:02.545807Z","end":"2026-06-10T15:51:02.647552Z","steps":["trace[1349637117] 'read index received' (duration: 16.701727ms)","trace[1349637117] 'applied index is now lower than readState.Index' (duration: 85.041365ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:51:02.647871Z","caller":"traceutil/trace.go:171","msg":"trace[290189435] transaction","detail":"{read_only:false; response_revision:26468; number_of_response:1; }","duration":"250.88196ms","start":"2026-06-10T15:51:02.396972Z","end":"2026-06-10T15:51:02.647854Z","steps":["trace[290189435] 'process raft request' (duration: 112.923208ms)","trace[290189435] 'compare' (duration: 136.975492ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:51:02.647992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.176971ms","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:6"} {"level":"info","ts":"2026-06-10T15:51:02.648042Z","caller":"traceutil/trace.go:171","msg":"trace[2092040542] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:26471; }","duration":"102.234722ms","start":"2026-06-10T15:51:02.545792Z","end":"2026-06-10T15:51:02.648026Z","steps":["trace[2092040542] 'agreement among raft nodes before linearized reading' (duration: 102.136229ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:02.64801Z","caller":"traceutil/trace.go:171","msg":"trace[352030813] transaction","detail":"{read_only:false; response_revision:26470; number_of_response:1; }","duration":"193.725598ms","start":"2026-06-10T15:51:02.454232Z","end":"2026-06-10T15:51:02.647958Z","steps":["trace[352030813] 'process raft request' (duration: 193.223967ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:02.648029Z","caller":"traceutil/trace.go:171","msg":"trace[645146717] transaction","detail":"{read_only:false; response_revision:26469; number_of_response:1; }","duration":"247.305538ms","start":"2026-06-10T15:51:02.40068Z","end":"2026-06-10T15:51:02.647985Z","steps":["trace[645146717] 'process raft request' (duration: 246.716376ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:02.648088Z","caller":"traceutil/trace.go:171","msg":"trace[824162602] transaction","detail":"{read_only:false; response_revision:26471; number_of_response:1; }","duration":"192.267349ms","start":"2026-06-10T15:51:02.455729Z","end":"2026-06-10T15:51:02.647996Z","steps":["trace[824162602] 'process raft request' (duration: 191.777078ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:03.22962Z","caller":"traceutil/trace.go:171","msg":"trace[1109619808] linearizableReadLoop","detail":"{readStateIndex:27349; appliedIndex:27348; }","duration":"238.085423ms","start":"2026-06-10T15:51:02.991501Z","end":"2026-06-10T15:51:03.229587Z","steps":["trace[1109619808] 'read index received' (duration: 237.875339ms)","trace[1109619808] 'applied index is now lower than readState.Index' (duration: 209.154µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:51:03.229715Z","caller":"traceutil/trace.go:171","msg":"trace[1985651356] transaction","detail":"{read_only:false; response_revision:26472; number_of_response:1; }","duration":"317.914473ms","start":"2026-06-10T15:51:02.911775Z","end":"2026-06-10T15:51:03.22969Z","steps":["trace[1985651356] 'process raft request' (duration: 317.577156ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:51:03.229993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:51:02.911742Z","time spent":"318.088335ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:51:03.229952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.422289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-10T15:51:03.230082Z","caller":"traceutil/trace.go:171","msg":"trace[384311045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26472; }","duration":"238.615613ms","start":"2026-06-10T15:51:02.991441Z","end":"2026-06-10T15:51:03.230056Z","steps":["trace[384311045] 'agreement among raft nodes before linearized reading' (duration: 238.274556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:51:03.422456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.780018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:51:03.42258Z","caller":"traceutil/trace.go:171","msg":"trace[234043831] linearizableReadLoop","detail":"{readStateIndex:27350; appliedIndex:27349; }","duration":"148.126528ms","start":"2026-06-10T15:51:03.274434Z","end":"2026-06-10T15:51:03.42256Z","steps":["trace[234043831] 'read index received' (duration: 20.148316ms)","trace[234043831] 'applied index is now lower than readState.Index' (duration: 127.976882ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:51:03.422891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.346222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-10T15:51:03.422935Z","caller":"traceutil/trace.go:171","msg":"trace[1664011880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26473; }","duration":"148.558316ms","start":"2026-06-10T15:51:03.274364Z","end":"2026-06-10T15:51:03.422922Z","steps":["trace[1664011880] 'agreement among raft nodes before linearized reading' (duration: 148.294501ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:03.423597Z","caller":"traceutil/trace.go:171","msg":"trace[1087825164] transaction","detail":"{read_only:false; response_revision:26473; number_of_response:1; }","duration":"187.270207ms","start":"2026-06-10T15:51:03.235362Z","end":"2026-06-10T15:51:03.422632Z","steps":["trace[1087825164] 'process raft request' (duration: 59.179743ms)","trace[1087825164] 'compare' (duration: 127.632675ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:51:34.22537Z","caller":"traceutil/trace.go:171","msg":"trace[1942279607] transaction","detail":"{read_only:false; response_revision:26712; number_of_response:1; }","duration":"112.932898ms","start":"2026-06-10T15:51:34.112373Z","end":"2026-06-10T15:51:34.225306Z","steps":["trace[1942279607] 'process raft request' (duration: 112.537899ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:51:34.43258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.332038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:51:34.432687Z","caller":"traceutil/trace.go:171","msg":"trace[936416587] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26712; }","duration":"125.492602ms","start":"2026-06-10T15:51:34.307173Z","end":"2026-06-10T15:51:34.432665Z","steps":["trace[936416587] 'range keys from in-memory index tree' (duration: 125.252937ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:34.894756Z","caller":"traceutil/trace.go:171","msg":"trace[1838786806] transaction","detail":"{read_only:false; response_revision:26714; number_of_response:1; }","duration":"114.713994ms","start":"2026-06-10T15:51:34.780008Z","end":"2026-06-10T15:51:34.894722Z","steps":["trace[1838786806] 'process raft request' (duration: 114.49848ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:35.049421Z","caller":"traceutil/trace.go:171","msg":"trace[1836535062] transaction","detail":"{read_only:false; response_revision:26717; number_of_response:1; }","duration":"252.129335ms","start":"2026-06-10T15:51:34.797254Z","end":"2026-06-10T15:51:35.049383Z","steps":["trace[1836535062] 'process raft request' (duration: 252.052184ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:35.049478Z","caller":"traceutil/trace.go:171","msg":"trace[593612344] transaction","detail":"{read_only:false; response_revision:26715; number_of_response:1; }","duration":"260.30343ms","start":"2026-06-10T15:51:34.789128Z","end":"2026-06-10T15:51:35.049431Z","steps":["trace[593612344] 'process raft request' (duration: 213.146169ms)","trace[593612344] 'compare' (duration: 46.545729ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:51:35.049521Z","caller":"traceutil/trace.go:171","msg":"trace[2043875789] transaction","detail":"{read_only:false; response_revision:26716; number_of_response:1; }","duration":"256.741599ms","start":"2026-06-10T15:51:34.792727Z","end":"2026-06-10T15:51:35.049468Z","steps":["trace[2043875789] 'process raft request' (duration: 256.427213ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:51:35.29607Z","caller":"traceutil/trace.go:171","msg":"trace[1535187629] transaction","detail":"{read_only:false; response_revision:26718; number_of_response:1; }","duration":"129.970982ms","start":"2026-06-10T15:51:35.166062Z","end":"2026-06-10T15:51:35.296033Z","steps":["trace[1535187629] 'process raft request' (duration: 30.429083ms)","trace[1535187629] 'compare' (duration: 99.417086ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:51:35.487501Z","caller":"traceutil/trace.go:171","msg":"trace[1876718054] transaction","detail":"{read_only:false; response_revision:26719; number_of_response:1; }","duration":"117.911308ms","start":"2026-06-10T15:51:35.369557Z","end":"2026-06-10T15:51:35.487468Z","steps":["trace[1876718054] 'process raft request' (duration: 102.758372ms)","trace[1876718054] 'compare' (duration: 15.003723ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:53:17.02499Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24922} {"level":"info","ts":"2026-06-10T15:53:17.166375Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24922,"took":"135.974422ms","hash":2349066031,"current-db-size-bytes":60764160,"current-db-size":"61 MB","current-db-size-in-use-bytes":37302272,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-06-10T15:53:17.166449Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2349066031,"revision":24922,"compact-revision":21808} {"level":"info","ts":"2026-06-10T15:55:25.482109Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T15:55:25.48763Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-10T15:55:25.487813Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-10T15:58:17.037916Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27744} {"level":"info","ts":"2026-06-10T15:58:17.174359Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27744,"took":"132.553125ms","hash":2864132212,"current-db-size-bytes":60764160,"current-db-size":"61 MB","current-db-size-in-use-bytes":34086912,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-10T15:58:17.174437Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2864132212,"revision":27744,"compact-revision":24922} {"level":"info","ts":"2026-06-10T16:03:17.045149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30338} {"level":"info","ts":"2026-06-10T16:03:17.180017Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30338,"took":"130.770641ms","hash":1273819161,"current-db-size-bytes":60764160,"current-db-size":"61 MB","current-db-size-in-use-bytes":31535104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-10T16:03:17.180087Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1273819161,"revision":30338,"compact-revision":27744} {"level":"info","ts":"2026-06-10T16:08:17.051236Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32654} {"level":"info","ts":"2026-06-10T16:08:17.200466Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32654,"took":"144.834466ms","hash":2417295125,"current-db-size-bytes":61808640,"current-db-size":"62 MB","current-db-size-in-use-bytes":31125504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-10T16:08:17.200522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2417295125,"revision":32654,"compact-revision":30338} {"level":"info","ts":"2026-06-10T16:13:17.057284Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34920} {"level":"info","ts":"2026-06-10T16:13:17.213183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34920,"took":"151.41563ms","hash":2041071204,"current-db-size-bytes":62709760,"current-db-size":"63 MB","current-db-size-in-use-bytes":32231424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-10T16:13:17.213237Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2041071204,"revision":34920,"compact-revision":32654} {"level":"info","ts":"2026-06-10T16:16:40.718338Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T16:16:40.723861Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-10T16:16:40.723929Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-10T16:18:17.06488Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37215} {"level":"info","ts":"2026-06-10T16:18:17.188516Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37215,"took":"118.465797ms","hash":1901412726,"current-db-size-bytes":63131648,"current-db-size":"63 MB","current-db-size-in-use-bytes":33087488,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-10T16:18:17.188591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1901412726,"revision":37215,"compact-revision":34920} {"level":"info","ts":"2026-06-10T16:21:07.916218Z","caller":"traceutil/trace.go:171","msg":"trace[1961008227] linearizableReadLoop","detail":"{readStateIndex:42060; appliedIndex:42059; }","duration":"389.842197ms","start":"2026-06-10T16:21:07.526346Z","end":"2026-06-10T16:21:07.916188Z","steps":["trace[1961008227] 'read index received' (duration: 389.652214ms)","trace[1961008227] 'applied index is now lower than readState.Index' (duration: 188.473µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T16:21:07.916383Z","caller":"traceutil/trace.go:171","msg":"trace[1943252805] transaction","detail":"{read_only:false; response_revision:40786; number_of_response:1; }","duration":"392.727126ms","start":"2026-06-10T16:21:07.523609Z","end":"2026-06-10T16:21:07.916336Z","steps":["trace[1943252805] 'process raft request' (duration: 392.336318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:07.91663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.523593Z","time spent":"392.9096ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T16:21:07.917732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.354308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/placement-db-sync-gvt84.18b7c1b698cb0724\\000\" range_end:\"/registry/events/openstack0\" limit:2000 revision:40785 ","response":"range_response_count:198 size:148188"} {"level":"info","ts":"2026-06-10T16:21:07.917817Z","caller":"traceutil/trace.go:171","msg":"trace[779740146] range","detail":"{range_begin:/registry/events/openstack/placement-db-sync-gvt84.18b7c1b698cb0724\u0000; range_end:/registry/events/openstack0; response_count:198; response_revision:40786; }","duration":"391.4847ms","start":"2026-06-10T16:21:07.52631Z","end":"2026-06-10T16:21:07.917795Z","steps":["trace[779740146] 'agreement among raft nodes before linearized reading' (duration: 390.017641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:07.917938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.526297Z","time spent":"391.564712ms","remote":"127.0.0.1:37894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":106,"response count":198,"response size":148211,"request content":"key:\"/registry/events/openstack/placement-db-sync-gvt84.18b7c1b698cb0724\\000\" range_end:\"/registry/events/openstack0\" limit:2000 revision:40785 "} {"level":"info","ts":"2026-06-10T16:21:08.348273Z","caller":"traceutil/trace.go:171","msg":"trace[2146697902] linearizableReadLoop","detail":"{readStateIndex:42063; appliedIndex:42060; }","duration":"431.94856ms","start":"2026-06-10T16:21:07.916296Z","end":"2026-06-10T16:21:08.348245Z","steps":["trace[2146697902] 'read index received' (duration: 382.666623ms)","trace[2146697902] 'applied index is now lower than readState.Index' (duration: 49.280707ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T16:21:08.348297Z","caller":"traceutil/trace.go:171","msg":"trace[1899355236] transaction","detail":"{read_only:false; response_revision:40787; number_of_response:1; }","duration":"566.034593ms","start":"2026-06-10T16:21:07.782193Z","end":"2026-06-10T16:21:08.348228Z","steps":["trace[1899355236] 'process raft request' (duration: 516.753245ms)","trace[1899355236] 'compare' (duration: 49.023023ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T16:21:08.348394Z","caller":"traceutil/trace.go:171","msg":"trace[1159929784] transaction","detail":"{read_only:false; response_revision:40789; number_of_response:1; }","duration":"556.939839ms","start":"2026-06-10T16:21:07.791444Z","end":"2026-06-10T16:21:08.348384Z","steps":["trace[1159929784] 'process raft request' (duration: 556.742115ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:08.348385Z","caller":"traceutil/trace.go:171","msg":"trace[771672622] transaction","detail":"{read_only:false; response_revision:40788; number_of_response:1; }","duration":"558.553351ms","start":"2026-06-10T16:21:07.789786Z","end":"2026-06-10T16:21:08.34834Z","steps":["trace[771672622] 'process raft request' (duration: 558.347337ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:08.34852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.782167Z","time spent":"566.230707ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T16:21:08.348552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.515097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:10671"} {"level":"info","ts":"2026-06-10T16:21:08.348618Z","caller":"traceutil/trace.go:171","msg":"trace[2091908739] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:40789; }","duration":"760.619949ms","start":"2026-06-10T16:21:07.587981Z","end":"2026-06-10T16:21:08.3486Z","steps":["trace[2091908739] 'agreement among raft nodes before linearized reading' (duration: 760.442256ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:08.348656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.587964Z","time spent":"760.68622ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10694,"request content":"key:\"/registry/pods/openstack/rabbitmq-glance-server-0\" "} {"level":"warn","ts":"2026-06-10T16:21:08.348652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.78977Z","time spent":"558.766685ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T16:21:08.348525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.791435Z","time spent":"556.99038ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T16:21:08.349371Z","caller":"traceutil/trace.go:171","msg":"trace[713067337] transaction","detail":"{read_only:false; response_revision:40791; number_of_response:1; }","duration":"178.182815ms","start":"2026-06-10T16:21:08.17115Z","end":"2026-06-10T16:21:08.349333Z","steps":["trace[713067337] 'process raft request' (duration: 178.050932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:08.34947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.45884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:10637"} {"level":"info","ts":"2026-06-10T16:21:08.349581Z","caller":"traceutil/trace.go:171","msg":"trace[1708373589] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:40792; }","duration":"347.579354ms","start":"2026-06-10T16:21:08.001954Z","end":"2026-06-10T16:21:08.349533Z","steps":["trace[1708373589] 'agreement among raft nodes before linearized reading' (duration: 347.358808ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:08.349625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.001935Z","time spent":"347.681655ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":10660,"request content":"key:\"/registry/pods/openstack/rabbitmq-heat-server-0\" "} {"level":"warn","ts":"2026-06-10T16:21:08.349738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.342644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T16:21:08.349787Z","caller":"traceutil/trace.go:171","msg":"trace[1491841604] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:40792; }","duration":"157.414365ms","start":"2026-06-10T16:21:08.192359Z","end":"2026-06-10T16:21:08.349774Z","steps":["trace[1491841604] 'agreement among raft nodes before linearized reading' (duration: 157.313393ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:08.349881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.249946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T16:21:08.349935Z","caller":"traceutil/trace.go:171","msg":"trace[311931474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:40792; }","duration":"176.335237ms","start":"2026-06-10T16:21:08.173587Z","end":"2026-06-10T16:21:08.349922Z","steps":["trace[311931474] 'agreement among raft nodes before linearized reading' (duration: 176.235235ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:08.349948Z","caller":"traceutil/trace.go:171","msg":"trace[602089637] transaction","detail":"{read_only:false; response_revision:40790; number_of_response:1; }","duration":"370.796614ms","start":"2026-06-10T16:21:07.97878Z","end":"2026-06-10T16:21:08.349577Z","steps":["trace[602089637] 'process raft request' (duration: 370.284362ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:08.350691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.97875Z","time spent":"371.748382ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T16:21:09.273816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122321181592020,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T16:21:09.774333Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122321181592020,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T16:21:10.275343Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122321181592020,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T16:21:10.472531Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.757905359s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-10T16:21:10.473104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.97092881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-10T16:21:10.473205Z","caller":"traceutil/trace.go:171","msg":"trace[2065754838] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:40792; }","duration":"1.971080873s","start":"2026-06-10T16:21:08.5021Z","end":"2026-06-10T16:21:10.47318Z","steps":["trace[2065754838] 'range keys from in-memory index tree' (duration: 1.970733684s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.473259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.502079Z","time spent":"1.971167174s","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T16:21:10.473298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.024371001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:10671"} {"level":"info","ts":"2026-06-10T16:21:10.473406Z","caller":"traceutil/trace.go:171","msg":"trace[24354000] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:40792; }","duration":"2.024523294s","start":"2026-06-10T16:21:08.448851Z","end":"2026-06-10T16:21:10.473374Z","steps":["trace[24354000] 'range keys from in-memory index tree' (duration: 2.024122586s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.473453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.448832Z","time spent":"2.024610485s","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10694,"request content":"key:\"/registry/pods/openstack/rabbitmq-glance-server-0\" "} {"level":"info","ts":"2026-06-10T16:21:10.475272Z","caller":"traceutil/trace.go:171","msg":"trace[149792095] transaction","detail":"{read_only:false; response_revision:40793; number_of_response:1; }","duration":"1.760773646s","start":"2026-06-10T16:21:08.714456Z","end":"2026-06-10T16:21:10.475229Z","steps":["trace[149792095] 'process raft request' (duration: 1.758325117s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.475318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.778959965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T16:21:10.475419Z","caller":"traceutil/trace.go:171","msg":"trace[2072570971] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:40792; }","duration":"1.779113758s","start":"2026-06-10T16:21:08.696276Z","end":"2026-06-10T16:21:10.475389Z","steps":["trace[2072570971] 'count revisions from in-memory index tree' (duration: 1.778869203s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.475436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.714439Z","time spent":"1.760908419s","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T16:21:10.47548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.696264Z","time spent":"1.779197769s","remote":"127.0.0.1:48590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true "} {"level":"warn","ts":"2026-06-10T16:21:10.475715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.810522953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T16:21:10.475892Z","caller":"traceutil/trace.go:171","msg":"trace[921313890] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:40792; }","duration":"1.810748428s","start":"2026-06-10T16:21:08.665129Z","end":"2026-06-10T16:21:10.475877Z","steps":["trace[921313890] 'count revisions from in-memory index tree' (duration: 1.810448951s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.47594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.665105Z","time spent":"1.81082271s","remote":"127.0.0.1:51292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-06-10T16:21:10.479194Z","caller":"traceutil/trace.go:171","msg":"trace[934523242] transaction","detail":"{read_only:false; response_revision:40795; number_of_response:1; }","duration":"556.297417ms","start":"2026-06-10T16:21:09.922875Z","end":"2026-06-10T16:21:10.479172Z","steps":["trace[934523242] 'process raft request' (duration: 556.200015ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:10.479268Z","caller":"traceutil/trace.go:171","msg":"trace[1073137577] transaction","detail":"{read_only:false; response_revision:40794; number_of_response:1; }","duration":"1.413419139s","start":"2026-06-10T16:21:09.065824Z","end":"2026-06-10T16:21:10.479243Z","steps":["trace[1073137577] 'process raft request' (duration: 1.41294637s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.479346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:09.922851Z","time spent":"556.406878ms","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T16:21:10.479404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:09.0658Z","time spent":"1.413524641s","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T16:21:10.479436Z","caller":"traceutil/trace.go:171","msg":"trace[1584139079] transaction","detail":"{read_only:false; response_revision:40797; number_of_response:1; }","duration":"124.579102ms","start":"2026-06-10T16:21:10.354837Z","end":"2026-06-10T16:21:10.479417Z","steps":["trace[1584139079] 'process raft request' (duration: 124.369107ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:10.479444Z","caller":"traceutil/trace.go:171","msg":"trace[1626144053] transaction","detail":"{read_only:false; response_revision:40799; number_of_response:1; }","duration":"122.853096ms","start":"2026-06-10T16:21:10.356575Z","end":"2026-06-10T16:21:10.479428Z","steps":["trace[1626144053] 'process raft request' (duration: 122.784565ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:10.479416Z","caller":"traceutil/trace.go:171","msg":"trace[1324090331] transaction","detail":"{read_only:false; response_revision:40796; number_of_response:1; }","duration":"253.918098ms","start":"2026-06-10T16:21:10.225446Z","end":"2026-06-10T16:21:10.479364Z","steps":["trace[1324090331] 'process raft request' (duration: 253.686603ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:10.479538Z","caller":"traceutil/trace.go:171","msg":"trace[799395629] transaction","detail":"{read_only:false; response_revision:40798; number_of_response:1; }","duration":"123.276505ms","start":"2026-06-10T16:21:10.356246Z","end":"2026-06-10T16:21:10.479522Z","steps":["trace[799395629] 'process raft request' (duration: 123.03959ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:10.479622Z","caller":"traceutil/trace.go:171","msg":"trace[1257953006] transaction","detail":"{read_only:false; response_revision:40800; number_of_response:1; }","duration":"122.901777ms","start":"2026-06-10T16:21:10.35671Z","end":"2026-06-10T16:21:10.479612Z","steps":["trace[1257953006] 'process raft request' (duration: 122.688272ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:10.47963Z","caller":"traceutil/trace.go:171","msg":"trace[1586319382] linearizableReadLoop","detail":"{readStateIndex:42070; appliedIndex:42066; }","duration":"1.70605767s","start":"2026-06-10T16:21:08.773553Z","end":"2026-06-10T16:21:10.479611Z","steps":["trace[1586319382] 'read index received' (duration: 1.699282772s)","trace[1586319382] 'applied index is now lower than readState.Index' (duration: 6.773908ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T16:21:10.479665Z","caller":"traceutil/trace.go:171","msg":"trace[180546246] transaction","detail":"{read_only:false; response_revision:40801; number_of_response:1; }","duration":"121.497468ms","start":"2026-06-10T16:21:10.358154Z","end":"2026-06-10T16:21:10.479651Z","steps":["trace[180546246] 'process raft request' (duration: 121.389096ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.479762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.706200112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T16:21:10.479828Z","caller":"traceutil/trace.go:171","msg":"trace[897074237] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:40801; }","duration":"1.706312725s","start":"2026-06-10T16:21:08.773501Z","end":"2026-06-10T16:21:10.479814Z","steps":["trace[897074237] 'agreement among raft nodes before linearized reading' (duration: 1.706199122s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.479859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.77348Z","time spent":"1.706370035s","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":19,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-06-10T16:21:10.479855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.299414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"warn","ts":"2026-06-10T16:21:10.479864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.692464574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-10T16:21:10.479873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342232709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-10T16:21:10.479896Z","caller":"traceutil/trace.go:171","msg":"trace[1575482441] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:40801; }","duration":"678.376656ms","start":"2026-06-10T16:21:09.801507Z","end":"2026-06-10T16:21:10.479883Z","steps":["trace[1575482441] 'agreement among raft nodes before linearized reading' (duration: 678.252923ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:10.47991Z","caller":"traceutil/trace.go:171","msg":"trace[1029615366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:40801; }","duration":"1.692534776s","start":"2026-06-10T16:21:08.787361Z","end":"2026-06-10T16:21:10.479896Z","steps":["trace[1029615366] 'agreement among raft nodes before linearized reading' (duration: 1.692374093s)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:10.479919Z","caller":"traceutil/trace.go:171","msg":"trace[2015586343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:40801; }","duration":"1.342313199s","start":"2026-06-10T16:21:09.137591Z","end":"2026-06-10T16:21:10.479904Z","steps":["trace[2015586343] 'agreement among raft nodes before linearized reading' (duration: 1.342196757s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.479926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:09.801485Z","time spent":"678.433067ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1657,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-06-10T16:21:10.479919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292821979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-10T16:21:10.479951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:09.137571Z","time spent":"1.342372301s","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T16:21:10.479951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.787351Z","time spent":"1.692590097s","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-10T16:21:10.479973Z","caller":"traceutil/trace.go:171","msg":"trace[1017260574] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:40801; }","duration":"1.29287848s","start":"2026-06-10T16:21:09.187079Z","end":"2026-06-10T16:21:10.479957Z","steps":["trace[1017260574] 'agreement among raft nodes before linearized reading' (duration: 1.292784389s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.480017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.344348941s","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-10T16:21:10.480017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:09.187044Z","time spent":"1.292958501s","remote":"127.0.0.1:51312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-06-10T16:21:10.48005Z","caller":"traceutil/trace.go:171","msg":"trace[922552772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:40801; }","duration":"1.344410622s","start":"2026-06-10T16:21:09.135631Z","end":"2026-06-10T16:21:10.480041Z","steps":["trace[922552772] 'agreement among raft nodes before linearized reading' (duration: 1.34429496s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.480074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:09.135614Z","time spent":"1.344454034s","remote":"127.0.0.1:38078","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-10T16:21:10.480078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.467379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-06-10T16:21:10.480109Z","caller":"traceutil/trace.go:171","msg":"trace[801794110] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:40801; }","duration":"122.5467ms","start":"2026-06-10T16:21:10.357553Z","end":"2026-06-10T16:21:10.4801Z","steps":["trace[801794110] 'agreement among raft nodes before linearized reading' (duration: 122.462409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.480117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.344502564s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-10T16:21:10.480156Z","caller":"traceutil/trace.go:171","msg":"trace[1899549479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:40801; }","duration":"1.344568825s","start":"2026-06-10T16:21:09.135575Z","end":"2026-06-10T16:21:10.480144Z","steps":["trace[1899549479] 'agreement among raft nodes before linearized reading' (duration: 1.344456613s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.480191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:09.135558Z","time spent":"1.344623987s","remote":"127.0.0.1:38078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-10T16:21:10.480345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.239287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-10T16:21:10.480378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.726635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T16:21:10.480405Z","caller":"traceutil/trace.go:171","msg":"trace[131762348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:40801; }","duration":"307.344509ms","start":"2026-06-10T16:21:10.173046Z","end":"2026-06-10T16:21:10.48039Z","steps":["trace[131762348] 'agreement among raft nodes before linearized reading' (duration: 307.256627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.48048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:10.17302Z","time spent":"307.453981ms","remote":"127.0.0.1:37824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T16:21:10.480416Z","caller":"traceutil/trace.go:171","msg":"trace[892490923] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:40801; }","duration":"383.800065ms","start":"2026-06-10T16:21:10.096603Z","end":"2026-06-10T16:21:10.480404Z","steps":["trace[892490923] 'agreement among raft nodes before linearized reading' (duration: 383.737394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.480557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:10.096585Z","time spent":"383.952039ms","remote":"127.0.0.1:35444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-06-10T16:21:10.480923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.829567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T16:21:10.481012Z","caller":"traceutil/trace.go:171","msg":"trace[131134326] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:40801; }","duration":"989.95921ms","start":"2026-06-10T16:21:09.491035Z","end":"2026-06-10T16:21:10.480994Z","steps":["trace[131134326] 'agreement among raft nodes before linearized reading' (duration: 988.803387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.481053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:09.491014Z","time spent":"990.026562ms","remote":"127.0.0.1:51250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-06-10T16:21:10.481064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.028139556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/horizon-int-certs.18b7c34c5a58d9a9\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:40792 ","response":"range_response_count:1000 size:778402"} {"level":"info","ts":"2026-06-10T16:21:10.481117Z","caller":"traceutil/trace.go:171","msg":"trace[1452666050] range","detail":"{range_begin:/registry/events/openstack/horizon-int-certs.18b7c34c5a58d9a9\u0000; range_end:/registry/events/openstack0; response_count:1000; response_revision:40792; }","duration":"2.028218198s","start":"2026-06-10T16:21:08.452885Z","end":"2026-06-10T16:21:10.481103Z","steps":["trace[1452666050] 'range keys from in-memory index tree' (duration: 2.020804418s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.481224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.452874Z","time spent":"2.02830068s","remote":"127.0.0.1:37894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1198,"response size":778425,"request content":"key:\"/registry/events/openstack/horizon-int-certs.18b7c34c5a58d9a9\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:40792 "}