{"level":"warn","ts":"2026-06-16T22:59:06.984708Z","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-16T22:59:06.984798Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-16T22:59:06.984866Z","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-16T22:59:06.984875Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-06-16T22:59:06.984895Z","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-16T22:59:06.985519Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-06-16T22:59:06.985597Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-16T22:59:06.988658Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.805719ms"} {"level":"info","ts":"2026-06-16T22:59:06.999155Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-06-16T22:59:06.999289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-06-16T22:59:06.999324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-06-16T22:59:06.999349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-16T22:59:06.999363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-06-16T22:59:06.999416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-06-16T22:59:07.007209Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-16T22:59:07.011472Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-16T22:59:07.015795Z","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-16T22:59:07.019635Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-16T22:59:07.019832Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-16T22:59:07.019944Z","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-16T22:59:07.020052Z","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-16T22:59:07.020065Z","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-16T22:59:07.020886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-06-16T22:59:07.021049Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-06-16T22:59:07.022823Z","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-16T22:59:07.022973Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-06-16T22:59:07.02303Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-06-16T22:59:07.023138Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-16T22:59:07.023181Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-16T22:59:07.509733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-06-16T22:59:07.509781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-06-16T22:59:07.509806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-06-16T22:59:07.509819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-06-16T22:59:07.509825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-06-16T22:59:07.509833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-06-16T22:59:07.50984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-06-16T22:59:07.511502Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T22:59:07.511696Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-06-16T22:59:07.511695Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T22:59:07.511744Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T22:59:07.512122Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-16T22:59:07.512201Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-16T22:59:07.512651Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T22:59:07.512899Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T22:59:07.512988Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T22:59:07.515258Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-16T22:59:07.515361Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"warn","ts":"2026-06-16T22:59:23.451182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.511759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T22:59:23.451483Z","caller":"traceutil/trace.go:171","msg":"trace[1814548982] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:388; }","duration":"130.9946ms","start":"2026-06-16T22:59:23.320475Z","end":"2026-06-16T22:59:23.451469Z","steps":["trace[1814548982] 'read index received' (duration: 52.981µs)","trace[1814548982] 'applied index is now lower than readState.Index' (duration: 130.940919ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T22:59:23.451534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.068191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-16T22:59:23.451552Z","caller":"traceutil/trace.go:171","msg":"trace[1497173895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:374; }","duration":"131.116762ms","start":"2026-06-16T22:59:23.320427Z","end":"2026-06-16T22:59:23.451543Z","steps":["trace[1497173895] 'agreement among raft nodes before linearized reading' (duration: 131.081211ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T22:59:23.451777Z","caller":"traceutil/trace.go:171","msg":"trace[871336247] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"318.983522ms","start":"2026-06-16T22:59:23.132747Z","end":"2026-06-16T22:59:23.451731Z","steps":["trace[871336247] 'process raft request' (duration: 154.5281ms)","trace[871336247] 'compare' (duration: 163.410537ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T22:59:23.452011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T22:59:23.132727Z","time spent":"319.171037ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T22:59:23.618136Z","caller":"traceutil/trace.go:171","msg":"trace[1642014581] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"162.656929ms","start":"2026-06-16T22:59:23.455415Z","end":"2026-06-16T22:59:23.618072Z","steps":["trace[1642014581] 'process raft request' (duration: 159.787159ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T22:59:34.315441Z","caller":"traceutil/trace.go:171","msg":"trace[1294005852] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"114.443048ms","start":"2026-06-16T22:59:34.200971Z","end":"2026-06-16T22:59:34.315414Z","steps":["trace[1294005852] 'process raft request' (duration: 114.325185ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T22:59:50.086849Z","caller":"traceutil/trace.go:171","msg":"trace[1526347926] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"412.134043ms","start":"2026-06-16T22:59:49.674687Z","end":"2026-06-16T22:59:50.086821Z","steps":["trace[1526347926] 'read index received' (duration: 410.36114ms)","trace[1526347926] 'applied index is now lower than readState.Index' (duration: 1.769853ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T22:59:50.086922Z","caller":"traceutil/trace.go:171","msg":"trace[1052468411] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"549.743896ms","start":"2026-06-16T22:59:49.53715Z","end":"2026-06-16T22:59:50.086893Z","steps":["trace[1052468411] 'process raft request' (duration: 548.087116ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T22:59:50.087094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T22:59:49.537109Z","time spent":"549.876559ms","remote":"127.0.0.1:58074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T22:59:50.087101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.41199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vzk6x\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-16T22:59:50.087246Z","caller":"traceutil/trace.go:171","msg":"trace[532006546] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vzk6x; range_end:; response_count:1; response_revision:588; }","duration":"412.508102ms","start":"2026-06-16T22:59:49.674634Z","end":"2026-06-16T22:59:50.087142Z","steps":["trace[532006546] 'agreement among raft nodes before linearized reading' (duration: 412.321068ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T22:59:50.087357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T22:59:49.674614Z","time spent":"412.726927ms","remote":"127.0.0.1:58158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-vzk6x\" "} {"level":"warn","ts":"2026-06-16T22:59:50.151639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.694049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T22:59:50.151722Z","caller":"traceutil/trace.go:171","msg":"trace[281656595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"203.858604ms","start":"2026-06-16T22:59:49.947848Z","end":"2026-06-16T22:59:50.151706Z","steps":["trace[281656595] 'agreement among raft nodes before linearized reading' (duration: 203.69447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T22:59:50.15165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.22251ms","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-16T22:59:50.151746Z","caller":"traceutil/trace.go:171","msg":"trace[1839388425] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"149.84908ms","start":"2026-06-16T22:59:50.001864Z","end":"2026-06-16T22:59:50.151713Z","steps":["trace[1839388425] 'process raft request' (duration: 149.534612ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T22:59:50.151832Z","caller":"traceutil/trace.go:171","msg":"trace[478855242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"455.432414ms","start":"2026-06-16T22:59:49.696353Z","end":"2026-06-16T22:59:50.151786Z","steps":["trace[478855242] 'agreement among raft nodes before linearized reading' (duration: 455.164848ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T22:59:50.15194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T22:59:49.696321Z","time spent":"455.584988ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T22:59:50.152108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.832589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-16T22:59:50.152145Z","caller":"traceutil/trace.go:171","msg":"trace[1120727467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"400.89847ms","start":"2026-06-16T22:59:49.751238Z","end":"2026-06-16T22:59:50.152136Z","steps":["trace[1120727467] 'agreement among raft nodes before linearized reading' (duration: 400.369217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T22:59:50.152172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T22:59:49.751221Z","time spent":"400.944702ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-16T22:59:50.377431Z","caller":"traceutil/trace.go:171","msg":"trace[580571532] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"231.855523ms","start":"2026-06-16T22:59:50.145546Z","end":"2026-06-16T22:59:50.377401Z","steps":["trace[580571532] 'process raft request' (duration: 231.788461ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T22:59:50.377667Z","caller":"traceutil/trace.go:171","msg":"trace[179911266] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"274.914799ms","start":"2026-06-16T22:59:50.102697Z","end":"2026-06-16T22:59:50.377612Z","steps":["trace[179911266] 'process raft request' (duration: 274.469798ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T22:59:50.383141Z","caller":"traceutil/trace.go:171","msg":"trace[1555918848] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"226.78578ms","start":"2026-06-16T22:59:50.156301Z","end":"2026-06-16T22:59:50.383086Z","steps":["trace[1555918848] 'process raft request' (duration: 226.686098ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T22:59:50.383209Z","caller":"traceutil/trace.go:171","msg":"trace[705417042] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"227.18958ms","start":"2026-06-16T22:59:50.156009Z","end":"2026-06-16T22:59:50.383199Z","steps":["trace[705417042] 'process raft request' (duration: 226.76373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T22:59:51.051727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.346168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T22:59:51.051803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.251151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-16T22:59:51.05185Z","caller":"traceutil/trace.go:171","msg":"trace[1058918419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"505.535903ms","start":"2026-06-16T22:59:50.546284Z","end":"2026-06-16T22:59:51.05182Z","steps":["trace[1058918419] 'range keys from in-memory index tree' (duration: 505.263725ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T22:59:51.051905Z","caller":"traceutil/trace.go:171","msg":"trace[896956517] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:594; }","duration":"497.375714ms","start":"2026-06-16T22:59:50.554502Z","end":"2026-06-16T22:59:51.051878Z","steps":["trace[896956517] 'range keys from in-memory index tree' (duration: 497.16787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T22:59:51.051917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T22:59:50.546265Z","time spent":"505.638145ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T22:59:51.05195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T22:59:50.554497Z","time spent":"497.441287ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"info","ts":"2026-06-16T22:59:51.057154Z","caller":"traceutil/trace.go:171","msg":"trace[1342995308] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"289.051283ms","start":"2026-06-16T22:59:50.768082Z","end":"2026-06-16T22:59:51.057133Z","steps":["trace[1342995308] 'process raft request' (duration: 288.258724ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T22:59:57.960775Z","caller":"traceutil/trace.go:171","msg":"trace[1959661018] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"136.363313ms","start":"2026-06-16T22:59:57.824385Z","end":"2026-06-16T22:59:57.960748Z","steps":["trace[1959661018] 'process raft request' (duration: 135.912013ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:00:05.604696Z","caller":"traceutil/trace.go:171","msg":"trace[1796525670] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"229.190068ms","start":"2026-06-16T23:00:05.375476Z","end":"2026-06-16T23:00:05.604667Z","steps":["trace[1796525670] 'process raft request' (duration: 229.020684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:00:09.449253Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220562982075786,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T23:00:09.56179Z","caller":"traceutil/trace.go:171","msg":"trace[270497404] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"613.26523ms","start":"2026-06-16T23:00:08.94848Z","end":"2026-06-16T23:00:09.561746Z","steps":["trace[270497404] 'read index received' (duration: 613.080125ms)","trace[270497404] 'applied index is now lower than readState.Index' (duration: 181.825µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T23:00:09.561986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.477824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:00:09.562088Z","caller":"traceutil/trace.go:171","msg":"trace[879361267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"613.646549ms","start":"2026-06-16T23:00:08.948418Z","end":"2026-06-16T23:00:09.562064Z","steps":["trace[879361267] 'agreement among raft nodes before linearized reading' (duration: 613.461235ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:00:09.561916Z","caller":"traceutil/trace.go:171","msg":"trace[160793122] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"766.058162ms","start":"2026-06-16T23:00:08.795811Z","end":"2026-06-16T23:00:09.561869Z","steps":["trace[160793122] 'process raft request' (duration: 765.731724ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:00:09.56214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:00:08.948393Z","time spent":"613.739261ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T23:00:09.562297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:00:08.795787Z","time spent":"766.369148ms","remote":"127.0.0.1:58158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:00:14.735436Z","caller":"traceutil/trace.go:171","msg":"trace[962381593] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"103.574556ms","start":"2026-06-16T23:00:14.63183Z","end":"2026-06-16T23:00:14.735404Z","steps":["trace[962381593] 'process raft request' (duration: 103.34158ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:01:24.403129Z","caller":"traceutil/trace.go:171","msg":"trace[1318457341] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"220.277867ms","start":"2026-06-16T23:01:24.182822Z","end":"2026-06-16T23:01:24.4031Z","steps":["trace[1318457341] 'read index received' (duration: 220.204025ms)","trace[1318457341] 'applied index is now lower than readState.Index' (duration: 72.872µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:01:24.403201Z","caller":"traceutil/trace.go:171","msg":"trace[1590922354] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"317.803268ms","start":"2026-06-16T23:01:24.085359Z","end":"2026-06-16T23:01:24.403162Z","steps":["trace[1590922354] 'process raft request' (duration: 317.567013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:01:24.40347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:01:24.085335Z","time spent":"318.033953ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:01:24.403491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.616845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0edbb8a7-cc02-49e6-8103-4c7444031fc0\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-16T23:01:24.403585Z","caller":"traceutil/trace.go:171","msg":"trace[1700581911] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0edbb8a7-cc02-49e6-8103-4c7444031fc0; range_end:; response_count:1; response_revision:1837; }","duration":"220.775679ms","start":"2026-06-16T23:01:24.182787Z","end":"2026-06-16T23:01:24.403563Z","steps":["trace[1700581911] 'agreement among raft nodes before linearized reading' (duration: 220.462341ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:01:24.684427Z","caller":"traceutil/trace.go:171","msg":"trace[273369505] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"458.863886ms","start":"2026-06-16T23:01:24.225537Z","end":"2026-06-16T23:01:24.684401Z","steps":["trace[273369505] 'process raft request' (duration: 458.723003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:01:24.684885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:01:24.225509Z","time spent":"458.98953ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:01:24.750052Z","caller":"traceutil/trace.go:171","msg":"trace[281617873] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"340.072489ms","start":"2026-06-16T23:01:24.40994Z","end":"2026-06-16T23:01:24.750013Z","steps":["trace[281617873] 'process raft request' (duration: 339.888765ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:01:24.750252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:01:24.409918Z","time spent":"340.229744ms","remote":"127.0.0.1:58158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:01:25.382598Z","caller":"traceutil/trace.go:171","msg":"trace[86717559] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"323.722912ms","start":"2026-06-16T23:01:25.058835Z","end":"2026-06-16T23:01:25.382558Z","steps":["trace[86717559] 'process raft request' (duration: 234.195135ms)","trace[86717559] 'compare' (duration: 89.251679ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:01:25.382668Z","caller":"traceutil/trace.go:171","msg":"trace[1875921479] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"261.158061ms","start":"2026-06-16T23:01:25.121483Z","end":"2026-06-16T23:01:25.382641Z","steps":["trace[1875921479] 'process raft request' (duration: 260.970777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:01:25.382764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:01:25.058814Z","time spent":"323.866656ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:01:25.382884Z","caller":"traceutil/trace.go:171","msg":"trace[940753278] linearizableReadLoop","detail":"{readStateIndex:1917; appliedIndex:1915; }","duration":"255.495473ms","start":"2026-06-16T23:01:25.12736Z","end":"2026-06-16T23:01:25.382856Z","steps":["trace[940753278] 'read index received' (duration: 165.680759ms)","trace[940753278] 'applied index is now lower than readState.Index' (duration: 89.813564ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:01:25.3829Z","caller":"traceutil/trace.go:171","msg":"trace[1868082424] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"128.644527ms","start":"2026-06-16T23:01:25.254222Z","end":"2026-06-16T23:01:25.382867Z","steps":["trace[1868082424] 'process raft request' (duration: 128.275669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:01:25.383124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.98253ms","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:5"} {"level":"warn","ts":"2026-06-16T23:01:25.383133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.781151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-16T23:01:25.383164Z","caller":"traceutil/trace.go:171","msg":"trace[335476057] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1844; }","duration":"190.088062ms","start":"2026-06-16T23:01:25.193063Z","end":"2026-06-16T23:01:25.383151Z","steps":["trace[335476057] 'agreement among raft nodes before linearized reading' (duration: 189.965549ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:01:25.383173Z","caller":"traceutil/trace.go:171","msg":"trace[913659762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1844; }","duration":"255.822612ms","start":"2026-06-16T23:01:25.127341Z","end":"2026-06-16T23:01:25.383164Z","steps":["trace[913659762] 'agreement among raft nodes before linearized reading' (duration: 255.666328ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:01:30.619286Z","caller":"traceutil/trace.go:171","msg":"trace[902534961] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"187.824477ms","start":"2026-06-16T23:01:30.431435Z","end":"2026-06-16T23:01:30.619259Z","steps":["trace[902534961] 'process raft request' (duration: 187.601171ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:01:37.56539Z","caller":"traceutil/trace.go:171","msg":"trace[1730700163] linearizableReadLoop","detail":"{readStateIndex:2023; appliedIndex:2020; }","duration":"114.539264ms","start":"2026-06-16T23:01:37.450831Z","end":"2026-06-16T23:01:37.56537Z","steps":["trace[1730700163] 'read index received' (duration: 10.980056ms)","trace[1730700163] 'applied index is now lower than readState.Index' (duration: 103.558118ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:01:37.565441Z","caller":"traceutil/trace.go:171","msg":"trace[297112378] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"128.190126ms","start":"2026-06-16T23:01:37.437208Z","end":"2026-06-16T23:01:37.565398Z","steps":["trace[297112378] 'process raft request' (duration: 92.694154ms)","trace[297112378] 'compare' (duration: 35.261257ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:01:37.565514Z","caller":"traceutil/trace.go:171","msg":"trace[670863366] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"126.848624ms","start":"2026-06-16T23:01:37.43865Z","end":"2026-06-16T23:01:37.565499Z","steps":["trace[670863366] 'process raft request' (duration: 126.663619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:01:37.565954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.096669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-16T23:01:37.566046Z","caller":"traceutil/trace.go:171","msg":"trace[211311589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1945; }","duration":"115.242041ms","start":"2026-06-16T23:01:37.450785Z","end":"2026-06-16T23:01:37.566027Z","steps":["trace[211311589] 'agreement among raft nodes before linearized reading' (duration: 114.912093ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:01:40.947015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:01:40.561661Z","time spent":"385.349431ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-16T23:01:40.947074Z","caller":"traceutil/trace.go:171","msg":"trace[66481194] linearizableReadLoop","detail":"{readStateIndex:2048; appliedIndex:2048; }","duration":"281.589767ms","start":"2026-06-16T23:01:40.66545Z","end":"2026-06-16T23:01:40.947039Z","steps":["trace[66481194] 'read index received' (duration: 281.573217ms)","trace[66481194] 'applied index is now lower than readState.Index' (duration: 14.22µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T23:01:40.947426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.928925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T23:01:40.947575Z","caller":"traceutil/trace.go:171","msg":"trace[721322649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1969; }","duration":"282.129171ms","start":"2026-06-16T23:01:40.665422Z","end":"2026-06-16T23:01:40.947551Z","steps":["trace[721322649] 'agreement among raft nodes before linearized reading' (duration: 281.732561ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:01:41.215058Z","caller":"traceutil/trace.go:171","msg":"trace[668052909] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"453.000485ms","start":"2026-06-16T23:01:40.762006Z","end":"2026-06-16T23:01:41.215006Z","steps":["trace[668052909] 'process raft request' (duration: 450.699398ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:01:41.215214Z","caller":"traceutil/trace.go:171","msg":"trace[1885601249] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"263.329153ms","start":"2026-06-16T23:01:40.951852Z","end":"2026-06-16T23:01:41.215182Z","steps":["trace[1885601249] 'process raft request' (duration: 263.279242ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:01:41.215206Z","caller":"traceutil/trace.go:171","msg":"trace[171492990] linearizableReadLoop","detail":"{readStateIndex:2049; appliedIndex:2048; }","duration":"268.005366ms","start":"2026-06-16T23:01:40.947153Z","end":"2026-06-16T23:01:41.215159Z","steps":["trace[171492990] 'read index received' (duration: 265.671679ms)","trace[171492990] 'applied index is now lower than readState.Index' (duration: 2.315167ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:01:41.215226Z","caller":"traceutil/trace.go:171","msg":"trace[785498219] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"267.065674ms","start":"2026-06-16T23:01:40.948127Z","end":"2026-06-16T23:01:41.215193Z","steps":["trace[785498219] 'process raft request' (duration: 266.854759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:01:41.215547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.648698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T23:01:41.215614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.427595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-16T23:01:41.215662Z","caller":"traceutil/trace.go:171","msg":"trace[2055789212] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1972; }","duration":"351.577219ms","start":"2026-06-16T23:01:40.864068Z","end":"2026-06-16T23:01:41.215645Z","steps":["trace[2055789212] 'agreement among raft nodes before linearized reading' (duration: 351.22286ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:01:41.215663Z","caller":"traceutil/trace.go:171","msg":"trace[277137632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1972; }","duration":"267.767091ms","start":"2026-06-16T23:01:40.947874Z","end":"2026-06-16T23:01:41.215641Z","steps":["trace[277137632] 'agreement among raft nodes before linearized reading' (duration: 267.582286ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:01:41.215691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:01:40.86405Z","time spent":"351.63176ms","remote":"127.0.0.1:35912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-16T23:01:41.215808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.688908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-16T23:01:41.215894Z","caller":"traceutil/trace.go:171","msg":"trace[1834800298] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1972; }","duration":"151.867553ms","start":"2026-06-16T23:01:41.064006Z","end":"2026-06-16T23:01:41.215873Z","steps":["trace[1834800298] 'agreement among raft nodes before linearized reading' (duration: 151.496773ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:01:41.216169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:01:40.761982Z","time spent":"453.182929ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:02:05.39603Z","caller":"traceutil/trace.go:171","msg":"trace[871264058] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"105.384121ms","start":"2026-06-16T23:02:05.290611Z","end":"2026-06-16T23:02:05.395995Z","steps":["trace[871264058] 'process raft request' (duration: 105.044763ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:02:05.648052Z","caller":"traceutil/trace.go:171","msg":"trace[1993922665] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"242.835959ms","start":"2026-06-16T23:02:05.405182Z","end":"2026-06-16T23:02:05.648018Z","steps":["trace[1993922665] 'process raft request' (duration: 167.496415ms)","trace[1993922665] 'compare' (duration: 74.940945ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:02:05.648094Z","caller":"traceutil/trace.go:171","msg":"trace[614126355] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"242.864779ms","start":"2026-06-16T23:02:05.405192Z","end":"2026-06-16T23:02:05.648056Z","steps":["trace[614126355] 'process raft request' (duration: 242.701075ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:02:06.094159Z","caller":"traceutil/trace.go:171","msg":"trace[1334753196] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"121.768518ms","start":"2026-06-16T23:02:05.972362Z","end":"2026-06-16T23:02:06.09413Z","steps":["trace[1334753196] 'process raft request' (duration: 121.605584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:09.9883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.450451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:02:09.988436Z","caller":"traceutil/trace.go:171","msg":"trace[589508733] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2127; }","duration":"447.966854ms","start":"2026-06-16T23:02:09.540442Z","end":"2026-06-16T23:02:09.988409Z","steps":["trace[589508733] 'count revisions from in-memory index tree' (duration: 447.334668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:09.988762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:09.540426Z","time spent":"448.273341ms","remote":"127.0.0.1:42698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-16T23:02:10.160572Z","caller":"traceutil/trace.go:171","msg":"trace[1862353080] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"498.922258ms","start":"2026-06-16T23:02:09.661625Z","end":"2026-06-16T23:02:10.160548Z","steps":["trace[1862353080] 'process raft request' (duration: 498.768354ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:02:10.160692Z","caller":"traceutil/trace.go:171","msg":"trace[1372159971] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2212; }","duration":"498.941849ms","start":"2026-06-16T23:02:09.66172Z","end":"2026-06-16T23:02:10.160662Z","steps":["trace[1372159971] 'read index received' (duration: 498.930988ms)","trace[1372159971] 'applied index is now lower than readState.Index' (duration: 9.321µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T23:02:10.160734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:09.661609Z","time spent":"499.04302ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:02:10.160935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.193014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T23:02:10.160975Z","caller":"traceutil/trace.go:171","msg":"trace[288063052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2128; }","duration":"499.308657ms","start":"2026-06-16T23:02:09.661655Z","end":"2026-06-16T23:02:10.160964Z","steps":["trace[288063052] 'agreement among raft nodes before linearized reading' (duration: 499.132633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:10.161025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:09.661632Z","time spent":"499.383559ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-16T23:02:10.578481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.589783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T23:02:10.578709Z","caller":"traceutil/trace.go:171","msg":"trace[1293734032] linearizableReadLoop","detail":"{readStateIndex:2214; appliedIndex:2212; }","duration":"417.932707ms","start":"2026-06-16T23:02:10.16076Z","end":"2026-06-16T23:02:10.578692Z","steps":["trace[1293734032] 'read index received' (duration: 301.040056ms)","trace[1293734032] 'applied index is now lower than readState.Index' (duration: 116.891251ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:02:10.578708Z","caller":"traceutil/trace.go:171","msg":"trace[1490397895] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"905.710505ms","start":"2026-06-16T23:02:09.672963Z","end":"2026-06-16T23:02:10.578674Z","steps":["trace[1490397895] 'process raft request' (duration: 788.822765ms)","trace[1490397895] 'compare' (duration: 116.272026ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T23:02:10.578838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.945754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T23:02:10.57886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:09.672949Z","time spent":"905.834218ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:02:10.578845Z","caller":"traceutil/trace.go:171","msg":"trace[2025077356] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"901.728179ms","start":"2026-06-16T23:02:09.677058Z","end":"2026-06-16T23:02:10.578787Z","steps":["trace[2025077356] 'process raft request' (duration: 901.530084ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:02:10.578908Z","caller":"traceutil/trace.go:171","msg":"trace[1186636546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2130; }","duration":"631.063436ms","start":"2026-06-16T23:02:09.947829Z","end":"2026-06-16T23:02:10.578892Z","steps":["trace[1186636546] 'agreement among raft nodes before linearized reading' (duration: 630.941374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:10.578933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.469207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-16T23:02:10.578949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:09.947803Z","time spent":"631.139628ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T23:02:10.578972Z","caller":"traceutil/trace.go:171","msg":"trace[110624404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2130; }","duration":"857.535279ms","start":"2026-06-16T23:02:09.721425Z","end":"2026-06-16T23:02:10.578961Z","steps":["trace[110624404] 'agreement among raft nodes before linearized reading' (duration: 857.347025ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:10.579014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:09.72141Z","time spent":"857.59475ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T23:02:10.579057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:09.677044Z","time spent":"901.901523ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:02:10.579083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.283491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-16T23:02:10.579198Z","caller":"traceutil/trace.go:171","msg":"trace[358399901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2130; }","duration":"847.460774ms","start":"2026-06-16T23:02:09.731718Z","end":"2026-06-16T23:02:10.579179Z","steps":["trace[358399901] 'agreement among raft nodes before linearized reading' (duration: 847.002593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:10.579227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.204351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-16T23:02:10.579334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.414614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-16T23:02:10.579401Z","caller":"traceutil/trace.go:171","msg":"trace[861397840] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2130; }","duration":"916.524466ms","start":"2026-06-16T23:02:09.66286Z","end":"2026-06-16T23:02:10.579385Z","steps":["trace[861397840] 'agreement among raft nodes before linearized reading' (duration: 916.153417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:10.579457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:09.662841Z","time spent":"916.604089ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T23:02:10.579283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:09.731708Z","time spent":"847.563838ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-16T23:02:10.579372Z","caller":"traceutil/trace.go:171","msg":"trace[999624993] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2130; }","duration":"869.376445ms","start":"2026-06-16T23:02:09.709973Z","end":"2026-06-16T23:02:10.579349Z","steps":["trace[999624993] 'agreement among raft nodes before linearized reading' (duration: 868.895124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:10.579621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:09.709962Z","time spent":"869.645852ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-16T23:02:10.583884Z","caller":"traceutil/trace.go:171","msg":"trace[639219059] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"418.766448ms","start":"2026-06-16T23:02:10.1651Z","end":"2026-06-16T23:02:10.583867Z","steps":["trace[639219059] 'process raft request' (duration: 418.485411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:10.584317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:10.165081Z","time spent":"419.173287ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:02:10.584129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.345611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-16T23:02:10.584166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.117279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T23:02:10.584486Z","caller":"traceutil/trace.go:171","msg":"trace[152775453] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2131; }","duration":"105.733819ms","start":"2026-06-16T23:02:10.478734Z","end":"2026-06-16T23:02:10.584467Z","steps":["trace[152775453] 'agreement among raft nodes before linearized reading' (duration: 105.289609ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:02:10.584586Z","caller":"traceutil/trace.go:171","msg":"trace[286955324] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2131; }","duration":"391.58349ms","start":"2026-06-16T23:02:10.192986Z","end":"2026-06-16T23:02:10.58457Z","steps":["trace[286955324] 'agreement among raft nodes before linearized reading' (duration: 391.093118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:10.584635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:10.192964Z","time spent":"391.656721ms","remote":"127.0.0.1:58038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":54,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-06-16T23:02:14.307015Z","caller":"traceutil/trace.go:171","msg":"trace[862962557] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"136.131346ms","start":"2026-06-16T23:02:14.170857Z","end":"2026-06-16T23:02:14.306988Z","steps":["trace[862962557] 'process raft request' (duration: 135.971192ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:02:14.780427Z","caller":"traceutil/trace.go:171","msg":"trace[63418297] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"134.773892ms","start":"2026-06-16T23:02:14.645633Z","end":"2026-06-16T23:02:14.780407Z","steps":["trace[63418297] 'process raft request' (duration: 134.721701ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:02:14.78046Z","caller":"traceutil/trace.go:171","msg":"trace[1211776430] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"134.998468ms","start":"2026-06-16T23:02:14.645412Z","end":"2026-06-16T23:02:14.780411Z","steps":["trace[1211776430] 'process raft request' (duration: 134.844004ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:02:14.780665Z","caller":"traceutil/trace.go:171","msg":"trace[1854902376] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"134.68369ms","start":"2026-06-16T23:02:14.64595Z","end":"2026-06-16T23:02:14.780634Z","steps":["trace[1854902376] 'process raft request' (duration: 134.430644ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:02:14.780701Z","caller":"traceutil/trace.go:171","msg":"trace[1092835144] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"134.71087ms","start":"2026-06-16T23:02:14.645973Z","end":"2026-06-16T23:02:14.780683Z","steps":["trace[1092835144] 'process raft request' (duration: 134.596118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:21.376368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.740579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T23:02:21.376629Z","caller":"traceutil/trace.go:171","msg":"trace[301176230] linearizableReadLoop","detail":"{readStateIndex:2273; appliedIndex:2270; }","duration":"694.228506ms","start":"2026-06-16T23:02:20.682384Z","end":"2026-06-16T23:02:21.376612Z","steps":["trace[301176230] 'read index received' (duration: 105.159005ms)","trace[301176230] 'applied index is now lower than readState.Index' (duration: 589.068561ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:02:21.376713Z","caller":"traceutil/trace.go:171","msg":"trace[565625903] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"712.112509ms","start":"2026-06-16T23:02:20.664575Z","end":"2026-06-16T23:02:21.376688Z","steps":["trace[565625903] 'process raft request' (duration: 711.890194ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:02:21.376757Z","caller":"traceutil/trace.go:171","msg":"trace[1333213727] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"741.293275ms","start":"2026-06-16T23:02:20.635437Z","end":"2026-06-16T23:02:21.37673Z","steps":["trace[1333213727] 'process raft request' (duration: 219.092294ms)","trace[1333213727] 'compare' (duration: 521.412672ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T23:02:21.376873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:20.664555Z","time spent":"712.228192ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:02:21.37688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:20.63541Z","time spent":"741.413448ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:02:21.376985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.395744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T23:02:21.37703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.326567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-16T23:02:21.377077Z","caller":"traceutil/trace.go:171","msg":"trace[228027569] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2186; }","duration":"537.413319ms","start":"2026-06-16T23:02:20.839653Z","end":"2026-06-16T23:02:21.377066Z","steps":["trace[228027569] 'agreement among raft nodes before linearized reading' (duration: 537.277136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:21.377077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.049279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-16T23:02:21.377084Z","caller":"traceutil/trace.go:171","msg":"trace[663895436] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:2186; }","duration":"170.539518ms","start":"2026-06-16T23:02:21.206523Z","end":"2026-06-16T23:02:21.377063Z","steps":["trace[663895436] 'agreement among raft nodes before linearized reading' (duration: 170.371674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:21.377108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:20.839632Z","time spent":"537.46867ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-16T23:02:21.377123Z","caller":"traceutil/trace.go:171","msg":"trace[1235557175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2186; }","duration":"539.111971ms","start":"2026-06-16T23:02:20.837993Z","end":"2026-06-16T23:02:21.377105Z","steps":["trace[1235557175] 'agreement among raft nodes before linearized reading' (duration: 538.968908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:21.377167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:20.837977Z","time spent":"539.184173ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T23:02:21.377146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.53456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-16T23:02:21.377174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.058645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-16T23:02:21.377224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.618271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T23:02:21.377237Z","caller":"traceutil/trace.go:171","msg":"trace[280861236] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2186; }","duration":"446.818867ms","start":"2026-06-16T23:02:20.930394Z","end":"2026-06-16T23:02:21.377213Z","steps":["trace[280861236] 'agreement among raft nodes before linearized reading' (duration: 446.452548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:21.377257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.256451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:02:21.377268Z","caller":"traceutil/trace.go:171","msg":"trace[1551439614] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2186; }","duration":"315.685422ms","start":"2026-06-16T23:02:21.061569Z","end":"2026-06-16T23:02:21.377255Z","steps":["trace[1551439614] 'agreement among raft nodes before linearized reading' (duration: 315.61939ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:02:21.377286Z","caller":"traceutil/trace.go:171","msg":"trace[525709599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2186; }","duration":"429.294771ms","start":"2026-06-16T23:02:20.947983Z","end":"2026-06-16T23:02:21.377278Z","steps":["trace[525709599] 'agreement among raft nodes before linearized reading' (duration: 429.25233ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:02:21.377276Z","caller":"traceutil/trace.go:171","msg":"trace[1208702067] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2186; }","duration":"110.218569ms","start":"2026-06-16T23:02:21.267032Z","end":"2026-06-16T23:02:21.377251Z","steps":["trace[1208702067] 'agreement among raft nodes before linearized reading' (duration: 109.997023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:21.377294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:20.930376Z","time spent":"446.906629ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":2,"response size":29,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-06-16T23:02:21.37731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:20.947977Z","time spent":"429.328442ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T23:02:21.377301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:21.061559Z","time spent":"315.734334ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-16T23:02:21.377201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.806894ms","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-16T23:02:21.377385Z","caller":"traceutil/trace.go:171","msg":"trace[1256112744] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2186; }","duration":"570.03192ms","start":"2026-06-16T23:02:20.807343Z","end":"2026-06-16T23:02:21.377375Z","steps":["trace[1256112744] 'agreement among raft nodes before linearized reading' (duration: 569.540438ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:21.377402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.408639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-16T23:02:21.37742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:20.807326Z","time spent":"570.083841ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-16T23:02:21.377454Z","caller":"traceutil/trace.go:171","msg":"trace[399122463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2186; }","duration":"537.47124ms","start":"2026-06-16T23:02:20.839967Z","end":"2026-06-16T23:02:21.377438Z","steps":["trace[399122463] 'agreement among raft nodes before linearized reading' (duration: 537.310116ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:21.377498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:20.839962Z","time spent":"537.524162ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-16T23:02:21.377623Z","caller":"traceutil/trace.go:171","msg":"trace[414647348] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"587.971603ms","start":"2026-06-16T23:02:20.788822Z","end":"2026-06-16T23:02:21.376794Z","steps":["trace[414647348] 'process raft request' (duration: 587.743547ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:21.377646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.683506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-16T23:02:21.377724Z","caller":"traceutil/trace.go:171","msg":"trace[1167923292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2186; }","duration":"537.784218ms","start":"2026-06-16T23:02:20.839926Z","end":"2026-06-16T23:02:21.37771Z","steps":["trace[1167923292] 'agreement among raft nodes before linearized reading' (duration: 536.919037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:21.377731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:20.788805Z","time spent":"588.856745ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:02:21.377742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.370153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-16T23:02:21.377758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:20.839915Z","time spent":"537.83422ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-16T23:02:21.377781Z","caller":"traceutil/trace.go:171","msg":"trace[721490902] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2186; }","duration":"695.448425ms","start":"2026-06-16T23:02:20.68232Z","end":"2026-06-16T23:02:21.377768Z","steps":["trace[721490902] 'agreement among raft nodes before linearized reading' (duration: 694.366899ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:02:21.378003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:02:20.682302Z","time spent":"695.69055ms","remote":"127.0.0.1:35912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-16T23:02:21.672352Z","caller":"traceutil/trace.go:171","msg":"trace[1106373454] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"289.61408ms","start":"2026-06-16T23:02:21.382706Z","end":"2026-06-16T23:02:21.67232Z","steps":["trace[1106373454] 'process raft request' (duration: 241.453975ms)","trace[1106373454] 'compare' (duration: 47.984441ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:02:21.672573Z","caller":"traceutil/trace.go:171","msg":"trace[1501138081] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"289.358405ms","start":"2026-06-16T23:02:21.383185Z","end":"2026-06-16T23:02:21.672543Z","steps":["trace[1501138081] 'process raft request' (duration: 289.092458ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:02:21.672889Z","caller":"traceutil/trace.go:171","msg":"trace[1639487985] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"289.300173ms","start":"2026-06-16T23:02:21.383567Z","end":"2026-06-16T23:02:21.672867Z","steps":["trace[1639487985] 'process raft request' (duration: 288.867363ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:02:21.673059Z","caller":"traceutil/trace.go:171","msg":"trace[1461398871] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"289.431627ms","start":"2026-06-16T23:02:21.383614Z","end":"2026-06-16T23:02:21.673045Z","steps":["trace[1461398871] 'process raft request' (duration: 289.12829ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:02:21.673088Z","caller":"traceutil/trace.go:171","msg":"trace[133475438] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2275; }","duration":"226.242376ms","start":"2026-06-16T23:02:21.446816Z","end":"2026-06-16T23:02:21.673059Z","steps":["trace[133475438] 'read index received' (duration: 177.372893ms)","trace[133475438] 'applied index is now lower than readState.Index' (duration: 48.868073ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T23:02:21.67328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.443231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-16T23:02:21.673327Z","caller":"traceutil/trace.go:171","msg":"trace[123847692] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2192; }","duration":"226.548384ms","start":"2026-06-16T23:02:21.446764Z","end":"2026-06-16T23:02:21.673313Z","steps":["trace[123847692] 'agreement among raft nodes before linearized reading' (duration: 226.393399ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:03:03.664446Z","caller":"traceutil/trace.go:171","msg":"trace[969711065] transaction","detail":"{read_only:false; response_revision:2588; number_of_response:1; }","duration":"119.426771ms","start":"2026-06-16T23:03:03.545Z","end":"2026-06-16T23:03:03.664427Z","steps":["trace[969711065] 'process raft request' (duration: 117.75273ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:03:37.729861Z","caller":"traceutil/trace.go:171","msg":"trace[86096024] linearizableReadLoop","detail":"{readStateIndex:2904; appliedIndex:2903; }","duration":"202.224378ms","start":"2026-06-16T23:03:37.527613Z","end":"2026-06-16T23:03:37.729837Z","steps":["trace[86096024] 'read index received' (duration: 201.976593ms)","trace[86096024] 'applied index is now lower than readState.Index' (duration: 246.925µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:03:37.729904Z","caller":"traceutil/trace.go:171","msg":"trace[208596529] transaction","detail":"{read_only:false; response_revision:2793; number_of_response:1; }","duration":"333.618777ms","start":"2026-06-16T23:03:37.396256Z","end":"2026-06-16T23:03:37.729875Z","steps":["trace[208596529] 'process raft request' (duration: 333.386822ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:03:37.730041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:03:37.396229Z","time spent":"333.739481ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:03:37.730079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.451644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-16T23:03:37.730141Z","caller":"traceutil/trace.go:171","msg":"trace[1595458543] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2793; }","duration":"202.568876ms","start":"2026-06-16T23:03:37.527561Z","end":"2026-06-16T23:03:37.73013Z","steps":["trace[1595458543] 'agreement among raft nodes before linearized reading' (duration: 202.384122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:03:38.241787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.588726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:03:38.241852Z","caller":"traceutil/trace.go:171","msg":"trace[2088166466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2794; }","duration":"294.697189ms","start":"2026-06-16T23:03:37.947139Z","end":"2026-06-16T23:03:38.241836Z","steps":["trace[2088166466] 'range keys from in-memory index tree' (duration: 294.463173ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:03:38.242331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.099421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T23:03:38.242389Z","caller":"traceutil/trace.go:171","msg":"trace[1710798406] transaction","detail":"{read_only:false; response_revision:2795; number_of_response:1; }","duration":"280.214699ms","start":"2026-06-16T23:03:37.962165Z","end":"2026-06-16T23:03:38.24238Z","steps":["trace[1710798406] 'process raft request' (duration: 69.017135ms)","trace[1710798406] 'compare' (duration: 210.902477ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T23:03:38.599904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.293324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T23:03:38.600016Z","caller":"traceutil/trace.go:171","msg":"trace[656045849] linearizableReadLoop","detail":"{readStateIndex:2907; appliedIndex:2905; }","duration":"629.143057ms","start":"2026-06-16T23:03:37.970856Z","end":"2026-06-16T23:03:38.599999Z","steps":["trace[656045849] 'read index received' (duration: 60.259504ms)","trace[656045849] 'applied index is now lower than readState.Index' (duration: 568.882383ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:03:38.6001Z","caller":"traceutil/trace.go:171","msg":"trace[26300963] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"636.117644ms","start":"2026-06-16T23:03:37.963958Z","end":"2026-06-16T23:03:38.600075Z","steps":["trace[26300963] 'process raft request' (duration: 398.577545ms)","trace[26300963] 'compare' (duration: 237.14635ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T23:03:38.600194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.334401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-16T23:03:38.600222Z","caller":"traceutil/trace.go:171","msg":"trace[2014691114] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2796; }","duration":"629.382083ms","start":"2026-06-16T23:03:37.970832Z","end":"2026-06-16T23:03:38.600214Z","steps":["trace[2014691114] 'agreement among raft nodes before linearized reading' (duration: 629.242819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:03:38.60022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:03:37.963935Z","time spent":"636.225036ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:03:38.600253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:03:37.970825Z","time spent":"629.422043ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T23:03:38.600642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.31945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T23:03:38.600656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.254474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-16T23:03:38.600682Z","caller":"traceutil/trace.go:171","msg":"trace[103320888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2796; }","duration":"357.385602ms","start":"2026-06-16T23:03:38.243287Z","end":"2026-06-16T23:03:38.600672Z","steps":["trace[103320888] 'agreement among raft nodes before linearized reading' (duration: 357.31841ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:03:38.600694Z","caller":"traceutil/trace.go:171","msg":"trace[1334325679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2796; }","duration":"497.351157ms","start":"2026-06-16T23:03:38.10333Z","end":"2026-06-16T23:03:38.600682Z","steps":["trace[1334325679] 'agreement among raft nodes before linearized reading' (duration: 497.253994ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:03:38.60073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:03:38.103304Z","time spent":"497.416978ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T23:03:38.60071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:03:38.243273Z","time spent":"357.429902ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T23:03:38.600766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.965436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-16T23:03:38.600844Z","caller":"traceutil/trace.go:171","msg":"trace[1479498584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2796; }","duration":"475.094369ms","start":"2026-06-16T23:03:38.125729Z","end":"2026-06-16T23:03:38.600823Z","steps":["trace[1479498584] 'agreement among raft nodes before linearized reading' (duration: 474.852993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:03:38.600841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.189903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-16T23:03:38.600852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.396153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18b9b224afd841e1\" ","response":"range_response_count:1 size:765"} {"level":"warn","ts":"2026-06-16T23:03:38.600902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:03:38.125704Z","time spent":"475.189622ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-16T23:03:38.600919Z","caller":"traceutil/trace.go:171","msg":"trace[78640934] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2796; }","duration":"560.308256ms","start":"2026-06-16T23:03:38.040591Z","end":"2026-06-16T23:03:38.6009Z","steps":["trace[78640934] 'agreement among raft nodes before linearized reading' (duration: 560.05678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:03:38.600853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.482245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T23:03:38.60095Z","caller":"traceutil/trace.go:171","msg":"trace[918129162] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18b9b224afd841e1; range_end:; response_count:1; response_revision:2796; }","duration":"321.561477ms","start":"2026-06-16T23:03:38.279365Z","end":"2026-06-16T23:03:38.600926Z","steps":["trace[918129162] 'agreement among raft nodes before linearized reading' (duration: 321.2792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:03:38.600966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:03:38.040576Z","time spent":"560.381757ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T23:03:38.601011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:03:38.279335Z","time spent":"321.662749ms","remote":"127.0.0.1:58066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":787,"request content":"key:\"/registry/events/openstack/valkey-node-0.18b9b224afd841e1\" "} {"level":"info","ts":"2026-06-16T23:03:38.601001Z","caller":"traceutil/trace.go:171","msg":"trace[2054031432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2796; }","duration":"474.68632ms","start":"2026-06-16T23:03:38.12629Z","end":"2026-06-16T23:03:38.600976Z","steps":["trace[2054031432] 'agreement among raft nodes before linearized reading' (duration: 474.322101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:03:38.601079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:03:38.126272Z","time spent":"474.790152ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-16T23:06:04.498222Z","caller":"traceutil/trace.go:171","msg":"trace[956599702] linearizableReadLoop","detail":"{readStateIndex:3913; appliedIndex:3912; }","duration":"131.438473ms","start":"2026-06-16T23:06:04.366764Z","end":"2026-06-16T23:06:04.498203Z","steps":["trace[956599702] 'read index received' (duration: 131.278449ms)","trace[956599702] 'applied index is now lower than readState.Index' (duration: 159.254µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:06:04.498323Z","caller":"traceutil/trace.go:171","msg":"trace[953313647] transaction","detail":"{read_only:false; response_revision:3767; number_of_response:1; }","duration":"187.874309ms","start":"2026-06-16T23:06:04.310424Z","end":"2026-06-16T23:06:04.498298Z","steps":["trace[953313647] 'process raft request' (duration: 187.663904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:06:04.498391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.619757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-06-16T23:06:04.498416Z","caller":"traceutil/trace.go:171","msg":"trace[136527766] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:3767; }","duration":"131.661038ms","start":"2026-06-16T23:06:04.366746Z","end":"2026-06-16T23:06:04.498407Z","steps":["trace[136527766] 'agreement among raft nodes before linearized reading' (duration: 131.538425ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:07:27.454651Z","caller":"traceutil/trace.go:171","msg":"trace[418713344] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"116.73578ms","start":"2026-06-16T23:07:27.337883Z","end":"2026-06-16T23:07:27.454619Z","steps":["trace[418713344] 'process raft request' (duration: 116.242748ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:07:31.503131Z","caller":"traceutil/trace.go:171","msg":"trace[538004362] linearizableReadLoop","detail":"{readStateIndex:5194; appliedIndex:5193; }","duration":"133.125065ms","start":"2026-06-16T23:07:31.369987Z","end":"2026-06-16T23:07:31.503112Z","steps":["trace[538004362] 'read index received' (duration: 133.033963ms)","trace[538004362] 'applied index is now lower than readState.Index' (duration: 90.252µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:07:31.503241Z","caller":"traceutil/trace.go:171","msg":"trace[46307579] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"142.815397ms","start":"2026-06-16T23:07:31.360399Z","end":"2026-06-16T23:07:31.503214Z","steps":["trace[46307579] 'process raft request' (duration: 142.600262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:07:31.503315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.319929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/storage-loki-0.18b9b25f667c6eef\" ","response":"range_response_count:1 size:943"} {"level":"info","ts":"2026-06-16T23:07:31.503343Z","caller":"traceutil/trace.go:171","msg":"trace[83215517] range","detail":"{range_begin:/registry/events/monitoring/storage-loki-0.18b9b25f667c6eef; range_end:; response_count:1; response_revision:5003; }","duration":"133.36595ms","start":"2026-06-16T23:07:31.36997Z","end":"2026-06-16T23:07:31.503336Z","steps":["trace[83215517] 'agreement among raft nodes before linearized reading' (duration: 133.231357ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:07:31.766227Z","caller":"traceutil/trace.go:171","msg":"trace[1765332569] linearizableReadLoop","detail":"{readStateIndex:5195; appliedIndex:5194; }","duration":"262.994651ms","start":"2026-06-16T23:07:31.503187Z","end":"2026-06-16T23:07:31.766182Z","steps":["trace[1765332569] 'read index received' (duration: 201.407808ms)","trace[1765332569] 'applied index is now lower than readState.Index' (duration: 61.584753ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:07:31.766297Z","caller":"traceutil/trace.go:171","msg":"trace[889982742] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"298.946077ms","start":"2026-06-16T23:07:31.467316Z","end":"2026-06-16T23:07:31.766262Z","steps":["trace[889982742] 'process raft request' (duration: 237.272902ms)","trace[889982742] 'compare' (duration: 61.290635ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T23:07:31.766562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.101524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-wkwgt-tls\" ","response":"range_response_count:1 size:1966"} {"level":"info","ts":"2026-06-16T23:07:31.766611Z","caller":"traceutil/trace.go:171","msg":"trace[1612654246] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-wkwgt-tls; range_end:; response_count:1; response_revision:5004; }","duration":"327.202327ms","start":"2026-06-16T23:07:31.439394Z","end":"2026-06-16T23:07:31.766596Z","steps":["trace[1612654246] 'agreement among raft nodes before linearized reading' (duration: 326.953161ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:07:31.766654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:07:31.439383Z","time spent":"327.262058ms","remote":"127.0.0.1:52428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":1988,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-wkwgt-tls\" "} {"level":"warn","ts":"2026-06-16T23:07:31.7668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.295598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-25823b27-8c5a-4336-978e-2369912b382c\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-06-16T23:07:31.766927Z","caller":"traceutil/trace.go:171","msg":"trace[695762305] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-25823b27-8c5a-4336-978e-2369912b382c; range_end:; response_count:1; response_revision:5004; }","duration":"334.493983ms","start":"2026-06-16T23:07:31.432407Z","end":"2026-06-16T23:07:31.766901Z","steps":["trace[695762305] 'agreement among raft nodes before linearized reading' (duration: 334.127194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:07:31.76698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:07:31.432389Z","time spent":"334.578344ms","remote":"127.0.0.1:58158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3622,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-25823b27-8c5a-4336-978e-2369912b382c\" "} {"level":"info","ts":"2026-06-16T23:07:31.774018Z","caller":"traceutil/trace.go:171","msg":"trace[1668547736] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"268.203785ms","start":"2026-06-16T23:07:31.505756Z","end":"2026-06-16T23:07:31.77396Z","steps":["trace[1668547736] 'process raft request' (duration: 267.974129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:07:31.774134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.059187ms","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-16T23:07:31.774235Z","caller":"traceutil/trace.go:171","msg":"trace[934621707] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5007; }","duration":"252.207501ms","start":"2026-06-16T23:07:31.522008Z","end":"2026-06-16T23:07:31.774216Z","steps":["trace[934621707] 'agreement among raft nodes before linearized reading' (duration: 252.003666ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:07:31.77437Z","caller":"traceutil/trace.go:171","msg":"trace[1137505754] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"263.587284ms","start":"2026-06-16T23:07:31.510733Z","end":"2026-06-16T23:07:31.774321Z","steps":["trace[1137505754] 'process raft request' (duration: 263.132193ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:07:31.774451Z","caller":"traceutil/trace.go:171","msg":"trace[2077166697] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"224.663947ms","start":"2026-06-16T23:07:31.549777Z","end":"2026-06-16T23:07:31.774441Z","steps":["trace[2077166697] 'process raft request' (duration: 224.156295ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:07:32.302009Z","caller":"traceutil/trace.go:171","msg":"trace[729849293] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"113.751099ms","start":"2026-06-16T23:07:32.188223Z","end":"2026-06-16T23:07:32.301974Z","steps":["trace[729849293] 'process raft request' (duration: 113.366699ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:07:44.728622Z","caller":"traceutil/trace.go:171","msg":"trace[1563180167] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"532.185151ms","start":"2026-06-16T23:07:44.196412Z","end":"2026-06-16T23:07:44.728597Z","steps":["trace[1563180167] 'process raft request' (duration: 532.018497ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:07:44.728781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:07:44.196393Z","time spent":"532.308863ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:07:44.750949Z","caller":"traceutil/trace.go:171","msg":"trace[185186113] transaction","detail":"{read_only:false; response_revision:5248; number_of_response:1; }","duration":"547.997312ms","start":"2026-06-16T23:07:44.202902Z","end":"2026-06-16T23:07:44.750899Z","steps":["trace[185186113] 'process raft request' (duration: 547.808587ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:07:44.751177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:07:44.202882Z","time spent":"548.176696ms","remote":"127.0.0.1:58158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8040,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:07:53.186137Z","caller":"traceutil/trace.go:171","msg":"trace[1240962476] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"138.631748ms","start":"2026-06-16T23:07:53.047487Z","end":"2026-06-16T23:07:53.186118Z","steps":["trace[1240962476] 'process raft request' (duration: 138.527235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:07:53.761044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.074937ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:07:53.76118Z","caller":"traceutil/trace.go:171","msg":"trace[2016276921] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5432; }","duration":"247.22684ms","start":"2026-06-16T23:07:53.513928Z","end":"2026-06-16T23:07:53.761155Z","steps":["trace[2016276921] 'range keys from in-memory index tree' (duration: 247.030796ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:07:53.761469Z","caller":"traceutil/trace.go:171","msg":"trace[999768259] linearizableReadLoop","detail":"{readStateIndex:5635; appliedIndex:5634; }","duration":"542.53423ms","start":"2026-06-16T23:07:53.218907Z","end":"2026-06-16T23:07:53.761441Z","steps":["trace[999768259] 'read index received' (duration: 466.216273ms)","trace[999768259] 'applied index is now lower than readState.Index' (duration: 76.316627ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:07:53.761607Z","caller":"traceutil/trace.go:171","msg":"trace[723543290] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"628.637672ms","start":"2026-06-16T23:07:53.132957Z","end":"2026-06-16T23:07:53.761595Z","steps":["trace[723543290] 'process raft request' (duration: 552.186012ms)","trace[723543290] 'compare' (duration: 76.182873ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T23:07:53.761741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.744552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T23:07:53.761822Z","caller":"traceutil/trace.go:171","msg":"trace[1627027872] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:5433; }","duration":"534.934087ms","start":"2026-06-16T23:07:53.22687Z","end":"2026-06-16T23:07:53.761805Z","steps":["trace[1627027872] 'agreement among raft nodes before linearized reading' (duration: 534.755433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:07:53.761869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:07:53.226849Z","time spent":"535.007659ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-16T23:07:53.761738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:07:53.132931Z","time spent":"628.708034ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:07:53.762352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.73023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f73f1024-4e4b-40b5-b751-1741e36899ab\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-06-16T23:07:53.762373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.823823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-06-16T23:07:53.762409Z","caller":"traceutil/trace.go:171","msg":"trace[1882728365] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f73f1024-4e4b-40b5-b751-1741e36899ab; range_end:; response_count:1; response_revision:5433; }","duration":"234.842743ms","start":"2026-06-16T23:07:53.527551Z","end":"2026-06-16T23:07:53.762394Z","steps":["trace[1882728365] 'agreement among raft nodes before linearized reading' (duration: 234.683129ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:07:53.762428Z","caller":"traceutil/trace.go:171","msg":"trace[55441233] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5433; }","duration":"207.920225ms","start":"2026-06-16T23:07:53.55449Z","end":"2026-06-16T23:07:53.76241Z","steps":["trace[55441233] 'agreement among raft nodes before linearized reading' (duration: 207.754241ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:07:53.762713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.791761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f73f1024-4e4b-40b5-b751-1741e36899ab\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-06-16T23:07:53.762774Z","caller":"traceutil/trace.go:171","msg":"trace[1238786032] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f73f1024-4e4b-40b5-b751-1741e36899ab; range_end:; response_count:1; response_revision:5433; }","duration":"543.889063ms","start":"2026-06-16T23:07:53.218868Z","end":"2026-06-16T23:07:53.762758Z","steps":["trace[1238786032] 'agreement among raft nodes before linearized reading' (duration: 543.166305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:07:53.76286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:07:53.218852Z","time spent":"543.995835ms","remote":"127.0.0.1:58158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f73f1024-4e4b-40b5-b751-1741e36899ab\" "} {"level":"warn","ts":"2026-06-16T23:07:55.530898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.149748ms","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-16T23:07:55.530998Z","caller":"traceutil/trace.go:171","msg":"trace[1052914385] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5456; }","duration":"200.286382ms","start":"2026-06-16T23:07:55.330689Z","end":"2026-06-16T23:07:55.530975Z","steps":["trace[1052914385] 'range keys from in-memory index tree' (duration: 200.024046ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:07:55.530999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.695674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-06-16T23:07:55.53105Z","caller":"traceutil/trace.go:171","msg":"trace[952512166] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5456; }","duration":"218.776826ms","start":"2026-06-16T23:07:55.312261Z","end":"2026-06-16T23:07:55.531038Z","steps":["trace[952512166] 'range keys from in-memory index tree' (duration: 218.487629ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:07:59.869828Z","caller":"traceutil/trace.go:171","msg":"trace[281219301] transaction","detail":"{read_only:false; response_revision:5525; number_of_response:1; }","duration":"132.569602ms","start":"2026-06-16T23:07:59.737236Z","end":"2026-06-16T23:07:59.869806Z","steps":["trace[281219301] 'process raft request' (duration: 132.413458ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:09:07.598672Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2958} {"level":"info","ts":"2026-06-16T23:09:07.672818Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2958,"took":"72.638303ms","hash":3552198651,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":34279424,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-16T23:09:07.672893Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3552198651,"revision":2958,"compact-revision":-1} {"level":"info","ts":"2026-06-16T23:14:07.607108Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6104} {"level":"info","ts":"2026-06-16T23:14:07.696181Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6104,"took":"87.083903ms","hash":1245429621,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":23461888,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-16T23:14:07.696253Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1245429621,"revision":6104,"compact-revision":2958} {"level":"info","ts":"2026-06-16T23:14:16.734784Z","caller":"traceutil/trace.go:171","msg":"trace[602018748] transaction","detail":"{read_only:false; response_revision:8344; number_of_response:1; }","duration":"147.141249ms","start":"2026-06-16T23:14:16.587613Z","end":"2026-06-16T23:14:16.734754Z","steps":["trace[602018748] 'process raft request' (duration: 146.973335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:14:17.78998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.690795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T23:14:17.78998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.114082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:14:17.790053Z","caller":"traceutil/trace.go:171","msg":"trace[1403710829] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8347; }","duration":"218.820428ms","start":"2026-06-16T23:14:17.571218Z","end":"2026-06-16T23:14:17.790038Z","steps":["trace[1403710829] 'range keys from in-memory index tree' (duration: 218.596802ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:14:17.790071Z","caller":"traceutil/trace.go:171","msg":"trace[788551266] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8347; }","duration":"110.266795ms","start":"2026-06-16T23:14:17.679783Z","end":"2026-06-16T23:14:17.79005Z","steps":["trace[788551266] 'count revisions from in-memory index tree' (duration: 109.989739ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:14:46.077217Z","caller":"traceutil/trace.go:171","msg":"trace[1187071674] transaction","detail":"{read_only:false; response_revision:8553; number_of_response:1; }","duration":"448.163909ms","start":"2026-06-16T23:14:45.629023Z","end":"2026-06-16T23:14:46.077187Z","steps":["trace[1187071674] 'process raft request' (duration: 448.019996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:14:46.077386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:14:45.629003Z","time spent":"448.301933ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:14:46.213764Z","caller":"traceutil/trace.go:171","msg":"trace[916296824] linearizableReadLoop","detail":"{readStateIndex:8858; appliedIndex:8855; }","duration":"266.987021ms","start":"2026-06-16T23:14:45.946752Z","end":"2026-06-16T23:14:46.213739Z","steps":["trace[916296824] 'read index received' (duration: 130.20553ms)","trace[916296824] 'applied index is now lower than readState.Index' (duration: 136.780031ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:14:46.214149Z","caller":"traceutil/trace.go:171","msg":"trace[175209396] transaction","detail":"{read_only:false; response_revision:8554; number_of_response:1; }","duration":"576.661739ms","start":"2026-06-16T23:14:45.63747Z","end":"2026-06-16T23:14:46.214132Z","steps":["trace[175209396] 'process raft request' (duration: 576.050975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:14:46.214872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.104937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:14:46.214947Z","caller":"traceutil/trace.go:171","msg":"trace[1053476708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8554; }","duration":"268.221101ms","start":"2026-06-16T23:14:45.946705Z","end":"2026-06-16T23:14:46.214926Z","steps":["trace[1053476708] 'agreement among raft nodes before linearized reading' (duration: 268.118178ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:14:46.216268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:14:45.637461Z","time spent":"576.727011ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:14:50.696464Z","caller":"traceutil/trace.go:171","msg":"trace[566658506] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"463.42902ms","start":"2026-06-16T23:14:50.233008Z","end":"2026-06-16T23:14:50.696437Z","steps":["trace[566658506] 'process raft request' (duration: 463.287827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:14:50.696621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:14:50.232995Z","time spent":"463.546842ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:14:50.854612Z","caller":"traceutil/trace.go:171","msg":"trace[914197842] transaction","detail":"{read_only:false; response_revision:8577; number_of_response:1; }","duration":"619.11423ms","start":"2026-06-16T23:14:50.235474Z","end":"2026-06-16T23:14:50.854588Z","steps":["trace[914197842] 'process raft request' (duration: 619.025868ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:14:50.854765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:14:50.235464Z","time spent":"619.222282ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:14:50.854831Z","caller":"traceutil/trace.go:171","msg":"trace[47482141] linearizableReadLoop","detail":"{readStateIndex:8881; appliedIndex:8879; }","duration":"619.737625ms","start":"2026-06-16T23:14:50.235065Z","end":"2026-06-16T23:14:50.854802Z","steps":["trace[47482141] 'read index received' (duration: 461.293978ms)","trace[47482141] 'applied index is now lower than readState.Index' (duration: 158.442427ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:14:50.854997Z","caller":"traceutil/trace.go:171","msg":"trace[297991676] transaction","detail":"{read_only:false; response_revision:8576; number_of_response:1; }","duration":"620.442372ms","start":"2026-06-16T23:14:50.234542Z","end":"2026-06-16T23:14:50.854984Z","steps":["trace[297991676] 'process raft request' (duration: 596.023359ms)","trace[297991676] 'compare' (duration: 23.794608ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:14:50.85507Z","caller":"traceutil/trace.go:171","msg":"trace[2010372871] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"619.232912ms","start":"2026-06-16T23:14:50.235824Z","end":"2026-06-16T23:14:50.855057Z","steps":["trace[2010372871] 'process raft request' (duration: 618.72888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:14:50.855138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:14:50.234517Z","time spent":"620.586856ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:14:50.855199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:14:50.235819Z","time spent":"619.302724ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:14:50.855395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.328179ms","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-16T23:14:50.855429Z","caller":"traceutil/trace.go:171","msg":"trace[278602488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8578; }","duration":"620.391961ms","start":"2026-06-16T23:14:50.235028Z","end":"2026-06-16T23:14:50.85542Z","steps":["trace[278602488] 'agreement among raft nodes before linearized reading' (duration: 620.280048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:14:50.855461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:14:50.235014Z","time spent":"620.441011ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T23:14:50.864787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.497163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-16T23:14:50.864817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.741488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T23:14:50.864844Z","caller":"traceutil/trace.go:171","msg":"trace[373205539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8579; }","duration":"613.584386ms","start":"2026-06-16T23:14:50.251247Z","end":"2026-06-16T23:14:50.864831Z","steps":["trace[373205539] 'agreement among raft nodes before linearized reading' (duration: 613.404941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:14:50.864967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:14:50.251231Z","time spent":"613.723839ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T23:14:50.864964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.664757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T23:14:50.865035Z","caller":"traceutil/trace.go:171","msg":"trace[1387970805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8579; }","duration":"613.76285ms","start":"2026-06-16T23:14:50.251257Z","end":"2026-06-16T23:14:50.865019Z","steps":["trace[1387970805] 'agreement among raft nodes before linearized reading' (duration: 613.577156ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:14:50.864847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.496085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-16T23:14:50.865105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:14:50.251244Z","time spent":"613.852482ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-16T23:14:50.865133Z","caller":"traceutil/trace.go:171","msg":"trace[228188759] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:8579; }","duration":"258.836633ms","start":"2026-06-16T23:14:50.606284Z","end":"2026-06-16T23:14:50.86512Z","steps":["trace[228188759] 'agreement among raft nodes before linearized reading' (duration: 258.433913ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:14:50.864887Z","caller":"traceutil/trace.go:171","msg":"trace[764427173] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:8579; }","duration":"566.857161ms","start":"2026-06-16T23:14:50.298014Z","end":"2026-06-16T23:14:50.864871Z","steps":["trace[764427173] 'agreement among raft nodes before linearized reading' (duration: 566.727407ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:14:50.865232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:14:50.297998Z","time spent":"567.217821ms","remote":"127.0.0.1:58202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":14,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-06-16T23:14:59.848491Z","caller":"traceutil/trace.go:171","msg":"trace[303538898] transaction","detail":"{read_only:false; response_revision:8667; number_of_response:1; }","duration":"226.54419ms","start":"2026-06-16T23:14:59.621916Z","end":"2026-06-16T23:14:59.848461Z","steps":["trace[303538898] 'process raft request' (duration: 226.369106ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:15:00.071949Z","caller":"traceutil/trace.go:171","msg":"trace[1297138799] transaction","detail":"{read_only:false; response_revision:8668; number_of_response:1; }","duration":"445.430013ms","start":"2026-06-16T23:14:59.626491Z","end":"2026-06-16T23:15:00.071921Z","steps":["trace[1297138799] 'process raft request' (duration: 445.30727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:15:00.072057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:14:59.626479Z","time spent":"445.523325ms","remote":"127.0.0.1:58158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4637,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:15:00.850501Z","caller":"traceutil/trace.go:171","msg":"trace[1783020476] transaction","detail":"{read_only:false; response_revision:8674; number_of_response:1; }","duration":"172.766214ms","start":"2026-06-16T23:15:00.677703Z","end":"2026-06-16T23:15:00.850469Z","steps":["trace[1783020476] 'process raft request' (duration: 104.812835ms)","trace[1783020476] 'compare' (duration: 67.722294ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:15:00.850787Z","caller":"traceutil/trace.go:171","msg":"trace[948800222] transaction","detail":"{read_only:false; response_revision:8675; number_of_response:1; }","duration":"124.297798ms","start":"2026-06-16T23:15:00.726431Z","end":"2026-06-16T23:15:00.850729Z","steps":["trace[948800222] 'process raft request' (duration: 123.96046ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:15:01.383178Z","caller":"traceutil/trace.go:171","msg":"trace[1914144021] transaction","detail":"{read_only:false; response_revision:8684; number_of_response:1; }","duration":"146.175299ms","start":"2026-06-16T23:15:01.236976Z","end":"2026-06-16T23:15:01.383151Z","steps":["trace[1914144021] 'process raft request' (duration: 145.994874ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:15:23.883026Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002431.wal"} {"level":"info","ts":"2026-06-16T23:16:03.419597Z","caller":"traceutil/trace.go:171","msg":"trace[1440684212] transaction","detail":"{read_only:false; response_revision:9344; number_of_response:1; }","duration":"143.806202ms","start":"2026-06-16T23:16:03.275767Z","end":"2026-06-16T23:16:03.419573Z","steps":["trace[1440684212] 'process raft request' (duration: 140.697367ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:16:15.387609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.947425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-16T23:16:15.387698Z","caller":"traceutil/trace.go:171","msg":"trace[1346886153] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:9409; }","duration":"130.066588ms","start":"2026-06-16T23:16:15.25761Z","end":"2026-06-16T23:16:15.387676Z","steps":["trace[1346886153] 'range keys from in-memory index tree' (duration: 129.801561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:16:15.387738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.954906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-16T23:16:15.387817Z","caller":"traceutil/trace.go:171","msg":"trace[1371454976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9409; }","duration":"109.062978ms","start":"2026-06-16T23:16:15.278736Z","end":"2026-06-16T23:16:15.387799Z","steps":["trace[1371454976] 'range keys from in-memory index tree' (duration: 108.780152ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:16:15.516278Z","caller":"traceutil/trace.go:171","msg":"trace[2010122815] transaction","detail":"{read_only:false; response_revision:9410; number_of_response:1; }","duration":"123.845887ms","start":"2026-06-16T23:16:15.392414Z","end":"2026-06-16T23:16:15.51626Z","steps":["trace[2010122815] 'process raft request' (duration: 123.708144ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:16:15.525437Z","caller":"traceutil/trace.go:171","msg":"trace[387755576] transaction","detail":"{read_only:false; response_revision:9411; number_of_response:1; }","duration":"132.922377ms","start":"2026-06-16T23:16:15.392498Z","end":"2026-06-16T23:16:15.52542Z","steps":["trace[387755576] 'process raft request' (duration: 132.799234ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:16:15.525714Z","caller":"traceutil/trace.go:171","msg":"trace[1928342897] linearizableReadLoop","detail":"{readStateIndex:9740; appliedIndex:9738; }","duration":"111.053688ms","start":"2026-06-16T23:16:15.414635Z","end":"2026-06-16T23:16:15.525689Z","steps":["trace[1928342897] 'read index received' (duration: 101.505495ms)","trace[1928342897] 'applied index is now lower than readState.Index' (duration: 9.546492ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T23:16:15.525983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.335034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-16T23:16:15.52607Z","caller":"traceutil/trace.go:171","msg":"trace[261427283] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:9412; }","duration":"111.464547ms","start":"2026-06-16T23:16:15.414587Z","end":"2026-06-16T23:16:15.526052Z","steps":["trace[261427283] 'agreement among raft nodes before linearized reading' (duration: 111.247302ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:16:15.72275Z","caller":"traceutil/trace.go:171","msg":"trace[154655303] transaction","detail":"{read_only:false; response_revision:9415; number_of_response:1; }","duration":"191.664535ms","start":"2026-06-16T23:16:15.531047Z","end":"2026-06-16T23:16:15.722711Z","steps":["trace[154655303] 'process raft request' (duration: 191.416419ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:16:15.722753Z","caller":"traceutil/trace.go:171","msg":"trace[213173639] transaction","detail":"{read_only:false; response_revision:9416; number_of_response:1; }","duration":"191.661945ms","start":"2026-06-16T23:16:15.531064Z","end":"2026-06-16T23:16:15.722726Z","steps":["trace[213173639] 'process raft request' (duration: 191.575343ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:16:15.723032Z","caller":"traceutil/trace.go:171","msg":"trace[984789005] transaction","detail":"{read_only:false; response_revision:9417; number_of_response:1; }","duration":"191.852459ms","start":"2026-06-16T23:16:15.531168Z","end":"2026-06-16T23:16:15.72302Z","steps":["trace[984789005] 'process raft request' (duration: 191.517551ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:16:15.723003Z","caller":"traceutil/trace.go:171","msg":"trace[548394210] linearizableReadLoop","detail":"{readStateIndex:9746; appliedIndex:9743; }","duration":"117.700768ms","start":"2026-06-16T23:16:15.605251Z","end":"2026-06-16T23:16:15.722952Z","steps":["trace[548394210] 'read index received' (duration: 117.119904ms)","trace[548394210] 'applied index is now lower than readState.Index' (duration: 578.924µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T23:16:15.723234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.969185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xtfx8\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-06-16T23:16:15.723286Z","caller":"traceutil/trace.go:171","msg":"trace[1596104503] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xtfx8; range_end:; response_count:1; response_revision:9417; }","duration":"118.067247ms","start":"2026-06-16T23:16:15.605207Z","end":"2026-06-16T23:16:15.723274Z","steps":["trace[1596104503] 'agreement among raft nodes before linearized reading' (duration: 117.849122ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:16:49.494389Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-16T23:16:49.498722Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-16T23:16:49.498795Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-16T23:19:07.614016Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8296} {"level":"info","ts":"2026-06-16T23:19:07.692049Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8296,"took":"75.79039ms","hash":2554979204,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":26599424,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-16T23:19:07.692194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2554979204,"revision":8296,"compact-revision":6104} {"level":"info","ts":"2026-06-16T23:19:36.616238Z","caller":"traceutil/trace.go:171","msg":"trace[451736482] linearizableReadLoop","detail":"{readStateIndex:11517; appliedIndex:11516; }","duration":"159.059902ms","start":"2026-06-16T23:19:36.457145Z","end":"2026-06-16T23:19:36.616205Z","steps":["trace[451736482] 'read index received' (duration: 158.748564ms)","trace[451736482] 'applied index is now lower than readState.Index' (duration: 309.568µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:19:36.616342Z","caller":"traceutil/trace.go:171","msg":"trace[1026914062] transaction","detail":"{read_only:false; response_revision:11139; number_of_response:1; }","duration":"164.964155ms","start":"2026-06-16T23:19:36.451345Z","end":"2026-06-16T23:19:36.616309Z","steps":["trace[1026914062] 'process raft request' (duration: 164.654718ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:19:36.616542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.37929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-16T23:19:36.616589Z","caller":"traceutil/trace.go:171","msg":"trace[1021719546] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11139; }","duration":"159.447522ms","start":"2026-06-16T23:19:36.45713Z","end":"2026-06-16T23:19:36.616577Z","steps":["trace[1021719546] 'agreement among raft nodes before linearized reading' (duration: 159.271397ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:19:36.616619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.75846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-16T23:19:36.616619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.886903ms","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-16T23:19:36.616662Z","caller":"traceutil/trace.go:171","msg":"trace[423752317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11139; }","duration":"157.825811ms","start":"2026-06-16T23:19:36.458827Z","end":"2026-06-16T23:19:36.616653Z","steps":["trace[423752317] 'agreement among raft nodes before linearized reading' (duration: 157.648427ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:19:36.616679Z","caller":"traceutil/trace.go:171","msg":"trace[1973916390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11139; }","duration":"136.976635ms","start":"2026-06-16T23:19:36.479687Z","end":"2026-06-16T23:19:36.616664Z","steps":["trace[1973916390] 'agreement among raft nodes before linearized reading' (duration: 136.79402ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:19:53.67728Z","caller":"traceutil/trace.go:171","msg":"trace[672817575] linearizableReadLoop","detail":"{readStateIndex:11615; appliedIndex:11614; }","duration":"183.081506ms","start":"2026-06-16T23:19:53.494171Z","end":"2026-06-16T23:19:53.677253Z","steps":["trace[672817575] 'read index received' (duration: 183.034064ms)","trace[672817575] 'applied index is now lower than readState.Index' (duration: 46.292µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:19:53.677367Z","caller":"traceutil/trace.go:171","msg":"trace[1379816173] transaction","detail":"{read_only:false; response_revision:11234; number_of_response:1; }","duration":"431.905338ms","start":"2026-06-16T23:19:53.245428Z","end":"2026-06-16T23:19:53.677334Z","steps":["trace[1379816173] 'process raft request' (duration: 431.663771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:19:53.677447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.2653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:19:53.677499Z","caller":"traceutil/trace.go:171","msg":"trace[208697965] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11234; }","duration":"183.424305ms","start":"2026-06-16T23:19:53.494064Z","end":"2026-06-16T23:19:53.677489Z","steps":["trace[208697965] 'agreement among raft nodes before linearized reading' (duration: 183.332022ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:19:53.677604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:19:53.245406Z","time spent":"432.038882ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:19:53.677637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.134411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T23:19:53.677727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.724446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:19:53.677755Z","caller":"traceutil/trace.go:171","msg":"trace[853738606] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11234; }","duration":"149.368157ms","start":"2026-06-16T23:19:53.528363Z","end":"2026-06-16T23:19:53.677731Z","steps":["trace[853738606] 'agreement among raft nodes before linearized reading' (duration: 149.211084ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:19:53.677776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.768217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-06-16T23:19:53.677803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.601653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:19:53.67783Z","caller":"traceutil/trace.go:171","msg":"trace[1600480216] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11234; }","duration":"149.844118ms","start":"2026-06-16T23:19:53.527973Z","end":"2026-06-16T23:19:53.677817Z","steps":["trace[1600480216] 'agreement among raft nodes before linearized reading' (duration: 149.631763ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:19:53.677871Z","caller":"traceutil/trace.go:171","msg":"trace[349024789] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11234; }","duration":"182.719537ms","start":"2026-06-16T23:19:53.495131Z","end":"2026-06-16T23:19:53.677851Z","steps":["trace[349024789] 'agreement among raft nodes before linearized reading' (duration: 182.590894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:19:53.677885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.341011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:19:53.67793Z","caller":"traceutil/trace.go:171","msg":"trace[301167794] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11234; }","duration":"150.416883ms","start":"2026-06-16T23:19:53.527499Z","end":"2026-06-16T23:19:53.677915Z","steps":["trace[301167794] 'agreement among raft nodes before linearized reading' (duration: 150.31538ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:19:53.677908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.752928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T23:19:53.677954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.245481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-06-16T23:19:53.677997Z","caller":"traceutil/trace.go:171","msg":"trace[2051464479] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11234; }","duration":"182.85649ms","start":"2026-06-16T23:19:53.495119Z","end":"2026-06-16T23:19:53.677976Z","steps":["trace[2051464479] 'agreement among raft nodes before linearized reading' (duration: 182.674236ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:19:53.677947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.175598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:19:53.678057Z","caller":"traceutil/trace.go:171","msg":"trace[186099219] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11234; }","duration":"183.297421ms","start":"2026-06-16T23:19:53.494749Z","end":"2026-06-16T23:19:53.678047Z","steps":["trace[186099219] 'agreement among raft nodes before linearized reading' (duration: 182.741128ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:19:53.678056Z","caller":"traceutil/trace.go:171","msg":"trace[1949015346] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11234; }","duration":"181.399605ms","start":"2026-06-16T23:19:53.496634Z","end":"2026-06-16T23:19:53.678033Z","steps":["trace[1949015346] 'agreement among raft nodes before linearized reading' (duration: 181.104998ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:19:53.678067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.707866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:19:53.678134Z","caller":"traceutil/trace.go:171","msg":"trace[1770759366] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11234; }","duration":"150.235218ms","start":"2026-06-16T23:19:53.527883Z","end":"2026-06-16T23:19:53.678118Z","steps":["trace[1770759366] 'agreement among raft nodes before linearized reading' (duration: 149.678214ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:19:53.678078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.573573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-06-16T23:19:53.67819Z","caller":"traceutil/trace.go:171","msg":"trace[303133066] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11234; }","duration":"149.877369ms","start":"2026-06-16T23:19:53.527926Z","end":"2026-06-16T23:19:53.677803Z","steps":["trace[303133066] 'agreement among raft nodes before linearized reading' (duration: 149.697965ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:19:53.679527Z","caller":"traceutil/trace.go:171","msg":"trace[321350185] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11234; }","duration":"147.87674ms","start":"2026-06-16T23:19:53.530384Z","end":"2026-06-16T23:19:53.67826Z","steps":["trace[321350185] 'agreement among raft nodes before linearized reading' (duration: 147.232015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:19:56.145415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.298701ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-16T23:19:56.145941Z","caller":"traceutil/trace.go:171","msg":"trace[547503579] transaction","detail":"{read_only:false; response_revision:11248; number_of_response:1; }","duration":"147.634435ms","start":"2026-06-16T23:19:55.998273Z","end":"2026-06-16T23:19:56.145907Z","steps":["trace[547503579] 'process raft request' (duration: 147.251836ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:24:07.620011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10780} {"level":"info","ts":"2026-06-16T23:24:07.70603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10780,"took":"83.74407ms","hash":3016872348,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":27467776,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-16T23:24:07.706096Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3016872348,"revision":10780,"compact-revision":8296} {"level":"info","ts":"2026-06-16T23:25:55.2001Z","caller":"traceutil/trace.go:171","msg":"trace[1619728775] transaction","detail":"{read_only:false; response_revision:13689; number_of_response:1; }","duration":"164.296211ms","start":"2026-06-16T23:25:55.035771Z","end":"2026-06-16T23:25:55.200068Z","steps":["trace[1619728775] 'process raft request' (duration: 164.046175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:25:58.315336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.896701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T23:25:58.315409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.42819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xtfx8\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-06-16T23:25:58.315447Z","caller":"traceutil/trace.go:171","msg":"trace[393632886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13703; }","duration":"369.048933ms","start":"2026-06-16T23:25:57.946376Z","end":"2026-06-16T23:25:58.315425Z","steps":["trace[393632886] 'range keys from in-memory index tree' (duration: 368.832328ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:25:58.315464Z","caller":"traceutil/trace.go:171","msg":"trace[1836849497] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xtfx8; range_end:; response_count:1; response_revision:13703; }","duration":"210.518272ms","start":"2026-06-16T23:25:58.104927Z","end":"2026-06-16T23:25:58.315446Z","steps":["trace[1836849497] 'range keys from in-memory index tree' (duration: 210.260076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:25:58.315498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:25:57.946362Z","time spent":"369.125016ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T23:25:58.316136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.027833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T23:25:58.316197Z","caller":"traceutil/trace.go:171","msg":"trace[554000060] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:13703; }","duration":"305.149326ms","start":"2026-06-16T23:25:58.011033Z","end":"2026-06-16T23:25:58.316182Z","steps":["trace[554000060] 'count revisions from in-memory index tree' (duration: 304.950861ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:25:58.316241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:25:58.01101Z","time spent":"305.216758ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-06-16T23:26:16.628432Z","caller":"traceutil/trace.go:171","msg":"trace[1347037775] linearizableReadLoop","detail":"{readStateIndex:14276; appliedIndex:14275; }","duration":"131.829876ms","start":"2026-06-16T23:26:16.496578Z","end":"2026-06-16T23:26:16.628408Z","steps":["trace[1347037775] 'read index received' (duration: 131.735243ms)","trace[1347037775] 'applied index is now lower than readState.Index' (duration: 93.523µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:26:16.628474Z","caller":"traceutil/trace.go:171","msg":"trace[2066115911] transaction","detail":"{read_only:false; response_revision:13805; number_of_response:1; }","duration":"133.144537ms","start":"2026-06-16T23:26:16.495296Z","end":"2026-06-16T23:26:16.628441Z","steps":["trace[2066115911] 'process raft request' (duration: 132.972172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:16.628697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.107343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-16T23:26:16.628751Z","caller":"traceutil/trace.go:171","msg":"trace[1156529405] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13805; }","duration":"132.176565ms","start":"2026-06-16T23:26:16.496564Z","end":"2026-06-16T23:26:16.62874Z","steps":["trace[1156529405] 'agreement among raft nodes before linearized reading' (duration: 131.958509ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:16.663458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.196484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T23:26:16.663559Z","caller":"traceutil/trace.go:171","msg":"trace[107777488] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:13806; }","duration":"132.339328ms","start":"2026-06-16T23:26:16.531198Z","end":"2026-06-16T23:26:16.663537Z","steps":["trace[107777488] 'agreement among raft nodes before linearized reading' (duration: 132.152313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:16.663567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.414829ms","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-16T23:26:16.663748Z","caller":"traceutil/trace.go:171","msg":"trace[1801825750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13806; }","duration":"165.602573ms","start":"2026-06-16T23:26:16.498132Z","end":"2026-06-16T23:26:16.663734Z","steps":["trace[1801825750] 'agreement among raft nodes before linearized reading' (duration: 165.131682ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:26:16.85654Z","caller":"traceutil/trace.go:171","msg":"trace[526414157] transaction","detail":"{read_only:false; response_revision:13807; number_of_response:1; }","duration":"224.04302ms","start":"2026-06-16T23:26:16.632474Z","end":"2026-06-16T23:26:16.856517Z","steps":["trace[526414157] 'process raft request' (duration: 211.968238ms)","trace[526414157] 'compare' (duration: 11.825346ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:26:16.856609Z","caller":"traceutil/trace.go:171","msg":"trace[483616582] transaction","detail":"{read_only:false; response_revision:13809; number_of_response:1; }","duration":"188.080928ms","start":"2026-06-16T23:26:16.668518Z","end":"2026-06-16T23:26:16.856599Z","steps":["trace[483616582] 'process raft request' (duration: 187.950175ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:26:16.856537Z","caller":"traceutil/trace.go:171","msg":"trace[1798074116] transaction","detail":"{read_only:false; response_revision:13808; number_of_response:1; }","duration":"188.495798ms","start":"2026-06-16T23:26:16.668014Z","end":"2026-06-16T23:26:16.85651Z","steps":["trace[1798074116] 'process raft request' (duration: 188.405996ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:26:16.856667Z","caller":"traceutil/trace.go:171","msg":"trace[718957121] transaction","detail":"{read_only:false; response_revision:13810; number_of_response:1; }","duration":"188.044927ms","start":"2026-06-16T23:26:16.668604Z","end":"2026-06-16T23:26:16.856649Z","steps":["trace[718957121] 'process raft request' (duration: 187.930904ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:26:24.124098Z","caller":"traceutil/trace.go:171","msg":"trace[974065880] transaction","detail":"{read_only:false; response_revision:13845; number_of_response:1; }","duration":"124.159829ms","start":"2026-06-16T23:26:23.999903Z","end":"2026-06-16T23:26:24.124063Z","steps":["trace[974065880] 'process raft request' (duration: 123.726908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.075439Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220562982109219,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T23:26:33.243566Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.050291215s","expected-duration":"1s"} {"level":"info","ts":"2026-06-16T23:26:33.24401Z","caller":"traceutil/trace.go:171","msg":"trace[1458925858] linearizableReadLoop","detail":"{readStateIndex:14378; appliedIndex:14377; }","duration":"668.928713ms","start":"2026-06-16T23:26:32.575057Z","end":"2026-06-16T23:26:33.243986Z","steps":["trace[1458925858] 'read index received' (duration: 668.780759ms)","trace[1458925858] 'applied index is now lower than readState.Index' (duration: 146.593µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:26:33.244137Z","caller":"traceutil/trace.go:171","msg":"trace[1481133632] transaction","detail":"{read_only:false; response_revision:13903; number_of_response:1; }","duration":"1.051049764s","start":"2026-06-16T23:26:32.193034Z","end":"2026-06-16T23:26:33.244083Z","steps":["trace[1481133632] 'process raft request' (duration: 1.050781477s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.244198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.118958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:26:33.24424Z","caller":"traceutil/trace.go:171","msg":"trace[1200191807] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:13903; }","duration":"669.188939ms","start":"2026-06-16T23:26:32.575039Z","end":"2026-06-16T23:26:33.244228Z","steps":["trace[1200191807] 'agreement among raft nodes before linearized reading' (duration: 669.092707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.244279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:32.57498Z","time spent":"669.288371ms","remote":"127.0.0.1:48044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-16T23:26:33.244346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:32.192998Z","time spent":"1.051209077s","remote":"127.0.0.1:58158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12479,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:26:33.244401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.90912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-16T23:26:33.244446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.950867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:26:33.24448Z","caller":"traceutil/trace.go:171","msg":"trace[1772690218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13903; }","duration":"305.015513ms","start":"2026-06-16T23:26:32.939446Z","end":"2026-06-16T23:26:33.244461Z","steps":["trace[1772690218] 'agreement among raft nodes before linearized reading' (duration: 304.794018ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.24452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.161356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-16T23:26:33.244522Z","caller":"traceutil/trace.go:171","msg":"trace[221569725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13903; }","duration":"297.05325ms","start":"2026-06-16T23:26:32.947452Z","end":"2026-06-16T23:26:33.244505Z","steps":["trace[221569725] 'agreement among raft nodes before linearized reading' (duration: 296.928927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.244518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:32.939438Z","time spent":"305.074004ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-16T23:26:33.244611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.004215ms","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-16T23:26:33.244638Z","caller":"traceutil/trace.go:171","msg":"trace[1481974635] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13903; }","duration":"301.061066ms","start":"2026-06-16T23:26:32.943567Z","end":"2026-06-16T23:26:33.244628Z","steps":["trace[1481974635] 'agreement among raft nodes before linearized reading' (duration: 300.990264ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.244652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.659731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-16T23:26:33.244657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:32.943553Z","time spent":"301.099707ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-16T23:26:33.244679Z","caller":"traceutil/trace.go:171","msg":"trace[2118271631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13903; }","duration":"306.723713ms","start":"2026-06-16T23:26:32.937948Z","end":"2026-06-16T23:26:33.244671Z","steps":["trace[2118271631] 'agreement among raft nodes before linearized reading' (duration: 306.644821ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.244401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.086929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xtfx8\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-06-16T23:26:33.244713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:32.937926Z","time spent":"306.780015ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-16T23:26:33.244728Z","caller":"traceutil/trace.go:171","msg":"trace[2051187806] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xtfx8; range_end:; response_count:1; response_revision:13903; }","duration":"660.477358ms","start":"2026-06-16T23:26:32.584238Z","end":"2026-06-16T23:26:33.244715Z","steps":["trace[2051187806] 'agreement among raft nodes before linearized reading' (duration: 659.987906ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.244763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:32.584216Z","time spent":"660.53753ms","remote":"127.0.0.1:58158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6549,"request content":"key:\"/registry/pods/openstack/keepalived-xtfx8\" "} {"level":"info","ts":"2026-06-16T23:26:33.244568Z","caller":"traceutil/trace.go:171","msg":"trace[1804884380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13903; }","duration":"305.219407ms","start":"2026-06-16T23:26:32.939331Z","end":"2026-06-16T23:26:33.24455Z","steps":["trace[1804884380] 'agreement among raft nodes before linearized reading' (duration: 305.120045ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.244859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:32.939325Z","time spent":"305.520734ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T23:26:33.244962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.080987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-16T23:26:33.245017Z","caller":"traceutil/trace.go:171","msg":"trace[111001468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13903; }","duration":"551.158659ms","start":"2026-06-16T23:26:32.693843Z","end":"2026-06-16T23:26:33.245002Z","steps":["trace[111001468] 'agreement among raft nodes before linearized reading' (duration: 550.921903ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.245053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:32.693826Z","time spent":"551.22013ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-16T23:26:33.595678Z","caller":"traceutil/trace.go:171","msg":"trace[1930671196] transaction","detail":"{read_only:false; response_revision:13904; number_of_response:1; }","duration":"345.273598ms","start":"2026-06-16T23:26:33.250381Z","end":"2026-06-16T23:26:33.595654Z","steps":["trace[1930671196] 'process raft request' (duration: 313.312074ms)","trace[1930671196] 'compare' (duration: 31.500353ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T23:26:33.595815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:33.250349Z","time spent":"345.39374ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:26:33.601091Z","caller":"traceutil/trace.go:171","msg":"trace[10301214] transaction","detail":"{read_only:false; response_revision:13905; number_of_response:1; }","duration":"338.801851ms","start":"2026-06-16T23:26:33.262238Z","end":"2026-06-16T23:26:33.60104Z","steps":["trace[10301214] 'process raft request' (duration: 338.401921ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:26:33.601118Z","caller":"traceutil/trace.go:171","msg":"trace[237185734] transaction","detail":"{read_only:false; response_revision:13906; number_of_response:1; }","duration":"338.731789ms","start":"2026-06-16T23:26:33.262328Z","end":"2026-06-16T23:26:33.601059Z","steps":["trace[237185734] 'process raft request' (duration: 338.592346ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.601293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:33.262229Z","time spent":"338.966176ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:26:33.601446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:33.262322Z","time spent":"338.962135ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:26:33.601512Z","caller":"traceutil/trace.go:171","msg":"trace[2080635970] transaction","detail":"{read_only:false; response_revision:13907; number_of_response:1; }","duration":"339.110378ms","start":"2026-06-16T23:26:33.262366Z","end":"2026-06-16T23:26:33.601476Z","steps":["trace[2080635970] 'process raft request' (duration: 338.627827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.601785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:33.26236Z","time spent":"339.335014ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:26:33.601888Z","caller":"traceutil/trace.go:171","msg":"trace[1828367207] transaction","detail":"{read_only:false; response_revision:13908; number_of_response:1; }","duration":"339.253373ms","start":"2026-06-16T23:26:33.262621Z","end":"2026-06-16T23:26:33.601875Z","steps":["trace[1828367207] 'process raft request' (duration: 338.569266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.601958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:33.262601Z","time spent":"339.323715ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T23:26:33.602647Z","caller":"traceutil/trace.go:171","msg":"trace[653390389] linearizableReadLoop","detail":"{readStateIndex:14380; appliedIndex:14378; }","duration":"340.318338ms","start":"2026-06-16T23:26:33.262296Z","end":"2026-06-16T23:26:33.602614Z","steps":["trace[653390389] 'read index received' (duration: 301.489597ms)","trace[653390389] 'applied index is now lower than readState.Index' (duration: 38.827581ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:26:33.602746Z","caller":"traceutil/trace.go:171","msg":"trace[456510033] transaction","detail":"{read_only:false; response_revision:13909; number_of_response:1; }","duration":"272.916025ms","start":"2026-06-16T23:26:33.329819Z","end":"2026-06-16T23:26:33.602735Z","steps":["trace[456510033] 'process raft request' (duration: 272.011834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.60289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.492203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-dm5tq\" ","response":"range_response_count:1 size:12494"} {"level":"info","ts":"2026-06-16T23:26:33.602969Z","caller":"traceutil/trace.go:171","msg":"trace[1521415134] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-dm5tq; range_end:; response_count:1; response_revision:13909; }","duration":"340.596856ms","start":"2026-06-16T23:26:33.262353Z","end":"2026-06-16T23:26:33.60295Z","steps":["trace[1521415134] 'agreement among raft nodes before linearized reading' (duration: 340.394441ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.60301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:33.262347Z","time spent":"340.654777ms","remote":"127.0.0.1:58158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12516,"request content":"key:\"/registry/pods/openstack/openvswitch-dm5tq\" "} {"level":"warn","ts":"2026-06-16T23:26:33.602902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.781973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T23:26:33.603159Z","caller":"traceutil/trace.go:171","msg":"trace[1955322466] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:13909; }","duration":"297.04449ms","start":"2026-06-16T23:26:33.306082Z","end":"2026-06-16T23:26:33.603126Z","steps":["trace[1955322466] 'agreement among raft nodes before linearized reading' (duration: 296.749073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.603507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.201959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:594962"} {"level":"info","ts":"2026-06-16T23:26:33.603571Z","caller":"traceutil/trace.go:171","msg":"trace[1251429535] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:13909; }","duration":"341.309172ms","start":"2026-06-16T23:26:33.262247Z","end":"2026-06-16T23:26:33.603556Z","steps":["trace[1251429535] 'agreement among raft nodes before linearized reading' (duration: 340.573874ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:33.603613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:33.262227Z","time spent":"341.376054ms","remote":"127.0.0.1:58158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":63,"response size":594984,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-06-16T23:26:34.660594Z","caller":"traceutil/trace.go:171","msg":"trace[548647809] linearizableReadLoop","detail":"{readStateIndex:14390; appliedIndex:14389; }","duration":"324.955686ms","start":"2026-06-16T23:26:34.335622Z","end":"2026-06-16T23:26:34.660578Z","steps":["trace[548647809] 'read index received' (duration: 324.823043ms)","trace[548647809] 'applied index is now lower than readState.Index' (duration: 132.163µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:26:34.660716Z","caller":"traceutil/trace.go:171","msg":"trace[203550689] transaction","detail":"{read_only:false; response_revision:13915; number_of_response:1; }","duration":"465.916432ms","start":"2026-06-16T23:26:34.194767Z","end":"2026-06-16T23:26:34.660683Z","steps":["trace[203550689] 'process raft request' (duration: 465.722487ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:34.660809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.175062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:415445"} {"level":"info","ts":"2026-06-16T23:26:34.660844Z","caller":"traceutil/trace.go:171","msg":"trace[211286759] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:13915; }","duration":"325.262244ms","start":"2026-06-16T23:26:34.335574Z","end":"2026-06-16T23:26:34.660837Z","steps":["trace[211286759] 'agreement among raft nodes before linearized reading' (duration: 325.077299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:34.660864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:34.335551Z","time spent":"325.306835ms","remote":"127.0.0.1:38356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":415467,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-06-16T23:26:34.660862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:34.194751Z","time spent":"466.036706ms","remote":"127.0.0.1:58158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12580,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:26:34.661022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.518569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:16 size:151643"} {"level":"info","ts":"2026-06-16T23:26:34.661105Z","caller":"traceutil/trace.go:171","msg":"trace[1330968967] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:16; response_revision:13915; }","duration":"202.646752ms","start":"2026-06-16T23:26:34.458437Z","end":"2026-06-16T23:26:34.661084Z","steps":["trace[1330968967] 'agreement among raft nodes before linearized reading' (duration: 202.279603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:54.989295Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220562982110088,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T23:26:55.193072Z","caller":"traceutil/trace.go:171","msg":"trace[357649057] linearizableReadLoop","detail":"{readStateIndex:14702; appliedIndex:14701; }","duration":"704.060111ms","start":"2026-06-16T23:26:54.488979Z","end":"2026-06-16T23:26:55.193039Z","steps":["trace[357649057] 'read index received' (duration: 703.867157ms)","trace[357649057] 'applied index is now lower than readState.Index' (duration: 191.364µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:26:55.193162Z","caller":"traceutil/trace.go:171","msg":"trace[1591952107] transaction","detail":"{read_only:false; response_revision:14214; number_of_response:1; }","duration":"744.409699ms","start":"2026-06-16T23:26:54.448706Z","end":"2026-06-16T23:26:55.193115Z","steps":["trace[1591952107] 'process raft request' (duration: 744.176904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:55.193232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.233005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-16T23:26:55.193256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.384188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-144b3576-03e3-404d-9887-7b672af84330\" ","response":"range_response_count:1 size:3756"} {"level":"info","ts":"2026-06-16T23:26:55.193302Z","caller":"traceutil/trace.go:171","msg":"trace[1273900223] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:14214; }","duration":"704.367828ms","start":"2026-06-16T23:26:54.488918Z","end":"2026-06-16T23:26:55.193286Z","steps":["trace[1273900223] 'agreement among raft nodes before linearized reading' (duration: 704.235165ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:26:55.193311Z","caller":"traceutil/trace.go:171","msg":"trace[1587203431] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-144b3576-03e3-404d-9887-7b672af84330; range_end:; response_count:1; response_revision:14214; }","duration":"699.48508ms","start":"2026-06-16T23:26:54.493809Z","end":"2026-06-16T23:26:55.193294Z","steps":["trace[1587203431] 'agreement among raft nodes before linearized reading' (duration: 699.389398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:26:55.193343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:54.488896Z","time spent":"704.43629ms","remote":"127.0.0.1:52430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":29,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-06-16T23:26:55.193356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:54.493773Z","time spent":"699.570103ms","remote":"127.0.0.1:58158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3778,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-144b3576-03e3-404d-9887-7b672af84330\" "} {"level":"warn","ts":"2026-06-16T23:26:55.193348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:26:54.448681Z","time spent":"744.565862ms","remote":"127.0.0.1:58158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3741,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:26:55.193353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.91463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:26:55.193502Z","caller":"traceutil/trace.go:171","msg":"trace[1062767106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14214; }","duration":"247.129416ms","start":"2026-06-16T23:26:54.94635Z","end":"2026-06-16T23:26:55.19348Z","steps":["trace[1062767106] 'agreement among raft nodes before linearized reading' (duration: 246.90343ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:28:20.098298Z","caller":"traceutil/trace.go:171","msg":"trace[885118877] transaction","detail":"{read_only:false; response_revision:15017; number_of_response:1; }","duration":"104.407539ms","start":"2026-06-16T23:28:19.993857Z","end":"2026-06-16T23:28:20.098265Z","steps":["trace[885118877] 'process raft request' (duration: 103.881486ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:28:20.365098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.046988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T23:28:20.365195Z","caller":"traceutil/trace.go:171","msg":"trace[448353102] transaction","detail":"{read_only:false; response_revision:15019; number_of_response:1; }","duration":"262.018526ms","start":"2026-06-16T23:28:20.10316Z","end":"2026-06-16T23:28:20.365178Z","steps":["trace[448353102] 'process raft request' (duration: 34.804203ms)","trace[448353102] 'compare' (duration: 226.830033ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:28:20.377925Z","caller":"traceutil/trace.go:171","msg":"trace[1958197530] linearizableReadLoop","detail":"{readStateIndex:15534; appliedIndex:15530; }","duration":"165.935208ms","start":"2026-06-16T23:28:20.21197Z","end":"2026-06-16T23:28:20.377906Z","steps":["trace[1958197530] 'read index received' (duration: 165.774764ms)","trace[1958197530] 'applied index is now lower than readState.Index' (duration: 159.684µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:28:20.37814Z","caller":"traceutil/trace.go:171","msg":"trace[1208421219] transaction","detail":"{read_only:false; response_revision:15020; number_of_response:1; }","duration":"273.908813ms","start":"2026-06-16T23:28:20.104224Z","end":"2026-06-16T23:28:20.378133Z","steps":["trace[1208421219] 'process raft request' (duration: 273.495364ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:28:20.378267Z","caller":"traceutil/trace.go:171","msg":"trace[1858229937] transaction","detail":"{read_only:false; response_revision:15021; number_of_response:1; }","duration":"273.936504ms","start":"2026-06-16T23:28:20.104322Z","end":"2026-06-16T23:28:20.378259Z","steps":["trace[1858229937] 'process raft request' (duration: 273.496674ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:28:20.378356Z","caller":"traceutil/trace.go:171","msg":"trace[290484397] transaction","detail":"{read_only:false; response_revision:15022; number_of_response:1; }","duration":"273.785191ms","start":"2026-06-16T23:28:20.104564Z","end":"2026-06-16T23:28:20.378349Z","steps":["trace[290484397] 'process raft request' (duration: 273.282459ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:28:20.378429Z","caller":"traceutil/trace.go:171","msg":"trace[1331659488] transaction","detail":"{read_only:false; response_revision:15023; number_of_response:1; }","duration":"271.875474ms","start":"2026-06-16T23:28:20.106549Z","end":"2026-06-16T23:28:20.378424Z","steps":["trace[1331659488] 'process raft request' (duration: 271.322991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:28:20.378528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.543133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:28:20.378553Z","caller":"traceutil/trace.go:171","msg":"trace[1441619275] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15023; }","duration":"166.581524ms","start":"2026-06-16T23:28:20.211965Z","end":"2026-06-16T23:28:20.378546Z","steps":["trace[1441619275] 'agreement among raft nodes before linearized reading' (duration: 166.528933ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:28:20.378698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.660436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-16T23:28:20.378721Z","caller":"traceutil/trace.go:171","msg":"trace[763167721] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15023; }","duration":"166.716477ms","start":"2026-06-16T23:28:20.211999Z","end":"2026-06-16T23:28:20.378716Z","steps":["trace[763167721] 'agreement among raft nodes before linearized reading' (duration: 166.634245ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:28:20.379175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.107337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-06-16T23:28:20.379209Z","caller":"traceutil/trace.go:171","msg":"trace[287163636] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15023; }","duration":"167.181929ms","start":"2026-06-16T23:28:20.212019Z","end":"2026-06-16T23:28:20.379201Z","steps":["trace[287163636] 'agreement among raft nodes before linearized reading' (duration: 167.091926ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:28:25.952864Z","caller":"traceutil/trace.go:171","msg":"trace[953819093] linearizableReadLoop","detail":"{readStateIndex:15557; appliedIndex:15556; }","duration":"270.401377ms","start":"2026-06-16T23:28:25.682442Z","end":"2026-06-16T23:28:25.952844Z","steps":["trace[953819093] 'read index received' (duration: 270.247924ms)","trace[953819093] 'applied index is now lower than readState.Index' (duration: 152.773µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:28:25.953159Z","caller":"traceutil/trace.go:171","msg":"trace[1806615468] transaction","detail":"{read_only:false; response_revision:15045; number_of_response:1; }","duration":"402.255991ms","start":"2026-06-16T23:28:25.550894Z","end":"2026-06-16T23:28:25.95315Z","steps":["trace[1806615468] 'process raft request' (duration: 401.844531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:28:25.953246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:28:25.550866Z","time spent":"402.320923ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T23:28:25.953392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.948111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T23:28:25.953419Z","caller":"traceutil/trace.go:171","msg":"trace[1955987419] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:15045; }","duration":"271.011282ms","start":"2026-06-16T23:28:25.682401Z","end":"2026-06-16T23:28:25.953412Z","steps":["trace[1955987419] 'agreement among raft nodes before linearized reading' (duration: 270.960491ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:28:26.248557Z","caller":"traceutil/trace.go:171","msg":"trace[1045928439] transaction","detail":"{read_only:false; response_revision:15048; number_of_response:1; }","duration":"100.207427ms","start":"2026-06-16T23:28:26.148311Z","end":"2026-06-16T23:28:26.248518Z","steps":["trace[1045928439] 'process raft request' (duration: 100.054704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:28:26.248597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.947095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:28:26.248705Z","caller":"traceutil/trace.go:171","msg":"trace[247638672] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15048; }","duration":"101.059267ms","start":"2026-06-16T23:28:26.147619Z","end":"2026-06-16T23:28:26.248678Z","steps":["trace[247638672] 'agreement among raft nodes before linearized reading' (duration: 100.863212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:28:26.248819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.249198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-16T23:28:26.248892Z","caller":"traceutil/trace.go:171","msg":"trace[2001525505] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15048; }","duration":"100.3326ms","start":"2026-06-16T23:28:26.148541Z","end":"2026-06-16T23:28:26.248874Z","steps":["trace[2001525505] 'agreement among raft nodes before linearized reading' (duration: 100.146905ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:28:28.08942Z","caller":"traceutil/trace.go:171","msg":"trace[2108148006] linearizableReadLoop","detail":"{readStateIndex:15568; appliedIndex:15567; }","duration":"141.23435ms","start":"2026-06-16T23:28:27.948158Z","end":"2026-06-16T23:28:28.089393Z","steps":["trace[2108148006] 'read index received' (duration: 140.972844ms)","trace[2108148006] 'applied index is now lower than readState.Index' (duration: 260.106µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:28:28.089531Z","caller":"traceutil/trace.go:171","msg":"trace[1602722672] transaction","detail":"{read_only:false; response_revision:15055; number_of_response:1; }","duration":"245.736911ms","start":"2026-06-16T23:28:27.843765Z","end":"2026-06-16T23:28:28.089502Z","steps":["trace[1602722672] 'process raft request' (duration: 245.463474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:28:28.089583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.411555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:28:28.089623Z","caller":"traceutil/trace.go:171","msg":"trace[1768187800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15055; }","duration":"141.531597ms","start":"2026-06-16T23:28:27.94808Z","end":"2026-06-16T23:28:28.089612Z","steps":["trace[1768187800] 'agreement among raft nodes before linearized reading' (duration: 141.448525ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:28:51.794477Z","caller":"traceutil/trace.go:171","msg":"trace[230315222] transaction","detail":"{read_only:false; response_revision:15183; number_of_response:1; }","duration":"144.690419ms","start":"2026-06-16T23:28:51.649758Z","end":"2026-06-16T23:28:51.794448Z","steps":["trace[230315222] 'process raft request' (duration: 144.469024ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:29:07.625389Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12864} {"level":"info","ts":"2026-06-16T23:29:07.697844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12864,"took":"69.935055ms","hash":3757990926,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":30679040,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-16T23:29:07.697907Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3757990926,"revision":12864,"compact-revision":10780} {"level":"warn","ts":"2026-06-16T23:30:06.135844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.108445ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-16T23:30:06.135961Z","caller":"traceutil/trace.go:171","msg":"trace[1782476893] linearizableReadLoop","detail":"{readStateIndex:16224; appliedIndex:16223; }","duration":"427.083716ms","start":"2026-06-16T23:30:05.708859Z","end":"2026-06-16T23:30:06.135943Z","steps":["trace[1782476893] 'read index received' (duration: 56.886519ms)","trace[1782476893] 'applied index is now lower than readState.Index' (duration: 370.195757ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T23:30:06.13619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.940923ms","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-16T23:30:06.136258Z","caller":"traceutil/trace.go:171","msg":"trace[690494999] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15689; }","duration":"425.043516ms","start":"2026-06-16T23:30:05.711198Z","end":"2026-06-16T23:30:06.136242Z","steps":["trace[690494999] 'agreement among raft nodes before linearized reading' (duration: 424.940543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:30:06.136287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:30:05.71118Z","time spent":"425.101708ms","remote":"127.0.0.1:58420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-16T23:30:06.136303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.446904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-95mvx\" ","response":"range_response_count:1 size:20640"} {"level":"warn","ts":"2026-06-16T23:30:06.136328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.420995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-jqbn6\" ","response":"range_response_count:1 size:9790"} {"level":"info","ts":"2026-06-16T23:30:06.136355Z","caller":"traceutil/trace.go:171","msg":"trace[1007378694] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-95mvx; range_end:; response_count:1; response_revision:15689; }","duration":"427.541986ms","start":"2026-06-16T23:30:05.7088Z","end":"2026-06-16T23:30:06.136342Z","steps":["trace[1007378694] 'agreement among raft nodes before linearized reading' (duration: 427.372082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:30:06.136303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.369658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T23:30:06.13639Z","caller":"traceutil/trace.go:171","msg":"trace[2031302975] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-jqbn6; range_end:; response_count:1; response_revision:15689; }","duration":"253.543048ms","start":"2026-06-16T23:30:05.882832Z","end":"2026-06-16T23:30:06.136375Z","steps":["trace[2031302975] 'agreement among raft nodes before linearized reading' (duration: 253.350143ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:30:06.136404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T23:30:05.708775Z","time spent":"427.620448ms","remote":"127.0.0.1:58158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20662,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-95mvx\" "} {"level":"info","ts":"2026-06-16T23:30:06.136459Z","caller":"traceutil/trace.go:171","msg":"trace[391311751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15689; }","duration":"186.560343ms","start":"2026-06-16T23:30:05.949869Z","end":"2026-06-16T23:30:06.136429Z","steps":["trace[391311751] 'agreement among raft nodes before linearized reading' (duration: 186.307977ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:30:06.78979Z","caller":"traceutil/trace.go:171","msg":"trace[392109630] transaction","detail":"{read_only:false; response_revision:15690; number_of_response:1; }","duration":"260.416726ms","start":"2026-06-16T23:30:06.529346Z","end":"2026-06-16T23:30:06.789763Z","steps":["trace[392109630] 'process raft request' (duration: 260.246501ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:30:06.79078Z","caller":"traceutil/trace.go:171","msg":"trace[2030817639] linearizableReadLoop","detail":"{readStateIndex:16227; appliedIndex:16224; }","duration":"109.626128ms","start":"2026-06-16T23:30:06.681127Z","end":"2026-06-16T23:30:06.790753Z","steps":["trace[2030817639] 'read index received' (duration: 108.586463ms)","trace[2030817639] 'applied index is now lower than readState.Index' (duration: 1.038555ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:30:06.790844Z","caller":"traceutil/trace.go:171","msg":"trace[357735569] transaction","detail":"{read_only:false; response_revision:15691; number_of_response:1; }","duration":"258.206861ms","start":"2026-06-16T23:30:06.532614Z","end":"2026-06-16T23:30:06.790821Z","steps":["trace[357735569] 'process raft request' (duration: 257.893323ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:30:06.790899Z","caller":"traceutil/trace.go:171","msg":"trace[2089121717] transaction","detail":"{read_only:false; response_revision:15692; number_of_response:1; }","duration":"225.347384ms","start":"2026-06-16T23:30:06.565542Z","end":"2026-06-16T23:30:06.790889Z","steps":["trace[2089121717] 'process raft request' (duration: 225.16777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:30:06.791261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.740327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-06-16T23:30:06.791261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.119141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-06-16T23:30:06.791327Z","caller":"traceutil/trace.go:171","msg":"trace[731466626] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15692; }","duration":"108.850409ms","start":"2026-06-16T23:30:06.682459Z","end":"2026-06-16T23:30:06.791309Z","steps":["trace[731466626] 'agreement among raft nodes before linearized reading' (duration: 108.665025ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:30:06.791357Z","caller":"traceutil/trace.go:171","msg":"trace[1702674654] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15692; }","duration":"110.264203ms","start":"2026-06-16T23:30:06.681078Z","end":"2026-06-16T23:30:06.791342Z","steps":["trace[1702674654] 'agreement among raft nodes before linearized reading' (duration: 109.938895ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:30:17.256405Z","caller":"traceutil/trace.go:171","msg":"trace[1726886800] transaction","detail":"{read_only:false; response_revision:15757; number_of_response:1; }","duration":"100.19424ms","start":"2026-06-16T23:30:17.156186Z","end":"2026-06-16T23:30:17.25638Z","steps":["trace[1726886800] 'process raft request' (duration: 99.633516ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:30:37.656269Z","caller":"traceutil/trace.go:171","msg":"trace[1267085091] linearizableReadLoop","detail":"{readStateIndex:16508; appliedIndex:16507; }","duration":"271.467712ms","start":"2026-06-16T23:30:37.384774Z","end":"2026-06-16T23:30:37.656242Z","steps":["trace[1267085091] 'read index received' (duration: 256.518401ms)","trace[1267085091] 'applied index is now lower than readState.Index' (duration: 14.948261ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T23:30:37.65642Z","caller":"traceutil/trace.go:171","msg":"trace[1759837759] transaction","detail":"{read_only:false; response_revision:15960; number_of_response:1; }","duration":"272.024555ms","start":"2026-06-16T23:30:37.384384Z","end":"2026-06-16T23:30:37.656408Z","steps":["trace[1759837759] 'process raft request' (duration: 256.882379ms)","trace[1759837759] 'compare' (duration: 14.810589ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T23:30:37.656547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.015126ms","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-16T23:30:37.656636Z","caller":"traceutil/trace.go:171","msg":"trace[852304529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15960; }","duration":"265.107648ms","start":"2026-06-16T23:30:37.391508Z","end":"2026-06-16T23:30:37.656616Z","steps":["trace[852304529] 'agreement among raft nodes before linearized reading' (duration: 264.926424ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T23:30:37.656849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.992494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T23:30:37.656845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.052606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-scheduler-7c4df95f57-8xzpm\" ","response":"range_response_count:1 size:7941"} {"level":"info","ts":"2026-06-16T23:30:37.656888Z","caller":"traceutil/trace.go:171","msg":"trace[969518882] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:15960; }","duration":"253.045866ms","start":"2026-06-16T23:30:37.403833Z","end":"2026-06-16T23:30:37.656878Z","steps":["trace[969518882] 'agreement among raft nodes before linearized reading' (duration: 252.967024ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:30:37.6569Z","caller":"traceutil/trace.go:171","msg":"trace[843954160] range","detail":"{range_begin:/registry/pods/openstack/nova-scheduler-7c4df95f57-8xzpm; range_end:; response_count:1; response_revision:15960; }","duration":"272.128089ms","start":"2026-06-16T23:30:37.384758Z","end":"2026-06-16T23:30:37.656886Z","steps":["trace[843954160] 'agreement among raft nodes before linearized reading' (duration: 271.963565ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T23:34:07.643487Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15326} {"level":"info","ts":"2026-06-16T23:34:07.740963Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15326,"took":"94.14173ms","hash":2689426869,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":31547392,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-16T23:34:07.741018Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2689426869,"revision":15326,"compact-revision":12864} {"level":"info","ts":"2026-06-16T23:39:07.650735Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17564} {"level":"info","ts":"2026-06-16T23:39:07.74491Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17564,"took":"90.820577ms","hash":1960877683,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":26525696,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-16T23:39:07.744982Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1960877683,"revision":17564,"compact-revision":15326} {"level":"info","ts":"2026-06-16T23:39:44.923981Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-16T23:39:44.929519Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-16T23:39:44.929622Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}