{"level":"warn","ts":"2026-04-20T14:18:54.372512Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T14:18:54.372596Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.119:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.119:2380","--initial-cluster=instance=https://199.19.213.119:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.119:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.119: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-04-20T14:18:54.372732Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T14:18:54.372756Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.119:2380"]} {"level":"info","ts":"2026-04-20T14:18:54.372785Z","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-04-20T14:18:54.373413Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"]} {"level":"info","ts":"2026-04-20T14:18:54.37351Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.119:2380"],"listen-peer-urls":["https://199.19.213.119:2380"],"advertise-client-urls":["https://199.19.213.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.119: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-04-20T14:18:54.566788Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"192.948472ms"} {"level":"info","ts":"2026-04-20T14:18:54.574205Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ec9077c81c499df2","cluster-id":"7e82d3f5f740d3fb"} {"level":"info","ts":"2026-04-20T14:18:54.574318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T14:18:54.574346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became follower at term 0"} {"level":"info","ts":"2026-04-20T14:18:54.574361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ec9077c81c499df2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T14:18:54.57437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became follower at term 1"} {"level":"info","ts":"2026-04-20T14:18:54.574417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=(17046256290949078514)"} {"level":"warn","ts":"2026-04-20T14:18:54.584881Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T14:18:54.586426Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T14:18:54.588619Z","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-04-20T14:18:54.591408Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ec9077c81c499df2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T14:18:54.591671Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ec9077c81c499df2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T14:18:54.591958Z","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-04-20T14:18:54.592068Z","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-04-20T14:18:54.5921Z","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-04-20T14:18:54.593534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=(17046256290949078514)"} {"level":"info","ts":"2026-04-20T14:18:54.594246Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7e82d3f5f740d3fb","local-member-id":"ec9077c81c499df2","added-peer-id":"ec9077c81c499df2","added-peer-peer-urls":["https://199.19.213.119:2380"]} {"level":"info","ts":"2026-04-20T14:18:54.598303Z","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-04-20T14:18:54.598514Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.119:2380"} {"level":"info","ts":"2026-04-20T14:18:54.598593Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T14:18:54.5986Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.119:2380"} {"level":"info","ts":"2026-04-20T14:18:54.598536Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ec9077c81c499df2","initial-advertise-peer-urls":["https://199.19.213.119:2380"],"listen-peer-urls":["https://199.19.213.119:2380"],"advertise-client-urls":["https://199.19.213.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T14:18:55.275438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T14:18:55.275511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T14:18:55.275534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 received MsgPreVoteResp from ec9077c81c499df2 at term 1"} {"level":"info","ts":"2026-04-20T14:18:55.275553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became candidate at term 2"} {"level":"info","ts":"2026-04-20T14:18:55.275564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 received MsgVoteResp from ec9077c81c499df2 at term 2"} {"level":"info","ts":"2026-04-20T14:18:55.275585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became leader at term 2"} {"level":"info","ts":"2026-04-20T14:18:55.275603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ec9077c81c499df2 elected leader ec9077c81c499df2 at term 2"} {"level":"info","ts":"2026-04-20T14:18:55.277259Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ec9077c81c499df2","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.119:2379]}","request-path":"/0/members/ec9077c81c499df2/attributes","cluster-id":"7e82d3f5f740d3fb","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T14:18:55.277289Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:18:55.277556Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:18:55.277777Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:18:55.278268Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T14:18:55.278317Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T14:18:55.279802Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.119:2379"} {"level":"info","ts":"2026-04-20T14:18:55.280901Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T14:18:55.281138Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7e82d3f5f740d3fb","local-member-id":"ec9077c81c499df2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:18:55.281226Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:18:55.281254Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-20T14:18:59.742118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.445561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:18:59.742357Z","caller":"traceutil/trace.go:171","msg":"trace[503033712] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"1.132989766s","start":"2026-04-20T14:18:58.609334Z","end":"2026-04-20T14:18:59.742324Z","steps":["trace[503033712] 'process raft request' (duration: 281.130187ms)","trace[503033712] 'compare' (duration: 850.290751ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:18:59.742509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:58.609323Z","time spent":"1.133108547s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:18:59.742502Z","caller":"traceutil/trace.go:171","msg":"trace[601940470] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:210; }","duration":"645.345844ms","start":"2026-04-20T14:18:59.097063Z","end":"2026-04-20T14:18:59.742409Z","steps":["trace[601940470] 'read index received' (duration: 49.61µs)","trace[601940470] 'applied index is now lower than readState.Index' (duration: 645.294784ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:18:59.742963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.152735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:18:59.74303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:59.22478Z","time spent":"518.248535ms","remote":"127.0.0.1:48878","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-20T14:18:59.743115Z","caller":"traceutil/trace.go:171","msg":"trace[1304124011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:205; }","duration":"496.322806ms","start":"2026-04-20T14:18:59.246737Z","end":"2026-04-20T14:18:59.74306Z","steps":["trace[1304124011] 'agreement among raft nodes before linearized reading' (duration: 496.180905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:59.743215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:59.24672Z","time spent":"496.476017ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:18:59.742985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.921917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3492"} {"level":"info","ts":"2026-04-20T14:18:59.743437Z","caller":"traceutil/trace.go:171","msg":"trace[1033263158] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:205; }","duration":"646.40197ms","start":"2026-04-20T14:18:59.097012Z","end":"2026-04-20T14:18:59.743414Z","steps":["trace[1033263158] 'agreement among raft nodes before linearized reading' (duration: 645.516164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:59.743506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:59.096992Z","time spent":"646.502132ms","remote":"127.0.0.1:48988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3515,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-20T14:19:00.008572Z","caller":"traceutil/trace.go:171","msg":"trace[1179788680] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"264.606599ms","start":"2026-04-20T14:18:59.743942Z","end":"2026-04-20T14:19:00.008548Z","steps":["trace[1179788680] 'process raft request' (duration: 264.460458ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:00.05129Z","caller":"traceutil/trace.go:171","msg":"trace[1472938259] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"302.044457ms","start":"2026-04-20T14:18:59.749225Z","end":"2026-04-20T14:19:00.05127Z","steps":["trace[1472938259] 'process raft request' (duration: 301.997926ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:00.051358Z","caller":"traceutil/trace.go:171","msg":"trace[1320512613] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"303.707657ms","start":"2026-04-20T14:18:59.747611Z","end":"2026-04-20T14:19:00.051319Z","steps":["trace[1320512613] 'process raft request' (duration: 303.495565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:00.051544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:59.747595Z","time spent":"303.873799ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:19:00.05142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:59.749215Z","time spent":"302.137957ms","remote":"127.0.0.1:48988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:19:00.688453Z","caller":"traceutil/trace.go:171","msg":"trace[778783220] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:260; }","duration":"159.5776ms","start":"2026-04-20T14:19:00.528853Z","end":"2026-04-20T14:19:00.68843Z","steps":["trace[778783220] 'read index received' (duration: 159.314099ms)","trace[778783220] 'applied index is now lower than readState.Index' (duration: 262.381µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:00.688505Z","caller":"traceutil/trace.go:171","msg":"trace[1277786827] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"175.674744ms","start":"2026-04-20T14:19:00.512797Z","end":"2026-04-20T14:19:00.688472Z","steps":["trace[1277786827] 'process raft request' (duration: 175.472243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:00.688683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.855907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-04-20T14:19:00.688746Z","caller":"traceutil/trace.go:171","msg":"trace[1855845524] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:254; }","duration":"100.968319ms","start":"2026-04-20T14:19:00.58776Z","end":"2026-04-20T14:19:00.688728Z","steps":["trace[1855845524] 'agreement among raft nodes before linearized reading' (duration: 100.836368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:00.688741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.876382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:19:00.688966Z","caller":"traceutil/trace.go:171","msg":"trace[924617193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:254; }","duration":"160.137783ms","start":"2026-04-20T14:19:00.528811Z","end":"2026-04-20T14:19:00.688949Z","steps":["trace[924617193] 'agreement among raft nodes before linearized reading' (duration: 159.737131ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:25.451754Z","caller":"traceutil/trace.go:171","msg":"trace[1875560093] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"149.327613ms","start":"2026-04-20T14:19:25.302382Z","end":"2026-04-20T14:19:25.45171Z","steps":["trace[1875560093] 'process raft request' (duration: 149.054322ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:25.577349Z","caller":"traceutil/trace.go:171","msg":"trace[1280317091] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"261.967507ms","start":"2026-04-20T14:19:25.315342Z","end":"2026-04-20T14:19:25.57731Z","steps":["trace[1280317091] 'process raft request' (duration: 261.734666ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:25.577552Z","caller":"traceutil/trace.go:171","msg":"trace[872284798] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"255.589307ms","start":"2026-04-20T14:19:25.32195Z","end":"2026-04-20T14:19:25.577539Z","steps":["trace[872284798] 'process raft request' (duration: 255.387426ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:25.57758Z","caller":"traceutil/trace.go:171","msg":"trace[1688905623] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:525; }","duration":"231.705757ms","start":"2026-04-20T14:19:25.345842Z","end":"2026-04-20T14:19:25.577548Z","steps":["trace[1688905623] 'read index received' (duration: 107.059647ms)","trace[1688905623] 'applied index is now lower than readState.Index' (duration: 124.6454ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:25.577635Z","caller":"traceutil/trace.go:171","msg":"trace[1740147873] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"248.210243ms","start":"2026-04-20T14:19:25.329385Z","end":"2026-04-20T14:19:25.577595Z","steps":["trace[1740147873] 'process raft request' (duration: 248.061632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:25.57772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.920807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:19:25.577742Z","caller":"traceutil/trace.go:171","msg":"trace[589158068] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"261.925427ms","start":"2026-04-20T14:19:25.315796Z","end":"2026-04-20T14:19:25.577722Z","steps":["trace[589158068] 'process raft request' (duration: 261.402704ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:25.577783Z","caller":"traceutil/trace.go:171","msg":"trace[666678203] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"247.699781ms","start":"2026-04-20T14:19:25.330077Z","end":"2026-04-20T14:19:25.577777Z","steps":["trace[666678203] 'process raft request' (duration: 247.39875ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:25.577842Z","caller":"traceutil/trace.go:171","msg":"trace[1004980429] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"231.969417ms","start":"2026-04-20T14:19:25.345868Z","end":"2026-04-20T14:19:25.577837Z","steps":["trace[1004980429] 'process raft request' (duration: 231.641896ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:25.577857Z","caller":"traceutil/trace.go:171","msg":"trace[422910581] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"260.180928ms","start":"2026-04-20T14:19:25.317663Z","end":"2026-04-20T14:19:25.577844Z","steps":["trace[422910581] 'process raft request' (duration: 259.580446ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:25.577877Z","caller":"traceutil/trace.go:171","msg":"trace[1532894604] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"258.959703ms","start":"2026-04-20T14:19:25.3189Z","end":"2026-04-20T14:19:25.57786Z","steps":["trace[1532894604] 'process raft request' (duration: 258.376141ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:25.577902Z","caller":"traceutil/trace.go:171","msg":"trace[234475153] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"250.353962ms","start":"2026-04-20T14:19:25.327542Z","end":"2026-04-20T14:19:25.577896Z","steps":["trace[234475153] 'process raft request' (duration: 249.86816ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:25.577756Z","caller":"traceutil/trace.go:171","msg":"trace[524955385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"232.015598ms","start":"2026-04-20T14:19:25.34573Z","end":"2026-04-20T14:19:25.577746Z","steps":["trace[524955385] 'agreement among raft nodes before linearized reading' (duration: 231.901687ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:25.577945Z","caller":"traceutil/trace.go:171","msg":"trace[1321155441] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"254.144051ms","start":"2026-04-20T14:19:25.323796Z","end":"2026-04-20T14:19:25.577941Z","steps":["trace[1321155441] 'process raft request' (duration: 253.579938ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:25.589842Z","caller":"traceutil/trace.go:171","msg":"trace[372789954] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"131.702082ms","start":"2026-04-20T14:19:25.458121Z","end":"2026-04-20T14:19:25.589823Z","steps":["trace[372789954] 'process raft request' (duration: 131.43931ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:25.590065Z","caller":"traceutil/trace.go:171","msg":"trace[713613253] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"130.926759ms","start":"2026-04-20T14:19:25.459121Z","end":"2026-04-20T14:19:25.590048Z","steps":["trace[713613253] 'process raft request' (duration: 130.515107ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:25.590133Z","caller":"traceutil/trace.go:171","msg":"trace[137228706] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"111.065725ms","start":"2026-04-20T14:19:25.479043Z","end":"2026-04-20T14:19:25.590109Z","steps":["trace[137228706] 'process raft request' (duration: 110.769664ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:25.590248Z","caller":"traceutil/trace.go:171","msg":"trace[1010437776] transaction","detail":"{read_only:false; number_of_response:1; response_revision:519; }","duration":"130.660396ms","start":"2026-04-20T14:19:25.459567Z","end":"2026-04-20T14:19:25.590227Z","steps":["trace[1010437776] 'process raft request' (duration: 130.136314ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:26.598789Z","caller":"traceutil/trace.go:171","msg":"trace[1428045515] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"135.680546ms","start":"2026-04-20T14:19:26.463045Z","end":"2026-04-20T14:19:26.598726Z","steps":["trace[1428045515] 'process raft request' (duration: 135.149434ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:26.770412Z","caller":"traceutil/trace.go:171","msg":"trace[2125301079] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"112.921451ms","start":"2026-04-20T14:19:26.657465Z","end":"2026-04-20T14:19:26.770387Z","steps":["trace[2125301079] 'read index received' (duration: 70.515575ms)","trace[2125301079] 'applied index is now lower than readState.Index' (duration: 42.405016ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:19:26.770594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.108072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-20T14:19:26.77062Z","caller":"traceutil/trace.go:171","msg":"trace[639850743] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:579; }","duration":"113.172592ms","start":"2026-04-20T14:19:26.657438Z","end":"2026-04-20T14:19:26.770611Z","steps":["trace[639850743] 'agreement among raft nodes before linearized reading' (duration: 113.036042ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:26.770726Z","caller":"traceutil/trace.go:171","msg":"trace[1662061708] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"125.048217ms","start":"2026-04-20T14:19:26.645669Z","end":"2026-04-20T14:19:26.770717Z","steps":["trace[1662061708] 'process raft request' (duration: 82.396519ms)","trace[1662061708] 'compare' (duration: 42.216676ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:32.014217Z","caller":"traceutil/trace.go:171","msg":"trace[435185935] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"225.99857ms","start":"2026-04-20T14:19:31.788146Z","end":"2026-04-20T14:19:32.014144Z","steps":["trace[435185935] 'process raft request' (duration: 225.334928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:32.750192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.112431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:19:32.750394Z","caller":"traceutil/trace.go:171","msg":"trace[1262711835] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"502.880067ms","start":"2026-04-20T14:19:32.247499Z","end":"2026-04-20T14:19:32.750379Z","steps":["trace[1262711835] 'read index received' (duration: 77.584074ms)","trace[1262711835] 'applied index is now lower than readState.Index' (duration: 425.295213ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:32.750473Z","caller":"traceutil/trace.go:171","msg":"trace[1114355396] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"665.31724ms","start":"2026-04-20T14:19:32.085146Z","end":"2026-04-20T14:19:32.750463Z","steps":["trace[1114355396] 'process raft request' (duration: 156.867998ms)","trace[1114355396] 'compare' (duration: 507.793019ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:19:32.750526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:32.085128Z","time spent":"665.36905ms","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:19:32.750578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.501341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:19:32.750605Z","caller":"traceutil/trace.go:171","msg":"trace[2019296618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"402.559531ms","start":"2026-04-20T14:19:32.348035Z","end":"2026-04-20T14:19:32.750595Z","steps":["trace[2019296618] 'agreement among raft nodes before linearized reading' (duration: 402.46057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:32.750576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.061658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-20T14:19:32.750604Z","caller":"traceutil/trace.go:171","msg":"trace[956431890] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"603.909207ms","start":"2026-04-20T14:19:32.146687Z","end":"2026-04-20T14:19:32.750596Z","steps":["trace[956431890] 'process raft request' (duration: 603.610655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:32.750659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.046726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9h7j4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-20T14:19:32.750683Z","caller":"traceutil/trace.go:171","msg":"trace[1401797754] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9h7j4; range_end:; response_count:1; response_revision:616; }","duration":"397.101816ms","start":"2026-04-20T14:19:32.353575Z","end":"2026-04-20T14:19:32.750677Z","steps":["trace[1401797754] 'agreement among raft nodes before linearized reading' (duration: 397.058586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:32.75067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:32.146665Z","time spent":"603.973177ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:19:32.750652Z","caller":"traceutil/trace.go:171","msg":"trace[175984139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"503.140078ms","start":"2026-04-20T14:19:32.247494Z","end":"2026-04-20T14:19:32.750634Z","steps":["trace[175984139] 'agreement among raft nodes before linearized reading' (duration: 502.984047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:32.7507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:32.353557Z","time spent":"397.139886ms","remote":"127.0.0.1:48998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-9h7j4\" "} {"level":"warn","ts":"2026-04-20T14:19:32.750722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:32.247453Z","time spent":"503.261968ms","remote":"127.0.0.1:49062","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-04-20T14:19:32.750624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:32.348018Z","time spent":"402.602491ms","remote":"127.0.0.1:48790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T14:19:33.024438Z","caller":"traceutil/trace.go:171","msg":"trace[249968320] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"128.837863ms","start":"2026-04-20T14:19:32.895569Z","end":"2026-04-20T14:19:33.024407Z","steps":["trace[249968320] 'read index received' (duration: 128.590662ms)","trace[249968320] 'applied index is now lower than readState.Index' (duration: 246.241µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:33.024482Z","caller":"traceutil/trace.go:171","msg":"trace[1436937339] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"259.890376ms","start":"2026-04-20T14:19:32.764562Z","end":"2026-04-20T14:19:33.024453Z","steps":["trace[1436937339] 'process raft request' (duration: 259.664035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:33.024638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.045794ms","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-04-20T14:19:33.024683Z","caller":"traceutil/trace.go:171","msg":"trace[1547126987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"129.134234ms","start":"2026-04-20T14:19:32.895537Z","end":"2026-04-20T14:19:33.024671Z","steps":["trace[1547126987] 'agreement among raft nodes before linearized reading' (duration: 128.977463ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:15.63894Z","caller":"traceutil/trace.go:171","msg":"trace[1683296080] linearizableReadLoop","detail":"{readStateIndex:1209; appliedIndex:1208; }","duration":"282.886261ms","start":"2026-04-20T14:20:15.356019Z","end":"2026-04-20T14:20:15.638905Z","steps":["trace[1683296080] 'read index received' (duration: 282.649992ms)","trace[1683296080] 'applied index is now lower than readState.Index' (duration: 235.289µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:15.639135Z","caller":"traceutil/trace.go:171","msg":"trace[1128442685] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"284.49347ms","start":"2026-04-20T14:20:15.354565Z","end":"2026-04-20T14:20:15.639059Z","steps":["trace[1128442685] 'process raft request' (duration: 284.173509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:15.63961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.38782ms","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-04-20T14:20:15.639678Z","caller":"traceutil/trace.go:171","msg":"trace[1264146754] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1174; }","duration":"283.687121ms","start":"2026-04-20T14:20:15.355973Z","end":"2026-04-20T14:20:15.63966Z","steps":["trace[1264146754] 'agreement among raft nodes before linearized reading' (duration: 283.227974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:15.641401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.859167ms","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-04-20T14:20:15.641442Z","caller":"traceutil/trace.go:171","msg":"trace[1572660611] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1175; }","duration":"263.92715ms","start":"2026-04-20T14:20:15.377505Z","end":"2026-04-20T14:20:15.641433Z","steps":["trace[1572660611] 'agreement among raft nodes before linearized reading' (duration: 263.806145ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:39.880924Z","caller":"traceutil/trace.go:171","msg":"trace[1360674072] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"134.532801ms","start":"2026-04-20T14:20:39.746347Z","end":"2026-04-20T14:20:39.880879Z","steps":["trace[1360674072] 'process raft request' (duration: 134.012905ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:04.753122Z","caller":"traceutil/trace.go:171","msg":"trace[1192067456] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"170.820941ms","start":"2026-04-20T14:21:04.582278Z","end":"2026-04-20T14:21:04.753099Z","steps":["trace[1192067456] 'read index received' (duration: 170.651998ms)","trace[1192067456] 'applied index is now lower than readState.Index' (duration: 168.103µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:04.75332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.034656ms","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-04-20T14:21:04.753362Z","caller":"traceutil/trace.go:171","msg":"trace[1914844681] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1861; }","duration":"171.125248ms","start":"2026-04-20T14:21:04.582225Z","end":"2026-04-20T14:21:04.75335Z","steps":["trace[1914844681] 'agreement among raft nodes before linearized reading' (duration: 170.968334ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:04.753455Z","caller":"traceutil/trace.go:171","msg":"trace[671351462] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"213.208482ms","start":"2026-04-20T14:21:04.540231Z","end":"2026-04-20T14:21:04.75344Z","steps":["trace[671351462] 'process raft request' (duration: 212.739341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:05.184835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.555118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:21:05.185051Z","caller":"traceutil/trace.go:171","msg":"trace[1458828639] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"425.548188ms","start":"2026-04-20T14:21:04.759468Z","end":"2026-04-20T14:21:05.185016Z","steps":["trace[1458828639] 'process raft request' (duration: 140.733095ms)","trace[1458828639] 'compare' (duration: 284.289371ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:05.18512Z","caller":"traceutil/trace.go:171","msg":"trace[1762563947] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1932; }","duration":"360.030152ms","start":"2026-04-20T14:21:04.825061Z","end":"2026-04-20T14:21:05.185091Z","steps":["trace[1762563947] 'read index received' (duration: 75.063086ms)","trace[1762563947] 'applied index is now lower than readState.Index' (duration: 284.964546ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:05.185169Z","caller":"traceutil/trace.go:171","msg":"trace[1605299466] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"134.163841ms","start":"2026-04-20T14:21:05.05099Z","end":"2026-04-20T14:21:05.185153Z","steps":["trace[1605299466] 'process raft request' (duration: 133.944266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:05.18551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:04.759453Z","time spent":"425.938676ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:21:05.185908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.830851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bd163ec4-0705-44ee-859a-d71b7e278c0f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T14:21:05.18598Z","caller":"traceutil/trace.go:171","msg":"trace[1652696910] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bd163ec4-0705-44ee-859a-d71b7e278c0f; range_end:; response_count:1; response_revision:1864; }","duration":"360.928733ms","start":"2026-04-20T14:21:04.825031Z","end":"2026-04-20T14:21:05.18596Z","steps":["trace[1652696910] 'agreement among raft nodes before linearized reading' (duration: 360.138155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:05.186144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:04.825014Z","time spent":"361.111487ms","remote":"127.0.0.1:48998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bd163ec4-0705-44ee-859a-d71b7e278c0f\" "} {"level":"info","ts":"2026-04-20T14:21:05.59083Z","caller":"traceutil/trace.go:171","msg":"trace[2114793450] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1935; }","duration":"405.246711ms","start":"2026-04-20T14:21:05.185542Z","end":"2026-04-20T14:21:05.590788Z","steps":["trace[2114793450] 'read index received' (duration: 405.075978ms)","trace[2114793450] 'applied index is now lower than readState.Index' (duration: 168.943µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:05.59087Z","caller":"traceutil/trace.go:171","msg":"trace[1861376289] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"461.615721ms","start":"2026-04-20T14:21:05.12921Z","end":"2026-04-20T14:21:05.590825Z","steps":["trace[1861376289] 'process raft request' (duration: 461.372066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:05.591237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.803438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T14:21:05.591309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.895471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a816905507fae5\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-04-20T14:21:05.591323Z","caller":"traceutil/trace.go:171","msg":"trace[1542887261] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1865; }","duration":"493.940802ms","start":"2026-04-20T14:21:05.097358Z","end":"2026-04-20T14:21:05.591299Z","steps":["trace[1542887261] 'agreement among raft nodes before linearized reading' (duration: 493.770198ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:05.591369Z","caller":"traceutil/trace.go:171","msg":"trace[1207143319] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a816905507fae5; range_end:; response_count:1; response_revision:1865; }","duration":"474.064044ms","start":"2026-04-20T14:21:05.117286Z","end":"2026-04-20T14:21:05.59135Z","steps":["trace[1207143319] 'agreement among raft nodes before linearized reading' (duration: 473.655185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:05.591386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:05.097336Z","time spent":"494.033433ms","remote":"127.0.0.1:49114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":4,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:21:05.591418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:05.117271Z","time spent":"474.136486ms","remote":"127.0.0.1:48878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1005,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a816905507fae5\" "} {"level":"warn","ts":"2026-04-20T14:21:05.591266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:05.129189Z","time spent":"461.952778ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:21:05.610572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.437813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:21:05.610616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.756927ms","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-04-20T14:21:05.610687Z","caller":"traceutil/trace.go:171","msg":"trace[1420948132] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1866; }","duration":"313.581225ms","start":"2026-04-20T14:21:05.297075Z","end":"2026-04-20T14:21:05.610656Z","steps":["trace[1420948132] 'agreement among raft nodes before linearized reading' (duration: 313.399752ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:05.610717Z","caller":"traceutil/trace.go:171","msg":"trace[296923423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1866; }","duration":"322.8981ms","start":"2026-04-20T14:21:05.287797Z","end":"2026-04-20T14:21:05.610695Z","steps":["trace[296923423] 'agreement among raft nodes before linearized reading' (duration: 322.649655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:05.610773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:05.287777Z","time spent":"322.985832ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:21:05.610745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.492297ms","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-04-20T14:21:05.610775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.799401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:21:05.610835Z","caller":"traceutil/trace.go:171","msg":"trace[1803392395] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1866; }","duration":"320.61841ms","start":"2026-04-20T14:21:05.290196Z","end":"2026-04-20T14:21:05.610815Z","steps":["trace[1803392395] 'agreement among raft nodes before linearized reading' (duration: 320.327074ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:05.610839Z","caller":"traceutil/trace.go:171","msg":"trace[1701838159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1866; }","duration":"263.893893ms","start":"2026-04-20T14:21:05.34693Z","end":"2026-04-20T14:21:05.610824Z","steps":["trace[1701838159] 'agreement among raft nodes before linearized reading' (duration: 263.77327ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:05.610693Z","caller":"traceutil/trace.go:171","msg":"trace[1372168370] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"416.918108ms","start":"2026-04-20T14:21:05.193745Z","end":"2026-04-20T14:21:05.610663Z","steps":["trace[1372168370] 'process raft request' (duration: 416.543579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:05.610888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:05.290172Z","time spent":"320.706871ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-20T14:21:05.610741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.237096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bd163ec4-0705-44ee-859a-d71b7e278c0f\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-20T14:21:05.610978Z","caller":"traceutil/trace.go:171","msg":"trace[733646982] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bd163ec4-0705-44ee-859a-d71b7e278c0f; range_end:; response_count:1; response_revision:1866; }","duration":"322.593843ms","start":"2026-04-20T14:21:05.288372Z","end":"2026-04-20T14:21:05.610965Z","steps":["trace[733646982] 'agreement among raft nodes before linearized reading' (duration: 322.182774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:05.611025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:05.288361Z","time spent":"322.653395ms","remote":"127.0.0.1:48998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bd163ec4-0705-44ee-859a-d71b7e278c0f\" "} {"level":"warn","ts":"2026-04-20T14:21:05.611038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:05.193725Z","time spent":"417.205254ms","remote":"127.0.0.1:48998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:21:05.611413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:05.297067Z","time spent":"314.312891ms","remote":"127.0.0.1:33464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:21:11.724681Z","caller":"traceutil/trace.go:171","msg":"trace[286868137] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"114.194338ms","start":"2026-04-20T14:21:11.610456Z","end":"2026-04-20T14:21:11.72465Z","steps":["trace[286868137] 'process raft request' (duration: 113.961413ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:17.555303Z","caller":"traceutil/trace.go:171","msg":"trace[1599663812] linearizableReadLoop","detail":"{readStateIndex:2037; appliedIndex:2036; }","duration":"283.140474ms","start":"2026-04-20T14:21:17.272138Z","end":"2026-04-20T14:21:17.555279Z","steps":["trace[1599663812] 'read index received' (duration: 282.980201ms)","trace[1599663812] 'applied index is now lower than readState.Index' (duration: 159.303µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:17.555422Z","caller":"traceutil/trace.go:171","msg":"trace[878537528] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"328.725524ms","start":"2026-04-20T14:21:17.226664Z","end":"2026-04-20T14:21:17.55539Z","steps":["trace[878537528] 'process raft request' (duration: 328.425069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:17.555541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.388389ms","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":"warn","ts":"2026-04-20T14:21:17.555541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.417853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11988"} {"level":"info","ts":"2026-04-20T14:21:17.555579Z","caller":"traceutil/trace.go:171","msg":"trace[259265749] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1964; }","duration":"283.478041ms","start":"2026-04-20T14:21:17.272089Z","end":"2026-04-20T14:21:17.555567Z","steps":["trace[259265749] 'agreement among raft nodes before linearized reading' (duration: 283.322778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:17.555583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:17.226638Z","time spent":"328.849307ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:21:17.555613Z","caller":"traceutil/trace.go:171","msg":"trace[2098926214] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1964; }","duration":"106.497144ms","start":"2026-04-20T14:21:17.449101Z","end":"2026-04-20T14:21:17.555598Z","steps":["trace[2098926214] 'agreement among raft nodes before linearized reading' (duration: 106.317091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:18.000574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.643937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:21:18.000702Z","caller":"traceutil/trace.go:171","msg":"trace[1881352136] linearizableReadLoop","detail":"{readStateIndex:2038; appliedIndex:2037; }","duration":"434.369113ms","start":"2026-04-20T14:21:17.566312Z","end":"2026-04-20T14:21:18.000681Z","steps":["trace[1881352136] 'read index received' (duration: 73.434671ms)","trace[1881352136] 'applied index is now lower than readState.Index' (duration: 360.932692ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:18.000873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.548726ms","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-04-20T14:21:18.000932Z","caller":"traceutil/trace.go:171","msg":"trace[1365218059] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1965; }","duration":"434.639977ms","start":"2026-04-20T14:21:17.566278Z","end":"2026-04-20T14:21:18.000918Z","steps":["trace[1365218059] 'agreement among raft nodes before linearized reading' (duration: 434.543805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:18.000962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:17.566261Z","time spent":"434.692759ms","remote":"127.0.0.1:49254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-04-20T14:21:18.000785Z","caller":"traceutil/trace.go:171","msg":"trace[1880923042] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"440.433892ms","start":"2026-04-20T14:21:17.560336Z","end":"2026-04-20T14:21:18.00077Z","steps":["trace[1880923042] 'process raft request' (duration: 79.499761ms)","trace[1880923042] 'compare' (duration: 360.121526ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:18.00128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:17.560318Z","time spent":"440.898731ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:21:18.017167Z","caller":"traceutil/trace.go:171","msg":"trace[626584057] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"273.585424ms","start":"2026-04-20T14:21:17.743416Z","end":"2026-04-20T14:21:18.017001Z","steps":["trace[626584057] 'process raft request' (duration: 273.482802ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:18.017879Z","caller":"traceutil/trace.go:171","msg":"trace[2010199466] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"273.377388ms","start":"2026-04-20T14:21:17.74447Z","end":"2026-04-20T14:21:18.017847Z","steps":["trace[2010199466] 'process raft request' (duration: 273.238486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:18.018202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.757748ms","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-04-20T14:21:18.018225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.532331ms","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-04-20T14:21:18.018236Z","caller":"traceutil/trace.go:171","msg":"trace[674704569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1968; }","duration":"222.8268ms","start":"2026-04-20T14:21:17.795401Z","end":"2026-04-20T14:21:18.018228Z","steps":["trace[674704569] 'agreement among raft nodes before linearized reading' (duration: 222.709067ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:18.01826Z","caller":"traceutil/trace.go:171","msg":"trace[1744232197] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1968; }","duration":"235.588022ms","start":"2026-04-20T14:21:17.782661Z","end":"2026-04-20T14:21:18.018249Z","steps":["trace[1744232197] 'agreement among raft nodes before linearized reading' (duration: 235.48104ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:18.018093Z","caller":"traceutil/trace.go:171","msg":"trace[125191204] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"129.254812ms","start":"2026-04-20T14:21:17.888825Z","end":"2026-04-20T14:21:18.01808Z","steps":["trace[125191204] 'process raft request' (duration: 128.989897ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:33.844129Z","caller":"traceutil/trace.go:171","msg":"trace[1040669855] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"214.678119ms","start":"2026-04-20T14:21:33.629423Z","end":"2026-04-20T14:21:33.844101Z","steps":["trace[1040669855] 'process raft request' (duration: 214.491096ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:33.888446Z","caller":"traceutil/trace.go:171","msg":"trace[1726967267] linearizableReadLoop","detail":"{readStateIndex:2147; appliedIndex:2145; }","duration":"100.308012ms","start":"2026-04-20T14:21:33.7881Z","end":"2026-04-20T14:21:33.888408Z","steps":["trace[1726967267] 'read index received' (duration: 55.781449ms)","trace[1726967267] 'applied index is now lower than readState.Index' (duration: 44.524793ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:33.888607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.480895ms","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":"info","ts":"2026-04-20T14:21:33.888649Z","caller":"traceutil/trace.go:171","msg":"trace[1345300820] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2070; }","duration":"100.585117ms","start":"2026-04-20T14:21:33.788051Z","end":"2026-04-20T14:21:33.888636Z","steps":["trace[1345300820] 'agreement among raft nodes before linearized reading' (duration: 100.478635ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:40.212253Z","caller":"traceutil/trace.go:171","msg":"trace[2055070462] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"108.774965ms","start":"2026-04-20T14:21:40.103448Z","end":"2026-04-20T14:21:40.212223Z","steps":["trace[2055070462] 'process raft request' (duration: 108.608182ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:40.212466Z","caller":"traceutil/trace.go:171","msg":"trace[13902214] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"106.813602ms","start":"2026-04-20T14:21:40.105615Z","end":"2026-04-20T14:21:40.212429Z","steps":["trace[13902214] 'process raft request' (duration: 106.555138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:40.841408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.210072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:21:40.841486Z","caller":"traceutil/trace.go:171","msg":"trace[742547905] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2112; }","duration":"120.329915ms","start":"2026-04-20T14:21:40.72114Z","end":"2026-04-20T14:21:40.84147Z","steps":["trace[742547905] 'count revisions from in-memory index tree' (duration: 120.118721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:41.340822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.098913ms","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-04-20T14:21:41.340876Z","caller":"traceutil/trace.go:171","msg":"trace[620757201] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2113; }","duration":"121.198485ms","start":"2026-04-20T14:21:41.219664Z","end":"2026-04-20T14:21:41.340862Z","steps":["trace[620757201] 'range keys from in-memory index tree' (duration: 120.913421ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:46.437712Z","caller":"traceutil/trace.go:171","msg":"trace[142423419] transaction","detail":"{read_only:false; response_revision:2561; number_of_response:1; }","duration":"266.796434ms","start":"2026-04-20T14:22:46.170881Z","end":"2026-04-20T14:22:46.437677Z","steps":["trace[142423419] 'process raft request' (duration: 266.544851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:47.300741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.703073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T14:22:47.300705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.157475ms","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-04-20T14:22:47.300857Z","caller":"traceutil/trace.go:171","msg":"trace[1194598436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2564; }","duration":"488.808354ms","start":"2026-04-20T14:22:46.812021Z","end":"2026-04-20T14:22:47.30083Z","steps":["trace[1194598436] 'range keys from in-memory index tree' (duration: 488.524831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:47.30088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.433501ms","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-04-20T14:22:47.300777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.765252ms","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-04-20T14:22:47.301284Z","caller":"traceutil/trace.go:171","msg":"trace[184662308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2564; }","duration":"536.278319ms","start":"2026-04-20T14:22:46.764947Z","end":"2026-04-20T14:22:47.301225Z","steps":["trace[184662308] 'range keys from in-memory index tree' (duration: 535.58772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:47.301338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:46.764927Z","time spent":"536.39594ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:22:47.30092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:46.811994Z","time spent":"488.908625ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T14:22:47.300882Z","caller":"traceutil/trace.go:171","msg":"trace[440175270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2564; }","duration":"541.384128ms","start":"2026-04-20T14:22:46.759473Z","end":"2026-04-20T14:22:47.300857Z","steps":["trace[440175270] 'range keys from in-memory index tree' (duration: 540.711969ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:47.301166Z","caller":"traceutil/trace.go:171","msg":"trace[111929641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2564; }","duration":"483.541092ms","start":"2026-04-20T14:22:46.817412Z","end":"2026-04-20T14:22:47.300953Z","steps":["trace[111929641] 'range keys from in-memory index tree' (duration: 483.276679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:47.301632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:46.759453Z","time spent":"542.126677ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:22:47.30173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:46.817395Z","time spent":"484.312761ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T14:23:07.606267Z","caller":"traceutil/trace.go:171","msg":"trace[173272062] linearizableReadLoop","detail":"{readStateIndex:2779; appliedIndex:2778; }","duration":"113.938046ms","start":"2026-04-20T14:23:07.492301Z","end":"2026-04-20T14:23:07.606239Z","steps":["trace[173272062] 'read index received' (duration: 50.27581ms)","trace[173272062] 'applied index is now lower than readState.Index' (duration: 63.661016ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:23:07.606468Z","caller":"traceutil/trace.go:171","msg":"trace[2054137131] transaction","detail":"{read_only:false; response_revision:2681; number_of_response:1; }","duration":"151.314983ms","start":"2026-04-20T14:23:07.455135Z","end":"2026-04-20T14:23:07.60645Z","steps":["trace[2054137131] 'process raft request' (duration: 87.489025ms)","trace[2054137131] 'compare' (duration: 63.446934ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:23:07.60671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.38912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:23:07.606802Z","caller":"traceutil/trace.go:171","msg":"trace[1481294341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2681; }","duration":"114.518441ms","start":"2026-04-20T14:23:07.492267Z","end":"2026-04-20T14:23:07.606786Z","steps":["trace[1481294341] 'agreement among raft nodes before linearized reading' (duration: 114.329569ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:14.238936Z","caller":"traceutil/trace.go:171","msg":"trace[1109865711] linearizableReadLoop","detail":"{readStateIndex:2811; appliedIndex:2810; }","duration":"360.37925ms","start":"2026-04-20T14:23:13.878535Z","end":"2026-04-20T14:23:14.238914Z","steps":["trace[1109865711] 'read index received' (duration: 360.193938ms)","trace[1109865711] 'applied index is now lower than readState.Index' (duration: 184.112µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:23:14.239185Z","caller":"traceutil/trace.go:171","msg":"trace[1214899070] transaction","detail":"{read_only:false; response_revision:2711; number_of_response:1; }","duration":"565.527607ms","start":"2026-04-20T14:23:13.673642Z","end":"2026-04-20T14:23:14.239169Z","steps":["trace[1214899070] 'process raft request' (duration: 565.118403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:14.239338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:13.673617Z","time spent":"565.640739ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:23:14.239707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.46001ms","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-04-20T14:23:14.239793Z","caller":"traceutil/trace.go:171","msg":"trace[1830822400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2711; }","duration":"359.586271ms","start":"2026-04-20T14:23:13.880185Z","end":"2026-04-20T14:23:14.239772Z","steps":["trace[1830822400] 'agreement among raft nodes before linearized reading' (duration: 359.172207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:14.239841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:13.880173Z","time spent":"359.656292ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T14:23:14.239858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.993703ms","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-04-20T14:23:14.239974Z","caller":"traceutil/trace.go:171","msg":"trace[1811739362] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2711; }","duration":"356.229835ms","start":"2026-04-20T14:23:13.883729Z","end":"2026-04-20T14:23:14.239959Z","steps":["trace[1811739362] 'agreement among raft nodes before linearized reading' (duration: 355.923912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:14.239961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.34746ms","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-04-20T14:23:14.240037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:13.883715Z","time spent":"356.307496ms","remote":"127.0.0.1:49062","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-04-20T14:23:14.2401Z","caller":"traceutil/trace.go:171","msg":"trace[89491960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2711; }","duration":"361.556463ms","start":"2026-04-20T14:23:13.8785Z","end":"2026-04-20T14:23:14.240056Z","steps":["trace[89491960] 'agreement among raft nodes before linearized reading' (duration: 361.173629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:14.240124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.865762ms","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-04-20T14:23:14.2401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.135173ms","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-04-20T14:23:14.240157Z","caller":"traceutil/trace.go:171","msg":"trace[2060368313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2711; }","duration":"356.917932ms","start":"2026-04-20T14:23:13.883229Z","end":"2026-04-20T14:23:14.240147Z","steps":["trace[2060368313] 'agreement among raft nodes before linearized reading' (duration: 356.827481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:14.240185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:13.883218Z","time spent":"356.961302ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:23:14.240179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:13.878484Z","time spent":"361.660414ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T14:23:14.240196Z","caller":"traceutil/trace.go:171","msg":"trace[1813051366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2711; }","duration":"355.250825ms","start":"2026-04-20T14:23:13.884922Z","end":"2026-04-20T14:23:14.240173Z","steps":["trace[1813051366] 'agreement among raft nodes before linearized reading' (duration: 354.73226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:14.240256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:13.884917Z","time spent":"355.325366ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T14:23:14.506103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.754353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:23:14.506232Z","caller":"traceutil/trace.go:171","msg":"trace[697877028] transaction","detail":"{read_only:false; response_revision:2712; number_of_response:1; }","duration":"261.669968ms","start":"2026-04-20T14:23:14.244539Z","end":"2026-04-20T14:23:14.506209Z","steps":["trace[697877028] 'process raft request' (duration: 90.712263ms)","trace[697877028] 'compare' (duration: 170.362429ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:23:14.924377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.067347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:23:14.925006Z","caller":"traceutil/trace.go:171","msg":"trace[2059270012] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"680.027136ms","start":"2026-04-20T14:23:14.244927Z","end":"2026-04-20T14:23:14.924954Z","steps":["trace[2059270012] 'process raft request' (duration: 451.236121ms)","trace[2059270012] 'compare' (duration: 227.482851ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:23:14.92519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:14.244918Z","time spent":"680.174479ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:23:14.925299Z","caller":"traceutil/trace.go:171","msg":"trace[309378733] linearizableReadLoop","detail":"{readStateIndex:2816; appliedIndex:2812; }","duration":"576.585467ms","start":"2026-04-20T14:23:14.348654Z","end":"2026-04-20T14:23:14.92524Z","steps":["trace[309378733] 'read index received' (duration: 347.478228ms)","trace[309378733] 'applied index is now lower than readState.Index' (duration: 229.105909ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:23:14.925778Z","caller":"traceutil/trace.go:171","msg":"trace[453451086] transaction","detail":"{read_only:false; response_revision:2714; number_of_response:1; }","duration":"680.787394ms","start":"2026-04-20T14:23:14.244938Z","end":"2026-04-20T14:23:14.925725Z","steps":["trace[453451086] 'process raft request' (duration: 679.936015ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:14.925865Z","caller":"traceutil/trace.go:171","msg":"trace[257900817] transaction","detail":"{read_only:false; response_revision:2716; number_of_response:1; }","duration":"679.610912ms","start":"2026-04-20T14:23:14.246205Z","end":"2026-04-20T14:23:14.925816Z","steps":["trace[257900817] 'process raft request' (duration: 678.892645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:14.925898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:14.244921Z","time spent":"680.923536ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:23:14.92593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.132122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:23:14.925981Z","caller":"traceutil/trace.go:171","msg":"trace[1289422397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2719; }","duration":"577.189013ms","start":"2026-04-20T14:23:14.348776Z","end":"2026-04-20T14:23:14.925965Z","steps":["trace[1289422397] 'agreement among raft nodes before linearized reading' (duration: 577.063901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:14.926021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:14.348771Z","time spent":"577.242684ms","remote":"127.0.0.1:48796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T14:23:14.926143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:14.246188Z","time spent":"679.763553ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:23:14.926283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.185127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:23:14.926329Z","caller":"traceutil/trace.go:171","msg":"trace[9227223] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2719; }","duration":"432.255707ms","start":"2026-04-20T14:23:14.494059Z","end":"2026-04-20T14:23:14.926315Z","steps":["trace[9227223] 'agreement among raft nodes before linearized reading' (duration: 432.192157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:14.926361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:14.494045Z","time spent":"432.307398ms","remote":"127.0.0.1:33502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:23:14.92639Z","caller":"traceutil/trace.go:171","msg":"trace[449632727] transaction","detail":"{read_only:false; response_revision:2718; number_of_response:1; }","duration":"229.694585ms","start":"2026-04-20T14:23:14.696673Z","end":"2026-04-20T14:23:14.926368Z","steps":["trace[449632727] 'process raft request' (duration: 228.948547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:14.926034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.368525ms","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-04-20T14:23:14.926515Z","caller":"traceutil/trace.go:171","msg":"trace[2145234862] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2719; }","duration":"577.88989ms","start":"2026-04-20T14:23:14.348612Z","end":"2026-04-20T14:23:14.926502Z","steps":["trace[2145234862] 'agreement among raft nodes before linearized reading' (duration: 577.201363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:14.926482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.276204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a816abb0f2c31f\" ","response":"range_response_count:1 size:779"} {"level":"warn","ts":"2026-04-20T14:23:14.926547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:14.348597Z","time spent":"577.939901ms","remote":"127.0.0.1:49000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-04-20T14:23:14.926571Z","caller":"traceutil/trace.go:171","msg":"trace[1468061384] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a816abb0f2c31f; range_end:; response_count:1; response_revision:2719; }","duration":"262.411885ms","start":"2026-04-20T14:23:14.664145Z","end":"2026-04-20T14:23:14.926557Z","steps":["trace[1468061384] 'agreement among raft nodes before linearized reading' (duration: 262.083831ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:14.926613Z","caller":"traceutil/trace.go:171","msg":"trace[861426656] transaction","detail":"{read_only:false; response_revision:2719; number_of_response:1; }","duration":"228.900315ms","start":"2026-04-20T14:23:14.697701Z","end":"2026-04-20T14:23:14.926601Z","steps":["trace[861426656] 'process raft request' (duration: 228.086237ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:14.926618Z","caller":"traceutil/trace.go:171","msg":"trace[1701304783] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"680.717664ms","start":"2026-04-20T14:23:14.245056Z","end":"2026-04-20T14:23:14.925773Z","steps":["trace[1701304783] 'process raft request' (duration: 679.963686ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:14.925773Z","caller":"traceutil/trace.go:171","msg":"trace[68818489] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"236.056032ms","start":"2026-04-20T14:23:14.689683Z","end":"2026-04-20T14:23:14.925739Z","steps":["trace[68818489] 'process raft request' (duration: 235.491006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:14.926731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:14.245029Z","time spent":"681.641443ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:23:15.142642Z","caller":"traceutil/trace.go:171","msg":"trace[94573758] linearizableReadLoop","detail":"{readStateIndex:2820; appliedIndex:2819; }","duration":"210.799358ms","start":"2026-04-20T14:23:14.931826Z","end":"2026-04-20T14:23:15.142626Z","steps":["trace[94573758] 'read index received' (duration: 203.47489ms)","trace[94573758] 'applied index is now lower than readState.Index' (duration: 7.323528ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:23:15.142751Z","caller":"traceutil/trace.go:171","msg":"trace[525291729] transaction","detail":"{read_only:false; response_revision:2720; number_of_response:1; }","duration":"212.658618ms","start":"2026-04-20T14:23:14.930037Z","end":"2026-04-20T14:23:15.142695Z","steps":["trace[525291729] 'process raft request' (duration: 205.243179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:15.142797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.95751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-04-20T14:23:15.142822Z","caller":"traceutil/trace.go:171","msg":"trace[1176739605] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2720; }","duration":"211.01588ms","start":"2026-04-20T14:23:14.931798Z","end":"2026-04-20T14:23:15.142814Z","steps":["trace[1176739605] 'agreement among raft nodes before linearized reading' (duration: 210.889999ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:21.459859Z","caller":"traceutil/trace.go:171","msg":"trace[2128607752] linearizableReadLoop","detail":"{readStateIndex:2848; appliedIndex:2845; }","duration":"506.478965ms","start":"2026-04-20T14:23:20.953357Z","end":"2026-04-20T14:23:21.459836Z","steps":["trace[2128607752] 'read index received' (duration: 68.16053ms)","trace[2128607752] 'applied index is now lower than readState.Index' (duration: 438.317025ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:23:21.45989Z","caller":"traceutil/trace.go:171","msg":"trace[1646995885] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"509.606949ms","start":"2026-04-20T14:23:20.950265Z","end":"2026-04-20T14:23:21.459872Z","steps":["trace[1646995885] 'process raft request' (duration: 509.393426ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:21.459972Z","caller":"traceutil/trace.go:171","msg":"trace[924297611] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"509.588738ms","start":"2026-04-20T14:23:20.950328Z","end":"2026-04-20T14:23:21.459917Z","steps":["trace[924297611] 'process raft request' (duration: 509.469547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:21.460044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:20.950254Z","time spent":"509.706359ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:23:21.460115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.753308ms","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-04-20T14:23:21.460175Z","caller":"traceutil/trace.go:171","msg":"trace[804875518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2747; }","duration":"506.85193ms","start":"2026-04-20T14:23:20.953314Z","end":"2026-04-20T14:23:21.460166Z","steps":["trace[804875518] 'agreement among raft nodes before linearized reading' (duration: 506.610196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:21.460224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:20.953291Z","time spent":"506.908691ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:23:21.460245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:20.950313Z","time spent":"509.77858ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:23:21.460381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.199514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T14:23:21.460425Z","caller":"traceutil/trace.go:171","msg":"trace[881751507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2747; }","duration":"506.274874ms","start":"2026-04-20T14:23:20.95414Z","end":"2026-04-20T14:23:21.460415Z","steps":["trace[881751507] 'agreement among raft nodes before linearized reading' (duration: 506.170743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:21.460467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:20.954127Z","time spent":"506.322904ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T14:23:21.460594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.744499ms","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-04-20T14:23:21.46049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.549772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:23:21.460611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.014211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T14:23:21.460625Z","caller":"traceutil/trace.go:171","msg":"trace[1729128420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2747; }","duration":"505.789029ms","start":"2026-04-20T14:23:20.954827Z","end":"2026-04-20T14:23:21.460616Z","steps":["trace[1729128420] 'agreement among raft nodes before linearized reading' (duration: 505.708118ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:21.460642Z","caller":"traceutil/trace.go:171","msg":"trace[386552570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2747; }","duration":"506.078362ms","start":"2026-04-20T14:23:20.954554Z","end":"2026-04-20T14:23:21.460632Z","steps":["trace[386552570] 'agreement among raft nodes before linearized reading' (duration: 505.999292ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:21.460657Z","caller":"traceutil/trace.go:171","msg":"trace[614911422] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2747; }","duration":"155.843655ms","start":"2026-04-20T14:23:21.304779Z","end":"2026-04-20T14:23:21.460623Z","steps":["trace[614911422] 'agreement among raft nodes before linearized reading' (duration: 155.573432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:21.460653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:20.954821Z","time spent":"505.822059ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:23:21.460673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:20.954545Z","time spent":"506.121083ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T14:23:21.460747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.092974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-20T14:23:21.460809Z","caller":"traceutil/trace.go:171","msg":"trace[1863612663] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2747; }","duration":"113.197865ms","start":"2026-04-20T14:23:21.347594Z","end":"2026-04-20T14:23:21.460791Z","steps":["trace[1863612663] 'agreement among raft nodes before linearized reading' (duration: 112.930142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:18.454235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.679212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:25:18.454341Z","caller":"traceutil/trace.go:171","msg":"trace[1503599128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3357; }","duration":"108.819227ms","start":"2026-04-20T14:25:18.3455Z","end":"2026-04-20T14:25:18.45432Z","steps":["trace[1503599128] 'agreement among raft nodes before linearized reading' (duration: 51.754498ms)","trace[1503599128] 'range keys from in-memory index tree' (duration: 56.928815ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:25:18.454428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.79389ms","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-04-20T14:25:18.454545Z","caller":"traceutil/trace.go:171","msg":"trace[569784432] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3357; }","duration":"106.947795ms","start":"2026-04-20T14:25:18.347568Z","end":"2026-04-20T14:25:18.454516Z","steps":["trace[569784432] 'agreement among raft nodes before linearized reading' (duration: 49.668741ms)","trace[569784432] 'range keys from in-memory index tree' (duration: 57.014697ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:25:20.597851Z","caller":"traceutil/trace.go:171","msg":"trace[1251094238] transaction","detail":"{read_only:false; response_revision:3371; number_of_response:1; }","duration":"135.255724ms","start":"2026-04-20T14:25:20.462572Z","end":"2026-04-20T14:25:20.597828Z","steps":["trace[1251094238] 'process raft request' (duration: 119.428755ms)","trace[1251094238] 'compare' (duration: 15.717466ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:25:20.597977Z","caller":"traceutil/trace.go:171","msg":"trace[1676138883] transaction","detail":"{read_only:false; response_revision:3374; number_of_response:1; }","duration":"135.052958ms","start":"2026-04-20T14:25:20.462905Z","end":"2026-04-20T14:25:20.597958Z","steps":["trace[1676138883] 'process raft request' (duration: 134.951715ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:20.597973Z","caller":"traceutil/trace.go:171","msg":"trace[1724570300] transaction","detail":"{read_only:false; response_revision:3375; number_of_response:1; }","duration":"134.959095ms","start":"2026-04-20T14:25:20.463005Z","end":"2026-04-20T14:25:20.597964Z","steps":["trace[1724570300] 'process raft request' (duration: 134.876043ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:20.59813Z","caller":"traceutil/trace.go:171","msg":"trace[941731184] transaction","detail":"{read_only:false; response_revision:3372; number_of_response:1; }","duration":"135.324515ms","start":"2026-04-20T14:25:20.462763Z","end":"2026-04-20T14:25:20.598087Z","steps":["trace[941731184] 'process raft request' (duration: 135.023637ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:20.598238Z","caller":"traceutil/trace.go:171","msg":"trace[1450450399] transaction","detail":"{read_only:false; response_revision:3373; number_of_response:1; }","duration":"135.420878ms","start":"2026-04-20T14:25:20.462805Z","end":"2026-04-20T14:25:20.598226Z","steps":["trace[1450450399] 'process raft request' (duration: 135.029997ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:20.598348Z","caller":"traceutil/trace.go:171","msg":"trace[1391600064] transaction","detail":"{read_only:false; response_revision:3376; number_of_response:1; }","duration":"131.396936ms","start":"2026-04-20T14:25:20.466945Z","end":"2026-04-20T14:25:20.598342Z","steps":["trace[1391600064] 'process raft request' (duration: 130.955344ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:50.328566Z","caller":"traceutil/trace.go:171","msg":"trace[895565514] transaction","detail":"{read_only:false; response_revision:3617; number_of_response:1; }","duration":"527.485616ms","start":"2026-04-20T14:25:49.801061Z","end":"2026-04-20T14:25:50.328547Z","steps":["trace[895565514] 'process raft request' (duration: 527.334773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:50.328748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:49.801037Z","time spent":"527.62943ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:25:50.422539Z","caller":"traceutil/trace.go:171","msg":"trace[661695328] transaction","detail":"{read_only:false; response_revision:3618; number_of_response:1; }","duration":"292.619622ms","start":"2026-04-20T14:25:50.129894Z","end":"2026-04-20T14:25:50.422514Z","steps":["trace[661695328] 'process raft request' (duration: 292.438847ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:50.422734Z","caller":"traceutil/trace.go:171","msg":"trace[181568387] transaction","detail":"{read_only:false; response_revision:3619; number_of_response:1; }","duration":"219.098428ms","start":"2026-04-20T14:25:50.203613Z","end":"2026-04-20T14:25:50.422711Z","steps":["trace[181568387] 'process raft request' (duration: 218.857202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:56.542396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.974075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:25:56.542482Z","caller":"traceutil/trace.go:171","msg":"trace[94938693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3649; }","duration":"119.116229ms","start":"2026-04-20T14:25:56.423347Z","end":"2026-04-20T14:25:56.542463Z","steps":["trace[94938693] 'range keys from in-memory index tree' (duration: 118.900654ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:56.542502Z","caller":"traceutil/trace.go:171","msg":"trace[2046412217] transaction","detail":"{read_only:false; response_revision:3650; number_of_response:1; }","duration":"100.187164ms","start":"2026-04-20T14:25:56.442286Z","end":"2026-04-20T14:25:56.542474Z","steps":["trace[2046412217] 'process raft request' (duration: 24.742444ms)","trace[2046412217] 'compare' (duration: 75.270916ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:25:56.630077Z","caller":"traceutil/trace.go:171","msg":"trace[1191852288] linearizableReadLoop","detail":"{readStateIndex:3787; appliedIndex:3785; }","duration":"182.10906ms","start":"2026-04-20T14:25:56.447937Z","end":"2026-04-20T14:25:56.630046Z","steps":["trace[1191852288] 'read index received' (duration: 19.190141ms)","trace[1191852288] 'applied index is now lower than readState.Index' (duration: 162.917529ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:25:56.630183Z","caller":"traceutil/trace.go:171","msg":"trace[1612251942] transaction","detail":"{read_only:false; response_revision:3651; number_of_response:1; }","duration":"184.961608ms","start":"2026-04-20T14:25:56.445195Z","end":"2026-04-20T14:25:56.630157Z","steps":["trace[1612251942] 'process raft request' (duration: 184.703312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:56.630624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.665213ms","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-04-20T14:25:56.630734Z","caller":"traceutil/trace.go:171","msg":"trace[1019559211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3651; }","duration":"182.815677ms","start":"2026-04-20T14:25:56.447903Z","end":"2026-04-20T14:25:56.630718Z","steps":["trace[1019559211] 'agreement among raft nodes before linearized reading' (duration: 182.365666ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:00.612035Z","caller":"traceutil/trace.go:171","msg":"trace[1584140909] transaction","detail":"{read_only:false; response_revision:3668; number_of_response:1; }","duration":"176.789059ms","start":"2026-04-20T14:26:00.435218Z","end":"2026-04-20T14:26:00.612007Z","steps":["trace[1584140909] 'process raft request' (duration: 176.586825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:17.462801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194013540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:26:17.962988Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194013540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:26:18.46368Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194013540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:26:18.955874Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.021251572s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T14:26:18.956421Z","caller":"traceutil/trace.go:171","msg":"trace[1412735861] transaction","detail":"{read_only:false; response_revision:3760; number_of_response:1; }","duration":"2.021827635s","start":"2026-04-20T14:26:16.934558Z","end":"2026-04-20T14:26:18.956386Z","steps":["trace[1412735861] 'process raft request' (duration: 2.021611091s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:18.95665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:16.934548Z","time spent":"2.021978999s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:26:18.968034Z","caller":"traceutil/trace.go:171","msg":"trace[1202934646] transaction","detail":"{read_only:false; response_revision:3762; number_of_response:1; }","duration":"1.67618445s","start":"2026-04-20T14:26:17.291827Z","end":"2026-04-20T14:26:18.968011Z","steps":["trace[1202934646] 'process raft request' (duration: 1.676121529s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:18.968263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:17.291808Z","time spent":"1.676377784s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:26:18.96829Z","caller":"traceutil/trace.go:171","msg":"trace[610153339] transaction","detail":"{read_only:false; response_revision:3763; number_of_response:1; }","duration":"292.757339ms","start":"2026-04-20T14:26:18.675049Z","end":"2026-04-20T14:26:18.96828Z","steps":["trace[610153339] 'process raft request' (duration: 292.455622ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:18.96831Z","caller":"traceutil/trace.go:171","msg":"trace[1688033970] linearizableReadLoop","detail":"{readStateIndex:3902; appliedIndex:3899; }","duration":"2.005692963s","start":"2026-04-20T14:26:16.962606Z","end":"2026-04-20T14:26:18.968299Z","steps":["trace[1688033970] 'read index received' (duration: 1.993566095s)","trace[1688033970] 'applied index is now lower than readState.Index' (duration: 12.126158ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:18.968366Z","caller":"traceutil/trace.go:171","msg":"trace[128303525] transaction","detail":"{read_only:false; response_revision:3765; number_of_response:1; }","duration":"174.861215ms","start":"2026-04-20T14:26:18.793472Z","end":"2026-04-20T14:26:18.968333Z","steps":["trace[128303525] 'process raft request' (duration: 174.791464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:18.968425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.961989017s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/monitoring/kube-prometheus-stack-alertmanager-oauth2-proxy\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:26:18.968454Z","caller":"traceutil/trace.go:171","msg":"trace[21735022] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/monitoring/kube-prometheus-stack-alertmanager-oauth2-proxy; range_end:; response_count:0; response_revision:3765; }","duration":"1.962053069s","start":"2026-04-20T14:26:17.00639Z","end":"2026-04-20T14:26:18.968443Z","steps":["trace[21735022] 'agreement among raft nodes before linearized reading' (duration: 1.961995577s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:18.968446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.005842866s","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-04-20T14:26:18.968486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:17.006379Z","time spent":"1.962101309s","remote":"127.0.0.1:38154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":107,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/monitoring/kube-prometheus-stack-alertmanager-oauth2-proxy\" "} {"level":"info","ts":"2026-04-20T14:26:18.968496Z","caller":"traceutil/trace.go:171","msg":"trace[23566069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3765; }","duration":"2.005895207s","start":"2026-04-20T14:26:16.962591Z","end":"2026-04-20T14:26:18.968486Z","steps":["trace[23566069] 'agreement among raft nodes before linearized reading' (duration: 2.005739504s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:18.968518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:16.962553Z","time spent":"2.005960099s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:26:18.968555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.674992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:26:18.968608Z","caller":"traceutil/trace.go:171","msg":"trace[1411067079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3765; }","duration":"622.749274ms","start":"2026-04-20T14:26:18.345847Z","end":"2026-04-20T14:26:18.968596Z","steps":["trace[1411067079] 'agreement among raft nodes before linearized reading' (duration: 622.55794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:18.968645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:18.345803Z","time spent":"622.834206ms","remote":"127.0.0.1:48796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T14:26:18.968676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.245649912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-20T14:26:18.968666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.16458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.119\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T14:26:18.968702Z","caller":"traceutil/trace.go:171","msg":"trace[389685261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3765; }","duration":"1.245717324s","start":"2026-04-20T14:26:17.722978Z","end":"2026-04-20T14:26:18.968696Z","steps":["trace[389685261] 'agreement among raft nodes before linearized reading' (duration: 1.245650373s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:18.968715Z","caller":"traceutil/trace.go:171","msg":"trace[127217408] range","detail":"{range_begin:/registry/masterleases/199.19.213.119; range_end:; response_count:1; response_revision:3765; }","duration":"654.251272ms","start":"2026-04-20T14:26:18.314452Z","end":"2026-04-20T14:26:18.968704Z","steps":["trace[127217408] 'agreement among raft nodes before linearized reading' (duration: 654.070448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:18.968723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:17.722957Z","time spent":"1.245762665s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T14:26:18.96874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:18.314439Z","time spent":"654.295673ms","remote":"127.0.0.1:48838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.119\" "} {"level":"info","ts":"2026-04-20T14:26:18.968814Z","caller":"traceutil/trace.go:171","msg":"trace[807235285] transaction","detail":"{read_only:false; response_revision:3764; number_of_response:1; }","duration":"285.1421ms","start":"2026-04-20T14:26:18.683661Z","end":"2026-04-20T14:26:18.968803Z","steps":["trace[807235285] 'process raft request' (duration: 284.531817ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:18.968101Z","caller":"traceutil/trace.go:171","msg":"trace[915552577] transaction","detail":"{read_only:false; response_revision:3761; number_of_response:1; }","duration":"2.033051814s","start":"2026-04-20T14:26:16.935019Z","end":"2026-04-20T14:26:18.968071Z","steps":["trace[915552577] 'process raft request' (duration: 2.032804699s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:18.968962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:16.934984Z","time spent":"2.033931833s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:26:18.968829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.584686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T14:26:18.969007Z","caller":"traceutil/trace.go:171","msg":"trace[20958820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3765; }","duration":"154.777121ms","start":"2026-04-20T14:26:18.814217Z","end":"2026-04-20T14:26:18.968995Z","steps":["trace[20958820] 'agreement among raft nodes before linearized reading' (duration: 154.547816ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:22.961178Z","caller":"traceutil/trace.go:171","msg":"trace[1437417362] linearizableReadLoop","detail":"{readStateIndex:4758; appliedIndex:4757; }","duration":"104.484947ms","start":"2026-04-20T14:27:22.85667Z","end":"2026-04-20T14:27:22.961155Z","steps":["trace[1437417362] 'read index received' (duration: 101.887989ms)","trace[1437417362] 'applied index is now lower than readState.Index' (duration: 2.595728ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:27:22.961467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.770482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-20T14:27:22.961526Z","caller":"traceutil/trace.go:171","msg":"trace[1568303920] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4583; }","duration":"104.856753ms","start":"2026-04-20T14:27:22.856655Z","end":"2026-04-20T14:27:22.961512Z","steps":["trace[1568303920] 'agreement among raft nodes before linearized reading' (duration: 104.65833ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:22.961531Z","caller":"traceutil/trace.go:171","msg":"trace[94976052] transaction","detail":"{read_only:false; response_revision:4583; number_of_response:1; }","duration":"105.22763ms","start":"2026-04-20T14:27:22.856271Z","end":"2026-04-20T14:27:22.961498Z","steps":["trace[94976052] 'process raft request' (duration: 102.268116ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:25.184597Z","caller":"traceutil/trace.go:171","msg":"trace[389497337] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"125.230089ms","start":"2026-04-20T14:27:25.059342Z","end":"2026-04-20T14:27:25.184572Z","steps":["trace[389497337] 'process raft request' (duration: 58.454884ms)","trace[389497337] 'compare' (duration: 66.678484ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:27:25.202698Z","caller":"traceutil/trace.go:171","msg":"trace[600743256] linearizableReadLoop","detail":"{readStateIndex:4850; appliedIndex:4848; }","duration":"138.736336ms","start":"2026-04-20T14:27:25.06394Z","end":"2026-04-20T14:27:25.202677Z","steps":["trace[600743256] 'read index received' (duration: 53.673826ms)","trace[600743256] 'applied index is now lower than readState.Index' (duration: 85.06156ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:27:25.202734Z","caller":"traceutil/trace.go:171","msg":"trace[765225902] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4671; }","duration":"143.113115ms","start":"2026-04-20T14:27:25.059592Z","end":"2026-04-20T14:27:25.202705Z","steps":["trace[765225902] 'process raft request' (duration: 142.996523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:25.202969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.0032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-20T14:27:25.20304Z","caller":"traceutil/trace.go:171","msg":"trace[583030866] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4671; }","duration":"139.098683ms","start":"2026-04-20T14:27:25.063924Z","end":"2026-04-20T14:27:25.203023Z","steps":["trace[583030866] 'agreement among raft nodes before linearized reading' (duration: 138.854698ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:26.250147Z","caller":"traceutil/trace.go:171","msg":"trace[514458893] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"222.890888ms","start":"2026-04-20T14:27:26.027232Z","end":"2026-04-20T14:27:26.250123Z","steps":["trace[514458893] 'process raft request' (duration: 222.437579ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:32.041417Z","caller":"traceutil/trace.go:171","msg":"trace[505447411] linearizableReadLoop","detail":"{readStateIndex:4951; appliedIndex:4950; }","duration":"227.724624ms","start":"2026-04-20T14:27:31.813664Z","end":"2026-04-20T14:27:32.041389Z","steps":["trace[505447411] 'read index received' (duration: 226.867989ms)","trace[505447411] 'applied index is now lower than readState.Index' (duration: 855.495µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:27:32.041727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.037909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:27:32.041835Z","caller":"traceutil/trace.go:171","msg":"trace[1251744166] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:4767; }","duration":"228.196762ms","start":"2026-04-20T14:27:31.81361Z","end":"2026-04-20T14:27:32.041807Z","steps":["trace[1251744166] 'agreement among raft nodes before linearized reading' (duration: 227.874856ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:32.042109Z","caller":"traceutil/trace.go:171","msg":"trace[1652752475] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"383.501349ms","start":"2026-04-20T14:27:31.658585Z","end":"2026-04-20T14:27:32.042086Z","steps":["trace[1652752475] 'process raft request' (duration: 382.604453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:32.042366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:27:31.658557Z","time spent":"383.60689ms","remote":"127.0.0.1:48988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10869,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:27:32.648904Z","caller":"traceutil/trace.go:171","msg":"trace[409541755] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"360.39752ms","start":"2026-04-20T14:27:32.288472Z","end":"2026-04-20T14:27:32.648869Z","steps":["trace[409541755] 'process raft request' (duration: 324.146892ms)","trace[409541755] 'compare' (duration: 36.021054ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:27:32.649082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:27:32.288447Z","time spent":"360.544634ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:27:46.120031Z","caller":"traceutil/trace.go:171","msg":"trace[1854030668] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"268.581582ms","start":"2026-04-20T14:27:45.851416Z","end":"2026-04-20T14:27:46.119997Z","steps":["trace[1854030668] 'process raft request' (duration: 248.401771ms)","trace[1854030668] 'compare' (duration: 19.795733ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:02.314567Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194016595,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T14:28:02.410325Z","caller":"traceutil/trace.go:171","msg":"trace[454220898] linearizableReadLoop","detail":"{readStateIndex:5321; appliedIndex:5320; }","duration":"596.372262ms","start":"2026-04-20T14:28:01.813931Z","end":"2026-04-20T14:28:02.410303Z","steps":["trace[454220898] 'read index received' (duration: 596.239339ms)","trace[454220898] 'applied index is now lower than readState.Index' (duration: 132.023µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:02.410458Z","caller":"traceutil/trace.go:171","msg":"trace[968238696] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"599.23112ms","start":"2026-04-20T14:28:01.811199Z","end":"2026-04-20T14:28:02.41043Z","steps":["trace[968238696] 'process raft request' (duration: 598.962715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:02.410487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.539844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:02.410526Z","caller":"traceutil/trace.go:171","msg":"trace[1659961833] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:5127; }","duration":"596.744198ms","start":"2026-04-20T14:28:01.813771Z","end":"2026-04-20T14:28:02.410515Z","steps":["trace[1659961833] 'agreement among raft nodes before linearized reading' (duration: 596.644716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:02.410561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:01.813756Z","time spent":"596.795419ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:02.4106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:01.811185Z","time spent":"599.339531ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:28:02.910497Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194016598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:28:03.410696Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194016598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:28:03.91157Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194016598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:28:04.349464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000066565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T14:28:04.349626Z","caller":"traceutil/trace.go:171","msg":"trace[1055637476] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000318039s","start":"2026-04-20T14:28:02.34929Z","end":"2026-04-20T14:28:04.349608Z","steps":["trace[1055637476] 'agreement among raft nodes before linearized reading' (duration: 2.000114326s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:04.349737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:02.349233Z","time spent":"2.000488912s","remote":"127.0.0.1:48796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T14:28:04.41259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194016598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:28:04.912997Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194016598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:28:05.414049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194016598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:28:05.914528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194016598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:28:06.173299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000270886s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-20T14:28:06.173377Z","caller":"traceutil/trace.go:171","msg":"trace[1818399960] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000419769s","start":"2026-04-20T14:28:04.172939Z","end":"2026-04-20T14:28:06.173359Z","steps":["trace[1818399960] 'agreement among raft nodes before linearized reading' (duration: 2.000320667s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:06.173424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:04.172909Z","time spent":"2.00050477s","remote":"127.0.0.1:48796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/20 14:28:06 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T14:28:06.350411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000163073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T14:28:06.350497Z","caller":"traceutil/trace.go:171","msg":"trace[1053810384] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000323296s","start":"2026-04-20T14:28:04.350154Z","end":"2026-04-20T14:28:06.350477Z","steps":["trace[1053810384] 'agreement among raft nodes before linearized reading' (duration: 2.000207344s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:06.350547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:04.350133Z","time spent":"2.000401977s","remote":"127.0.0.1:48790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/20 14:28:06 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T14:28:06.415275Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194016598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:28:06.916443Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194016598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:28:07.417019Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194016598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:28:07.917192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194016598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:28:08.35833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000584358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-20T14:28:08.358402Z","caller":"traceutil/trace.go:171","msg":"trace[464848748] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000701101s","start":"2026-04-20T14:28:06.357686Z","end":"2026-04-20T14:28:08.358387Z","steps":["trace[464848748] 'agreement among raft nodes before linearized reading' (duration: 2.000616679s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:08.358441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:06.357642Z","time spent":"2.000790682s","remote":"127.0.0.1:48796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/20 14:28:08 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T14:28:08.414834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.416888Z","time spent":"4.997938341s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/20 14:28:08 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T14:28:08.417614Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194016598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:28:08.428063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.429568Z","time spent":"4.998488687s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/20 14:28:08 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T14:28:08.431194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.433016Z","time spent":"4.998172772s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/20 14:28:08 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T14:28:08.443185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.44467Z","time spent":"4.998511885s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/20 14:28:08 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T14:28:08.603384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999311532s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T14:28:08.603485Z","caller":"traceutil/trace.go:171","msg":"trace[2114567028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999458524s","start":"2026-04-20T14:28:03.604002Z","end":"2026-04-20T14:28:08.603461Z","steps":["trace[2114567028] 'agreement among raft nodes before linearized reading' (duration: 4.999308092s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:08.603546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.603987Z","time spent":"4.999544616s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T14:28:08.604249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999634438s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T14:28:08.604367Z","caller":"traceutil/trace.go:171","msg":"trace[654028172] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999799591s","start":"2026-04-20T14:28:03.604528Z","end":"2026-04-20T14:28:08.604327Z","steps":["trace[654028172] 'agreement among raft nodes before linearized reading' (duration: 4.999640728s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:08.604427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.604522Z","time spent":"4.999894332s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/04/20 14:28:08 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T14:28:08.605789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99922708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T14:28:08.605846Z","caller":"traceutil/trace.go:171","msg":"trace[2096846621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999296681s","start":"2026-04-20T14:28:03.60653Z","end":"2026-04-20T14:28:08.605827Z","steps":["trace[2096846621] 'agreement among raft nodes before linearized reading' (duration: 4.99923626s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:08.606143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.606525Z","time spent":"4.999524205s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/04/20 14:28:08 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T14:28:08.918862Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381332567194016598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:28:09.143926Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"6.73377849s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-20T14:28:09.148978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.869456251s","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-04-20T14:28:09.149086Z","caller":"traceutil/trace.go:171","msg":"trace[459293507] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5127; }","duration":"3.869573703s","start":"2026-04-20T14:28:05.279493Z","end":"2026-04-20T14:28:09.149067Z","steps":["trace[459293507] 'range keys from in-memory index tree' (duration: 3.869442041s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:09.151484Z","caller":"traceutil/trace.go:171","msg":"trace[581052646] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"6.762191556s","start":"2026-04-20T14:28:02.389274Z","end":"2026-04-20T14:28:09.151465Z","steps":["trace[581052646] 'process raft request' (duration: 6.754946665s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.151603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:02.389253Z","time spent":"6.762288768s","remote":"127.0.0.1:48988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11904,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:28:09.166734Z","caller":"traceutil/trace.go:171","msg":"trace[337348172] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"5.997708005s","start":"2026-04-20T14:28:03.169006Z","end":"2026-04-20T14:28:09.166714Z","steps":["trace[337348172] 'process raft request' (duration: 5.997506751s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.166925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.168978Z","time spent":"5.997881848s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:28:09.184407Z","caller":"traceutil/trace.go:171","msg":"trace[355209561] linearizableReadLoop","detail":"{readStateIndex:5331; appliedIndex:5321; }","duration":"6.773995346s","start":"2026-04-20T14:28:02.410393Z","end":"2026-04-20T14:28:09.184389Z","steps":["trace[355209561] 'read index received' (duration: 6.733840622s)","trace[355209561] 'applied index is now lower than readState.Index' (duration: 40.153944ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:09.184457Z","caller":"traceutil/trace.go:171","msg":"trace[1009098916] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"1.423922206s","start":"2026-04-20T14:28:07.760508Z","end":"2026-04-20T14:28:09.184431Z","steps":["trace[1009098916] 'process raft request' (duration: 1.423770783s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.184597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:07.760484Z","time spent":"1.424052627s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:28:09.184622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.973086282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3315"} {"level":"info","ts":"2026-04-20T14:28:09.184646Z","caller":"traceutil/trace.go:171","msg":"trace[102207707] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5136; }","duration":"6.973154554s","start":"2026-04-20T14:28:02.211483Z","end":"2026-04-20T14:28:09.184638Z","steps":["trace[102207707] 'agreement among raft nodes before linearized reading' (duration: 6.973029322s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.184668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:02.211464Z","time spent":"6.973198464s","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3338,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-20T14:28:09.184748Z","caller":"traceutil/trace.go:171","msg":"trace[810960443] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"3.007910309s","start":"2026-04-20T14:28:06.17683Z","end":"2026-04-20T14:28:09.18474Z","steps":["trace[810960443] 'process raft request' (duration: 3.007160946s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.184784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:06.176807Z","time spent":"3.00796019s","remote":"127.0.0.1:48878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:28:09.184856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.01410813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:28:09.184881Z","caller":"traceutil/trace.go:171","msg":"trace[1286639949] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:5136; }","duration":"7.014167492s","start":"2026-04-20T14:28:02.170706Z","end":"2026-04-20T14:28:09.184873Z","steps":["trace[1286639949] 'agreement among raft nodes before linearized reading' (duration: 7.014126471s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.184897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:02.170686Z","time spent":"7.014206232s","remote":"127.0.0.1:35886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:28:09.1845Z","caller":"traceutil/trace.go:171","msg":"trace[110151414] transaction","detail":"{read_only:false; response_revision:5136; number_of_response:1; }","duration":"543.688379ms","start":"2026-04-20T14:28:08.640804Z","end":"2026-04-20T14:28:09.184493Z","steps":["trace[110151414] 'process raft request' (duration: 543.526836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.184976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:08.640781Z","time spent":"544.164087ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:28:09.185077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.272148478s","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-04-20T14:28:09.185098Z","caller":"traceutil/trace.go:171","msg":"trace[1086546843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5136; }","duration":"7.272203309s","start":"2026-04-20T14:28:01.912887Z","end":"2026-04-20T14:28:09.18509Z","steps":["trace[1086546843] 'agreement among raft nodes before linearized reading' (duration: 7.272148168s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.185112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:01.912864Z","time spent":"7.272245s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:28:09.185196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.635478795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:28:09.185211Z","caller":"traceutil/trace.go:171","msg":"trace[1621554254] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:5136; }","duration":"6.635569376s","start":"2026-04-20T14:28:02.549636Z","end":"2026-04-20T14:28:09.185206Z","steps":["trace[1621554254] 'agreement among raft nodes before linearized reading' (duration: 6.635540136s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.185224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:02.54961Z","time spent":"6.635610366s","remote":"127.0.0.1:38128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.185273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346066403s","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-04-20T14:28:09.185311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.381715225s","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-04-20T14:28:09.185305Z","caller":"traceutil/trace.go:171","msg":"trace[207322455] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:5136; }","duration":"1.346120034s","start":"2026-04-20T14:28:07.839178Z","end":"2026-04-20T14:28:09.185298Z","steps":["trace[207322455] 'agreement among raft nodes before linearized reading' (duration: 1.346067553s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:09.185326Z","caller":"traceutil/trace.go:171","msg":"trace[2108643508] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:5136; }","duration":"1.381731065s","start":"2026-04-20T14:28:07.803591Z","end":"2026-04-20T14:28:09.185322Z","steps":["trace[2108643508] 'agreement among raft nodes before linearized reading' (duration: 1.381700725s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.185344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:07.839171Z","time spent":"1.346166564s","remote":"127.0.0.1:49106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.185377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.567946984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:28:09.185398Z","caller":"traceutil/trace.go:171","msg":"trace[1212536325] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:5136; }","duration":"2.568009644s","start":"2026-04-20T14:28:06.617384Z","end":"2026-04-20T14:28:09.185393Z","steps":["trace[1212536325] 'agreement among raft nodes before linearized reading' (duration: 2.567971554s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.185411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:06.617363Z","time spent":"2.568044805s","remote":"127.0.0.1:49304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":10,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.185491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.855153929s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:09.185509Z","caller":"traceutil/trace.go:171","msg":"trace[1232173352] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5136; }","duration":"2.85521259s","start":"2026-04-20T14:28:06.330292Z","end":"2026-04-20T14:28:09.185505Z","steps":["trace[1232173352] 'agreement among raft nodes before linearized reading' (duration: 2.85518327s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.185534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:06.330271Z","time spent":"2.85525899s","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.185536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.181265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:09.185556Z","caller":"traceutil/trace.go:171","msg":"trace[2118725725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5136; }","duration":"103.234817ms","start":"2026-04-20T14:28:09.082315Z","end":"2026-04-20T14:28:09.18555Z","steps":["trace[2118725725] 'agreement among raft nodes before linearized reading' (duration: 103.201036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.18562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.26404009s","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-04-20T14:28:09.185639Z","caller":"traceutil/trace.go:171","msg":"trace[1324012380] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5136; }","duration":"3.26406015s","start":"2026-04-20T14:28:05.921574Z","end":"2026-04-20T14:28:09.185634Z","steps":["trace[1324012380] 'agreement among raft nodes before linearized reading' (duration: 3.26403104s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.185674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:05.921542Z","time spent":"3.264107871s","remote":"127.0.0.1:46104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.185737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.721482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:551"} {"level":"warn","ts":"2026-04-20T14:28:09.185785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.305049993s","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-04-20T14:28:09.185784Z","caller":"traceutil/trace.go:171","msg":"trace[1228194650] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:5136; }","duration":"740.794513ms","start":"2026-04-20T14:28:08.444977Z","end":"2026-04-20T14:28:09.185771Z","steps":["trace[1228194650] 'agreement among raft nodes before linearized reading' (duration: 740.666221ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:09.185804Z","caller":"traceutil/trace.go:171","msg":"trace[2055153480] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:5136; }","duration":"3.305108074s","start":"2026-04-20T14:28:05.880691Z","end":"2026-04-20T14:28:09.185799Z","steps":["trace[2055153480] 'agreement among raft nodes before linearized reading' (duration: 3.305076623s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.185822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:05.880671Z","time spent":"3.305147576s","remote":"127.0.0.1:49156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.185894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.68916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-20T14:28:09.185927Z","caller":"traceutil/trace.go:171","msg":"trace[1488857780] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5136; }","duration":"752.741021ms","start":"2026-04-20T14:28:08.433179Z","end":"2026-04-20T14:28:09.18592Z","steps":["trace[1488857780] 'agreement among raft nodes before linearized reading' (duration: 752.656729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.185953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:08.433169Z","time spent":"752.777661ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T14:28:09.186129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.303043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-20T14:28:09.186153Z","caller":"traceutil/trace.go:171","msg":"trace[911852402] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5136; }","duration":"755.367364ms","start":"2026-04-20T14:28:08.430779Z","end":"2026-04-20T14:28:09.186147Z","steps":["trace[911852402] 'agreement among raft nodes before linearized reading' (duration: 755.271353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.186174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:08.430759Z","time spent":"755.410415ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-20T14:28:09.186283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.51079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-20T14:28:09.186306Z","caller":"traceutil/trace.go:171","msg":"trace[220355760] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5136; }","duration":"769.57256ms","start":"2026-04-20T14:28:08.416728Z","end":"2026-04-20T14:28:09.186301Z","steps":["trace[220355760] 'agreement among raft nodes before linearized reading' (duration: 769.519848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.186331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:08.416707Z","time spent":"769.618091ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T14:28:09.186482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.142827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-20T14:28:09.186521Z","caller":"traceutil/trace.go:171","msg":"trace[1436833232] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5136; }","duration":"806.230197ms","start":"2026-04-20T14:28:08.380282Z","end":"2026-04-20T14:28:09.186512Z","steps":["trace[1436833232] 'agreement among raft nodes before linearized reading' (duration: 806.141016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.186556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:08.38026Z","time spent":"806.287459ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-20T14:28:09.186663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.859697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:09.186686Z","caller":"traceutil/trace.go:171","msg":"trace[467242081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5136; }","duration":"827.920107ms","start":"2026-04-20T14:28:08.358759Z","end":"2026-04-20T14:28:09.186679Z","steps":["trace[467242081] 'agreement among raft nodes before linearized reading' (duration: 827.888007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.186702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:08.358735Z","time spent":"827.963428ms","remote":"127.0.0.1:48790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T14:28:09.186816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.861742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.119\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T14:28:09.186839Z","caller":"traceutil/trace.go:171","msg":"trace[1063916809] range","detail":"{range_begin:/registry/masterleases/199.19.213.119; range_end:; response_count:1; response_revision:5136; }","duration":"866.911653ms","start":"2026-04-20T14:28:08.319922Z","end":"2026-04-20T14:28:09.186834Z","steps":["trace[1063916809] 'agreement among raft nodes before linearized reading' (duration: 866.854482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.186855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:08.319879Z","time spent":"866.972484ms","remote":"127.0.0.1:48838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.119\" "} {"level":"warn","ts":"2026-04-20T14:28:09.186451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.37269454s","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":"warn","ts":"2026-04-20T14:28:09.186958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.683048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:28:09.186994Z","caller":"traceutil/trace.go:171","msg":"trace[804748923] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:5136; }","duration":"875.753809ms","start":"2026-04-20T14:28:08.311229Z","end":"2026-04-20T14:28:09.186983Z","steps":["trace[804748923] 'agreement among raft nodes before linearized reading' (duration: 875.703738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.185931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.96870903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3315"} {"level":"warn","ts":"2026-04-20T14:28:09.187034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:08.31121Z","time spent":"875.813371ms","remote":"127.0.0.1:49222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.187067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.422939765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-20T14:28:09.187066Z","caller":"traceutil/trace.go:171","msg":"trace[1201273818] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5136; }","duration":"3.96987225s","start":"2026-04-20T14:28:05.217174Z","end":"2026-04-20T14:28:09.187047Z","steps":["trace[1201273818] 'agreement among raft nodes before linearized reading' (duration: 3.96872035s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:09.187092Z","caller":"traceutil/trace.go:171","msg":"trace[595460726] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5136; }","duration":"4.422968096s","start":"2026-04-20T14:28:04.764117Z","end":"2026-04-20T14:28:09.187085Z","steps":["trace[595460726] 'agreement among raft nodes before linearized reading' (duration: 4.421849717s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:09.187054Z","caller":"traceutil/trace.go:171","msg":"trace[1698364753] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5136; }","duration":"6.37334986s","start":"2026-04-20T14:28:02.813627Z","end":"2026-04-20T14:28:09.186977Z","steps":["trace[1698364753] 'agreement among raft nodes before linearized reading' (duration: 6.37272894s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.187117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:05.217149Z","time spent":"3.969953771s","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3338,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-04-20T14:28:09.187127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.667321755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:28:09.185958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.113474922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:28:09.187149Z","caller":"traceutil/trace.go:171","msg":"trace[1387107308] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:5136; }","duration":"4.667340946s","start":"2026-04-20T14:28:04.5198Z","end":"2026-04-20T14:28:09.187141Z","steps":["trace[1387107308] 'agreement among raft nodes before linearized reading' (duration: 4.667313545s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.187164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:04.764061Z","time spent":"4.423055217s","remote":"127.0.0.1:49330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":532,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:28:09.187168Z","caller":"traceutil/trace.go:171","msg":"trace[1169540141] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:5136; }","duration":"4.114713293s","start":"2026-04-20T14:28:05.072446Z","end":"2026-04-20T14:28:09.187159Z","steps":["trace[1169540141] 'agreement among raft nodes before linearized reading' (duration: 4.113495632s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.187148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:02.813606Z","time spent":"6.373526503s","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.187241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.772485631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T14:28:09.187262Z","caller":"traceutil/trace.go:171","msg":"trace[987955362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5136; }","duration":"4.772537661s","start":"2026-04-20T14:28:04.414719Z","end":"2026-04-20T14:28:09.187257Z","steps":["trace[987955362] 'agreement among raft nodes before linearized reading' (duration: 4.7724612s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.18582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:08.44496Z","time spent":"740.850004ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":574,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-04-20T14:28:09.187279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:04.414701Z","time spent":"4.772574822s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T14:28:09.185346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:07.803582Z","time spent":"1.381758215s","remote":"127.0.0.1:33670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.187343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.145112277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:28:09.187096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.853718447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:09.187362Z","caller":"traceutil/trace.go:171","msg":"trace[417756379] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5136; }","duration":"6.145176078s","start":"2026-04-20T14:28:03.042179Z","end":"2026-04-20T14:28:09.187355Z","steps":["trace[417756379] 'agreement among raft nodes before linearized reading' (duration: 6.145139777s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:09.187366Z","caller":"traceutil/trace.go:171","msg":"trace[657545335] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:5136; }","duration":"4.854034142s","start":"2026-04-20T14:28:04.333325Z","end":"2026-04-20T14:28:09.187359Z","steps":["trace[657545335] 'agreement among raft nodes before linearized reading' (duration: 4.853738107s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.187379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.042158Z","time spent":"6.145216948s","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.187386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:04.333303Z","time spent":"4.854076302s","remote":"127.0.0.1:33566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.187169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:04.519771Z","time spent":"4.667393156s","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.1872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:05.072428Z","time spent":"4.114760343s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.187507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.655542197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-20T14:28:09.187535Z","caller":"traceutil/trace.go:171","msg":"trace[8711654] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5136; }","duration":"5.655597839s","start":"2026-04-20T14:28:03.531923Z","end":"2026-04-20T14:28:09.187521Z","steps":["trace[8711654] 'agreement among raft nodes before linearized reading' (duration: 5.655540288s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.187551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.531905Z","time spent":"5.655641399s","remote":"127.0.0.1:48998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-20T14:28:25.469879Z","caller":"traceutil/trace.go:171","msg":"trace[1465546263] linearizableReadLoop","detail":"{readStateIndex:5518; appliedIndex:5515; }","duration":"109.456997ms","start":"2026-04-20T14:28:25.360403Z","end":"2026-04-20T14:28:25.46986Z","steps":["trace[1465546263] 'read index received' (duration: 13.929093ms)","trace[1465546263] 'applied index is now lower than readState.Index' (duration: 95.527204ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:25.469996Z","caller":"traceutil/trace.go:171","msg":"trace[1385539582] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"114.834574ms","start":"2026-04-20T14:28:25.355152Z","end":"2026-04-20T14:28:25.469986Z","steps":["trace[1385539582] 'process raft request' (duration: 114.671891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:25.470188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.773021ms","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-04-20T14:28:25.470218Z","caller":"traceutil/trace.go:171","msg":"trace[1246991275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5318; }","duration":"109.838843ms","start":"2026-04-20T14:28:25.360371Z","end":"2026-04-20T14:28:25.470209Z","steps":["trace[1246991275] 'agreement among raft nodes before linearized reading' (duration: 109.715771ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:25.470218Z","caller":"traceutil/trace.go:171","msg":"trace[870577299] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"115.644966ms","start":"2026-04-20T14:28:25.354546Z","end":"2026-04-20T14:28:25.470191Z","steps":["trace[870577299] 'process raft request' (duration: 115.168328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:25.470403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.987485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:28:25.470429Z","caller":"traceutil/trace.go:171","msg":"trace[1909172987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5318; }","duration":"110.045956ms","start":"2026-04-20T14:28:25.360377Z","end":"2026-04-20T14:28:25.470423Z","steps":["trace[1909172987] 'agreement among raft nodes before linearized reading' (duration: 109.983165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.56546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.155604ms","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-04-20T14:28:35.565527Z","caller":"traceutil/trace.go:171","msg":"trace[36912322] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5369; }","duration":"781.248376ms","start":"2026-04-20T14:28:34.784264Z","end":"2026-04-20T14:28:35.565513Z","steps":["trace[36912322] 'range keys from in-memory index tree' (duration: 780.88702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.565558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:34.78426Z","time spent":"781.290256ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:28:35.565596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.87647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/keystone-credential-setup-tzdvx\" ","response":"range_response_count:1 size:2894"} {"level":"info","ts":"2026-04-20T14:28:35.565634Z","caller":"traceutil/trace.go:171","msg":"trace[61390754] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/keystone-credential-setup-tzdvx; range_end:; response_count:1; response_revision:5369; }","duration":"911.979251ms","start":"2026-04-20T14:28:34.653645Z","end":"2026-04-20T14:28:35.565624Z","steps":["trace[61390754] 'range keys from in-memory index tree' (duration: 911.716557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.565677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:34.653626Z","time spent":"912.025042ms","remote":"127.0.0.1:33388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":2917,"request content":"key:\"/registry/cilium.io/ciliumendpoints/openstack/keystone-credential-setup-tzdvx\" "} {"level":"warn","ts":"2026-04-20T14:28:35.565858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.887595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-20T14:28:35.565881Z","caller":"traceutil/trace.go:171","msg":"trace[2103743333] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5369; }","duration":"898.938985ms","start":"2026-04-20T14:28:34.666936Z","end":"2026-04-20T14:28:35.565875Z","steps":["trace[2103743333] 'range keys from in-memory index tree' (duration: 898.780553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.565898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:34.666896Z","time spent":"898.997756ms","remote":"127.0.0.1:48998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-20T14:28:35.565925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.365863ms","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":"warn","ts":"2026-04-20T14:28:35.566093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.663252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:35.566097Z","caller":"traceutil/trace.go:171","msg":"trace[1476802970] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5369; }","duration":"629.609748ms","start":"2026-04-20T14:28:34.936424Z","end":"2026-04-20T14:28:35.566034Z","steps":["trace[1476802970] 'count revisions from in-memory index tree' (duration: 629.248611ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:35.566119Z","caller":"traceutil/trace.go:171","msg":"trace[812160700] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5369; }","duration":"826.723494ms","start":"2026-04-20T14:28:34.739389Z","end":"2026-04-20T14:28:35.566112Z","steps":["trace[812160700] 'count revisions from in-memory index tree' (duration: 826.598052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.56614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:34.739373Z","time spent":"826.758934ms","remote":"127.0.0.1:48886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:35.566106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.540736ms","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-04-20T14:28:35.566205Z","caller":"traceutil/trace.go:171","msg":"trace[1011781700] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5369; }","duration":"252.699458ms","start":"2026-04-20T14:28:35.31348Z","end":"2026-04-20T14:28:35.566179Z","steps":["trace[1011781700] 'count revisions from in-memory index tree' (duration: 252.459885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.566227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:34.936401Z","time spent":"629.8077ms","remote":"127.0.0.1:46070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:35.566254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.703255ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:28:35.566418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.360682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T14:28:35.566486Z","caller":"traceutil/trace.go:171","msg":"trace[593801932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5369; }","duration":"782.449564ms","start":"2026-04-20T14:28:34.78402Z","end":"2026-04-20T14:28:35.566469Z","steps":["trace[593801932] 'range keys from in-memory index tree' (duration: 782.20075ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:35.566443Z","caller":"traceutil/trace.go:171","msg":"trace[1028989723] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5369; }","duration":"286.886488ms","start":"2026-04-20T14:28:35.279533Z","end":"2026-04-20T14:28:35.566419Z","steps":["trace[1028989723] 'range keys from in-memory index tree' (duration: 286.689014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.566518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:34.784013Z","time spent":"782.498315ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T14:28:35.56627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.359939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T14:28:35.566656Z","caller":"traceutil/trace.go:171","msg":"trace[101230147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5369; }","duration":"797.758706ms","start":"2026-04-20T14:28:34.768889Z","end":"2026-04-20T14:28:35.566648Z","steps":["trace[101230147] 'range keys from in-memory index tree' (duration: 797.305249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.566699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:34.768882Z","time spent":"797.809347ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T14:28:35.566203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.608505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:35.56676Z","caller":"traceutil/trace.go:171","msg":"trace[1749390423] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:5369; }","duration":"296.307917ms","start":"2026-04-20T14:28:35.270445Z","end":"2026-04-20T14:28:35.566753Z","steps":["trace[1749390423] 'count revisions from in-memory index tree' (duration: 295.520343ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:35.566971Z","caller":"traceutil/trace.go:171","msg":"trace[1180835589] linearizableReadLoop","detail":"{readStateIndex:5572; appliedIndex:5571; }","duration":"219.378503ms","start":"2026-04-20T14:28:35.347582Z","end":"2026-04-20T14:28:35.56696Z","steps":["trace[1180835589] 'read index received' (duration: 179.31566ms)","trace[1180835589] 'applied index is now lower than readState.Index' (duration: 40.062003ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:35.567039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.466214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:35.56706Z","caller":"traceutil/trace.go:171","msg":"trace[878296362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5370; }","duration":"219.534145ms","start":"2026-04-20T14:28:35.34752Z","end":"2026-04-20T14:28:35.567054Z","steps":["trace[878296362] 'agreement among raft nodes before linearized reading' (duration: 219.495475ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:35.567112Z","caller":"traceutil/trace.go:171","msg":"trace[1449440179] transaction","detail":"{read_only:false; response_revision:5370; number_of_response:1; }","duration":"246.018103ms","start":"2026-04-20T14:28:35.32105Z","end":"2026-04-20T14:28:35.567068Z","steps":["trace[1449440179] 'process raft request' (duration: 205.857668ms)","trace[1449440179] 'compare' (duration: 39.966202ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:35.819706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.784169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:28:35.820167Z","caller":"traceutil/trace.go:171","msg":"trace[509992909] transaction","detail":"{read_only:false; response_revision:5371; number_of_response:1; }","duration":"413.935883ms","start":"2026-04-20T14:28:35.406203Z","end":"2026-04-20T14:28:35.820138Z","steps":["trace[509992909] 'process raft request' (duration: 269.600205ms)","trace[509992909] 'compare' (duration: 143.615337ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:35.820219Z","caller":"traceutil/trace.go:171","msg":"trace[1676743495] transaction","detail":"{read_only:false; response_revision:5372; number_of_response:1; }","duration":"320.532408ms","start":"2026-04-20T14:28:35.499666Z","end":"2026-04-20T14:28:35.820199Z","steps":["trace[1676743495] 'process raft request' (duration: 320.384266ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:35.820464Z","caller":"traceutil/trace.go:171","msg":"trace[36982862] linearizableReadLoop","detail":"{readStateIndex:5575; appliedIndex:5572; }","duration":"253.393108ms","start":"2026-04-20T14:28:35.566995Z","end":"2026-04-20T14:28:35.820388Z","steps":["trace[36982862] 'read index received' (duration: 108.836236ms)","trace[36982862] 'applied index is now lower than readState.Index' (duration: 144.555532ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:35.820473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:35.499642Z","time spent":"320.775711ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:28:35.820837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:35.406176Z","time spent":"414.044754ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:28:35.820855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.599457ms","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-04-20T14:28:35.820949Z","caller":"traceutil/trace.go:171","msg":"trace[842101719] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5373; }","duration":"472.75505ms","start":"2026-04-20T14:28:35.348173Z","end":"2026-04-20T14:28:35.820929Z","steps":["trace[842101719] 'agreement among raft nodes before linearized reading' (duration: 472.595348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.820991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:35.348154Z","time spent":"472.828551ms","remote":"127.0.0.1:33556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:28:35.821055Z","caller":"traceutil/trace.go:171","msg":"trace[71755752] transaction","detail":"{read_only:false; response_revision:5373; number_of_response:1; }","duration":"314.861239ms","start":"2026-04-20T14:28:35.506116Z","end":"2026-04-20T14:28:35.820977Z","steps":["trace[71755752] 'process raft request' (duration: 314.036356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.82087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.844732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-credential-setup-tzdvx\" ","response":"range_response_count:1 size:7923"} {"level":"info","ts":"2026-04-20T14:28:35.821173Z","caller":"traceutil/trace.go:171","msg":"trace[940912959] range","detail":"{range_begin:/registry/pods/openstack/keystone-credential-setup-tzdvx; range_end:; response_count:1; response_revision:5373; }","duration":"307.197558ms","start":"2026-04-20T14:28:35.513962Z","end":"2026-04-20T14:28:35.821159Z","steps":["trace[940912959] 'agreement among raft nodes before linearized reading' (duration: 306.767921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.821243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:35.513907Z","time spent":"307.323539ms","remote":"127.0.0.1:48998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":7946,"request content":"key:\"/registry/pods/openstack/keystone-credential-setup-tzdvx\" "} {"level":"warn","ts":"2026-04-20T14:28:35.821158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.824925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T14:28:35.821352Z","caller":"traceutil/trace.go:171","msg":"trace[869932417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5373; }","duration":"266.041318ms","start":"2026-04-20T14:28:35.555295Z","end":"2026-04-20T14:28:35.821336Z","steps":["trace[869932417] 'agreement among raft nodes before linearized reading' (duration: 265.730734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.821177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:35.506104Z","time spent":"314.987931ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:28:35.821844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.532275ms","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-04-20T14:28:35.821882Z","caller":"traceutil/trace.go:171","msg":"trace[1157697638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5373; }","duration":"271.608156ms","start":"2026-04-20T14:28:35.550264Z","end":"2026-04-20T14:28:35.821872Z","steps":["trace[1157697638] 'agreement among raft nodes before linearized reading' (duration: 270.410336ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:35.856027Z","caller":"traceutil/trace.go:171","msg":"trace[1138811792] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5374; }","duration":"285.956692ms","start":"2026-04-20T14:28:35.57005Z","end":"2026-04-20T14:28:35.856006Z","steps":["trace[1138811792] 'process raft request' (duration: 285.755989ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:35.856434Z","caller":"traceutil/trace.go:171","msg":"trace[269735270] transaction","detail":"{read_only:false; response_revision:5375; number_of_response:1; }","duration":"284.687623ms","start":"2026-04-20T14:28:35.571734Z","end":"2026-04-20T14:28:35.856422Z","steps":["trace[269735270] 'process raft request' (duration: 284.216096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.85675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.645383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:35.856804Z","caller":"traceutil/trace.go:171","msg":"trace[279356732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5377; }","duration":"286.752135ms","start":"2026-04-20T14:28:35.57004Z","end":"2026-04-20T14:28:35.856792Z","steps":["trace[279356732] 'agreement among raft nodes before linearized reading' (duration: 286.647453ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:35.857035Z","caller":"traceutil/trace.go:171","msg":"trace[996298161] transaction","detail":"{read_only:false; response_revision:5376; number_of_response:1; }","duration":"285.11816ms","start":"2026-04-20T14:28:35.571902Z","end":"2026-04-20T14:28:35.85702Z","steps":["trace[996298161] 'process raft request' (duration: 284.314268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.857051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.970177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-20T14:28:35.857158Z","caller":"traceutil/trace.go:171","msg":"trace[1194517063] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5377; }","duration":"285.090459ms","start":"2026-04-20T14:28:35.572061Z","end":"2026-04-20T14:28:35.857152Z","steps":["trace[1194517063] 'agreement among raft nodes before linearized reading' (duration: 284.912896ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:35.856908Z","caller":"traceutil/trace.go:171","msg":"trace[1114376528] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"284.51863ms","start":"2026-04-20T14:28:35.57236Z","end":"2026-04-20T14:28:35.856879Z","steps":["trace[1114376528] 'process raft request' (duration: 284.229775ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:36.049282Z","caller":"traceutil/trace.go:171","msg":"trace[305635931] transaction","detail":"{read_only:false; response_revision:5378; number_of_response:1; }","duration":"222.881287ms","start":"2026-04-20T14:28:35.826361Z","end":"2026-04-20T14:28:36.049242Z","steps":["trace[305635931] 'process raft request' (duration: 207.584235ms)","trace[305635931] 'compare' (duration: 15.025388ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:36.049883Z","caller":"traceutil/trace.go:171","msg":"trace[1544736911] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"218.219853ms","start":"2026-04-20T14:28:35.831637Z","end":"2026-04-20T14:28:36.049856Z","steps":["trace[1544736911] 'process raft request' (duration: 217.763856ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:36.049879Z","caller":"traceutil/trace.go:171","msg":"trace[1053167661] linearizableReadLoop","detail":"{readStateIndex:5582; appliedIndex:5579; }","duration":"191.537501ms","start":"2026-04-20T14:28:35.858309Z","end":"2026-04-20T14:28:36.049847Z","steps":["trace[1053167661] 'read index received' (duration: 175.553099ms)","trace[1053167661] 'applied index is now lower than readState.Index' (duration: 15.967262ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:36.050045Z","caller":"traceutil/trace.go:171","msg":"trace[854753807] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"223.144301ms","start":"2026-04-20T14:28:35.826877Z","end":"2026-04-20T14:28:36.050021Z","steps":["trace[854753807] 'process raft request' (duration: 222.299738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:36.050594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.259413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:11918"} {"level":"info","ts":"2026-04-20T14:28:36.050654Z","caller":"traceutil/trace.go:171","msg":"trace[242336106] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5380; }","duration":"192.346274ms","start":"2026-04-20T14:28:35.858294Z","end":"2026-04-20T14:28:36.050641Z","steps":["trace[242336106] 'agreement among raft nodes before linearized reading' (duration: 191.636353ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:37.178626Z","caller":"traceutil/trace.go:171","msg":"trace[1929026488] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"116.463523ms","start":"2026-04-20T14:28:37.062135Z","end":"2026-04-20T14:28:37.178598Z","steps":["trace[1929026488] 'process raft request' (duration: 116.27063ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:37.470345Z","caller":"traceutil/trace.go:171","msg":"trace[997183450] linearizableReadLoop","detail":"{readStateIndex:5584; appliedIndex:5583; }","duration":"123.630195ms","start":"2026-04-20T14:28:37.346682Z","end":"2026-04-20T14:28:37.470312Z","steps":["trace[997183450] 'read index received' (duration: 48.934009ms)","trace[997183450] 'applied index is now lower than readState.Index' (duration: 74.694636ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:37.47039Z","caller":"traceutil/trace.go:171","msg":"trace[1409157180] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"283.393798ms","start":"2026-04-20T14:28:37.186962Z","end":"2026-04-20T14:28:37.470356Z","steps":["trace[1409157180] 'process raft request' (duration: 208.695063ms)","trace[1409157180] 'compare' (duration: 74.472032ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:37.470498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.801737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:37.470656Z","caller":"traceutil/trace.go:171","msg":"trace[764307344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5382; }","duration":"123.992881ms","start":"2026-04-20T14:28:37.34664Z","end":"2026-04-20T14:28:37.470633Z","steps":["trace[764307344] 'agreement among raft nodes before linearized reading' (duration: 123.788467ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:37.746806Z","caller":"traceutil/trace.go:171","msg":"trace[1768436016] linearizableReadLoop","detail":"{readStateIndex:5587; appliedIndex:5585; }","duration":"162.221702ms","start":"2026-04-20T14:28:37.584563Z","end":"2026-04-20T14:28:37.746785Z","steps":["trace[1768436016] 'read index received' (duration: 91.672622ms)","trace[1768436016] 'applied index is now lower than readState.Index' (duration: 70.54805ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:37.747004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.424605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:37.747229Z","caller":"traceutil/trace.go:171","msg":"trace[1025498224] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-init; range_end:; response_count:0; response_revision:5384; }","duration":"162.658019ms","start":"2026-04-20T14:28:37.58453Z","end":"2026-04-20T14:28:37.747188Z","steps":["trace[1025498224] 'agreement among raft nodes before linearized reading' (duration: 162.345534ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:37.747163Z","caller":"traceutil/trace.go:171","msg":"trace[970429450] transaction","detail":"{read_only:false; response_revision:5384; number_of_response:1; }","duration":"169.0379ms","start":"2026-04-20T14:28:37.577831Z","end":"2026-04-20T14:28:37.746869Z","steps":["trace[970429450] 'process raft request' (duration: 168.459351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:38.07698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.805428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:28:38.077065Z","caller":"traceutil/trace.go:171","msg":"trace[36614093] transaction","detail":"{read_only:false; response_revision:5386; number_of_response:1; }","duration":"292.389008ms","start":"2026-04-20T14:28:37.784661Z","end":"2026-04-20T14:28:38.07705Z","steps":["trace[36614093] 'process raft request' (duration: 124.439388ms)","trace[36614093] 'compare' (duration: 167.665796ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:38.11868Z","caller":"traceutil/trace.go:171","msg":"trace[138924412] transaction","detail":"{read_only:false; response_revision:5387; number_of_response:1; }","duration":"293.095967ms","start":"2026-04-20T14:28:37.825561Z","end":"2026-04-20T14:28:38.118657Z","steps":["trace[138924412] 'process raft request' (duration: 292.922204ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:38.118951Z","caller":"traceutil/trace.go:171","msg":"trace[1389982200] linearizableReadLoop","detail":"{readStateIndex:5592; appliedIndex:5588; }","duration":"257.439157ms","start":"2026-04-20T14:28:37.861501Z","end":"2026-04-20T14:28:38.11894Z","steps":["trace[1389982200] 'read index received' (duration: 47.573488ms)","trace[1389982200] 'applied index is now lower than readState.Index' (duration: 209.865089ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:38.118942Z","caller":"traceutil/trace.go:171","msg":"trace[1100736554] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"292.870434ms","start":"2026-04-20T14:28:37.826043Z","end":"2026-04-20T14:28:38.118914Z","steps":["trace[1100736554] 'process raft request' (duration: 292.561829ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:38.119015Z","caller":"traceutil/trace.go:171","msg":"trace[474026498] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"292.955136ms","start":"2026-04-20T14:28:37.826034Z","end":"2026-04-20T14:28:38.11899Z","steps":["trace[474026498] 'process raft request' (duration: 292.859634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:38.119378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.849453ms","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-04-20T14:28:38.119457Z","caller":"traceutil/trace.go:171","msg":"trace[1686881207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5389; }","duration":"257.990016ms","start":"2026-04-20T14:28:37.86145Z","end":"2026-04-20T14:28:38.11944Z","steps":["trace[1686881207] 'agreement among raft nodes before linearized reading' (duration: 257.578369ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:38.438565Z","caller":"traceutil/trace.go:171","msg":"trace[1661719711] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"314.788744ms","start":"2026-04-20T14:28:38.123751Z","end":"2026-04-20T14:28:38.43854Z","steps":["trace[1661719711] 'process raft request' (duration: 314.732553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:38.438867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.242189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T14:28:38.43892Z","caller":"traceutil/trace.go:171","msg":"trace[1183554616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5391; }","duration":"577.352601ms","start":"2026-04-20T14:28:37.861556Z","end":"2026-04-20T14:28:38.438909Z","steps":["trace[1183554616] 'agreement among raft nodes before linearized reading' (duration: 577.147068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:38.438875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:38.123732Z","time spent":"314.984697ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:28:38.439073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.87846ms","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-04-20T14:28:38.439106Z","caller":"traceutil/trace.go:171","msg":"trace[1841542888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5391; }","duration":"385.944652ms","start":"2026-04-20T14:28:38.053154Z","end":"2026-04-20T14:28:38.439098Z","steps":["trace[1841542888] 'agreement among raft nodes before linearized reading' (duration: 385.85853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:38.439131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:38.053135Z","time spent":"385.991052ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T14:28:38.438639Z","caller":"traceutil/trace.go:171","msg":"trace[1716789819] linearizableReadLoop","detail":"{readStateIndex:5593; appliedIndex:5592; }","duration":"319.63121ms","start":"2026-04-20T14:28:38.11898Z","end":"2026-04-20T14:28:38.438611Z","steps":["trace[1716789819] 'read index received' (duration: 239.235517ms)","trace[1716789819] 'applied index is now lower than readState.Index' (duration: 80.393253ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:38.438956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:37.861535Z","time spent":"577.413311ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T14:28:38.438993Z","caller":"traceutil/trace.go:171","msg":"trace[1740939121] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"352.291673ms","start":"2026-04-20T14:28:38.08669Z","end":"2026-04-20T14:28:38.438982Z","steps":["trace[1740939121] 'process raft request' (duration: 271.510764ms)","trace[1740939121] 'compare' (duration: 80.123219ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:38.439345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:38.086667Z","time spent":"352.645958ms","remote":"127.0.0.1:49036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5361,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:28:38.439479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.632899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:38.439551Z","caller":"traceutil/trace.go:171","msg":"trace[667215140] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:5391; }","duration":"562.841293ms","start":"2026-04-20T14:28:37.876697Z","end":"2026-04-20T14:28:38.439539Z","steps":["trace[667215140] 'agreement among raft nodes before linearized reading' (duration: 562.587299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:38.439642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.494535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.119\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T14:28:38.43969Z","caller":"traceutil/trace.go:171","msg":"trace[236192805] range","detail":"{range_begin:/registry/masterleases/199.19.213.119; range_end:; response_count:1; response_revision:5391; }","duration":"117.589607ms","start":"2026-04-20T14:28:38.322088Z","end":"2026-04-20T14:28:38.439678Z","steps":["trace[236192805] 'agreement among raft nodes before linearized reading' (duration: 117.424374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:38.439743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:37.876675Z","time spent":"562.988544ms","remote":"127.0.0.1:49174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:38.439827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.200133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T14:28:38.440024Z","caller":"traceutil/trace.go:171","msg":"trace[800706165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5391; }","duration":"578.449018ms","start":"2026-04-20T14:28:37.861556Z","end":"2026-04-20T14:28:38.440005Z","steps":["trace[800706165] 'agreement among raft nodes before linearized reading' (duration: 578.095602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:38.440078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:37.861535Z","time spent":"578.526999ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T14:28:38.439821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.604961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T14:28:38.440178Z","caller":"traceutil/trace.go:171","msg":"trace[1198090714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5391; }","duration":"386.997988ms","start":"2026-04-20T14:28:38.053153Z","end":"2026-04-20T14:28:38.440151Z","steps":["trace[1198090714] 'agreement among raft nodes before linearized reading' (duration: 386.49408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:38.440251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:38.053139Z","time spent":"387.094509ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T14:28:38.62608Z","caller":"traceutil/trace.go:171","msg":"trace[1326262771] linearizableReadLoop","detail":"{readStateIndex:5601; appliedIndex:5595; }","duration":"104.712806ms","start":"2026-04-20T14:28:38.521344Z","end":"2026-04-20T14:28:38.626057Z","steps":["trace[1326262771] 'read index received' (duration: 16.386128ms)","trace[1326262771] 'applied index is now lower than readState.Index' (duration: 88.325778ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:38.626385Z","caller":"traceutil/trace.go:171","msg":"trace[834286214] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"147.307984ms","start":"2026-04-20T14:28:38.479008Z","end":"2026-04-20T14:28:38.626316Z","steps":["trace[834286214] 'process raft request' (duration: 70.578078ms)","trace[834286214] 'compare' (duration: 76.162437ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:38.62645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.087851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:38.626492Z","caller":"traceutil/trace.go:171","msg":"trace[575005915] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5397; }","duration":"105.183352ms","start":"2026-04-20T14:28:38.521299Z","end":"2026-04-20T14:28:38.626482Z","steps":["trace[575005915] 'agreement among raft nodes before linearized reading' (duration: 105.01905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:38.638931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.825164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6256"} {"level":"info","ts":"2026-04-20T14:28:38.638994Z","caller":"traceutil/trace.go:171","msg":"trace[1261162340] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5400; }","duration":"116.927067ms","start":"2026-04-20T14:28:38.522052Z","end":"2026-04-20T14:28:38.638979Z","steps":["trace[1261162340] 'agreement among raft nodes before linearized reading' (duration: 116.751304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:38.638985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.546897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:38.639041Z","caller":"traceutil/trace.go:171","msg":"trace[855823845] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5400; }","duration":"117.664538ms","start":"2026-04-20T14:28:38.521363Z","end":"2026-04-20T14:28:38.639027Z","steps":["trace[855823845] 'agreement among raft nodes before linearized reading' (duration: 117.581316ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:39.314463Z","caller":"traceutil/trace.go:171","msg":"trace[1165785813] linearizableReadLoop","detail":"{readStateIndex:5609; appliedIndex:5607; }","duration":"101.295068ms","start":"2026-04-20T14:28:39.21314Z","end":"2026-04-20T14:28:39.314435Z","steps":["trace[1165785813] 'read index received' (duration: 42.568577ms)","trace[1165785813] 'applied index is now lower than readState.Index' (duration: 58.725001ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:39.314826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.666263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-20T14:28:39.315058Z","caller":"traceutil/trace.go:171","msg":"trace[1141171058] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"112.148498ms","start":"2026-04-20T14:28:39.202867Z","end":"2026-04-20T14:28:39.315015Z","steps":["trace[1141171058] 'process raft request' (duration: 52.831908ms)","trace[1141171058] 'compare' (duration: 58.461116ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:39.314904Z","caller":"traceutil/trace.go:171","msg":"trace[310636518] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5405; }","duration":"101.797777ms","start":"2026-04-20T14:28:39.21309Z","end":"2026-04-20T14:28:39.314888Z","steps":["trace[310636518] 'agreement among raft nodes before linearized reading' (duration: 101.479091ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:39.315071Z","caller":"traceutil/trace.go:171","msg":"trace[1769681098] transaction","detail":"{read_only:false; response_revision:5405; number_of_response:1; }","duration":"107.037849ms","start":"2026-04-20T14:28:39.208005Z","end":"2026-04-20T14:28:39.315043Z","steps":["trace[1769681098] 'process raft request' (duration: 106.347997ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:39.981982Z","caller":"traceutil/trace.go:171","msg":"trace[2069373938] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"183.087132ms","start":"2026-04-20T14:28:39.798863Z","end":"2026-04-20T14:28:39.98195Z","steps":["trace[2069373938] 'process raft request' (duration: 157.746264ms)","trace[2069373938] 'compare' (duration: 25.186335ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:40.295332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.511059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:28:40.295495Z","caller":"traceutil/trace.go:171","msg":"trace[14667443] linearizableReadLoop","detail":"{readStateIndex:5617; appliedIndex:5616; }","duration":"201.586205ms","start":"2026-04-20T14:28:40.093893Z","end":"2026-04-20T14:28:40.295479Z","steps":["trace[14667443] 'read index received' (duration: 83.856212ms)","trace[14667443] 'applied index is now lower than readState.Index' (duration: 117.728373ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:40.295589Z","caller":"traceutil/trace.go:171","msg":"trace[1710626960] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"209.267346ms","start":"2026-04-20T14:28:40.086282Z","end":"2026-04-20T14:28:40.295549Z","steps":["trace[1710626960] 'process raft request' (duration: 91.457252ms)","trace[1710626960] 'compare' (duration: 117.397517ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:40.295653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.733537ms","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-04-20T14:28:40.295722Z","caller":"traceutil/trace.go:171","msg":"trace[347733215] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5413; }","duration":"201.84427ms","start":"2026-04-20T14:28:40.093863Z","end":"2026-04-20T14:28:40.295707Z","steps":["trace[347733215] 'agreement among raft nodes before linearized reading' (duration: 201.729628ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:40.32954Z","caller":"traceutil/trace.go:171","msg":"trace[744892438] transaction","detail":"{read_only:false; response_revision:5415; number_of_response:1; }","duration":"203.931232ms","start":"2026-04-20T14:28:40.125589Z","end":"2026-04-20T14:28:40.32952Z","steps":["trace[744892438] 'process raft request' (duration: 203.81922ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:40.329782Z","caller":"traceutil/trace.go:171","msg":"trace[119014462] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"204.233687ms","start":"2026-04-20T14:28:40.125528Z","end":"2026-04-20T14:28:40.329761Z","steps":["trace[119014462] 'process raft request' (duration: 203.7806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:40.332244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.415337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:1147"} {"level":"info","ts":"2026-04-20T14:28:40.332393Z","caller":"traceutil/trace.go:171","msg":"trace[1801477015] range","detail":"{range_begin:/registry/services/endpoints/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:5416; }","duration":"223.622861ms","start":"2026-04-20T14:28:40.108716Z","end":"2026-04-20T14:28:40.332339Z","steps":["trace[1801477015] 'agreement among raft nodes before linearized reading' (duration: 221.812352ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:40.332769Z","caller":"traceutil/trace.go:171","msg":"trace[1883491281] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"204.985038ms","start":"2026-04-20T14:28:40.125714Z","end":"2026-04-20T14:28:40.330699Z","steps":["trace[1883491281] 'process raft request' (duration: 203.743499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:40.54639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.189228ms","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-04-20T14:28:40.546443Z","caller":"traceutil/trace.go:171","msg":"trace[964179553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5417; }","duration":"100.28445ms","start":"2026-04-20T14:28:40.446148Z","end":"2026-04-20T14:28:40.546433Z","steps":["trace[964179553] 'range keys from in-memory index tree' (duration: 100.091247ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:40.694221Z","caller":"traceutil/trace.go:171","msg":"trace[1464831459] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"143.74084ms","start":"2026-04-20T14:28:40.550449Z","end":"2026-04-20T14:28:40.69419Z","steps":["trace[1464831459] 'process raft request' (duration: 96.334247ms)","trace[1464831459] 'compare' (duration: 47.194049ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:40.694228Z","caller":"traceutil/trace.go:171","msg":"trace[201158354] transaction","detail":"{read_only:false; response_revision:5420; number_of_response:1; }","duration":"143.75612ms","start":"2026-04-20T14:28:40.550451Z","end":"2026-04-20T14:28:40.694207Z","steps":["trace[201158354] 'process raft request' (duration: 143.669809ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:40.694573Z","caller":"traceutil/trace.go:171","msg":"trace[1188074384] transaction","detail":"{read_only:false; response_revision:5421; number_of_response:1; }","duration":"142.942778ms","start":"2026-04-20T14:28:40.551618Z","end":"2026-04-20T14:28:40.694561Z","steps":["trace[1188074384] 'process raft request' (duration: 142.559142ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:40.694586Z","caller":"traceutil/trace.go:171","msg":"trace[602584126] transaction","detail":"{read_only:false; response_revision:5422; number_of_response:1; }","duration":"142.810185ms","start":"2026-04-20T14:28:40.551759Z","end":"2026-04-20T14:28:40.694569Z","steps":["trace[602584126] 'process raft request' (duration: 142.583952ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:40.69458Z","caller":"traceutil/trace.go:171","msg":"trace[899932146] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"141.109559ms","start":"2026-04-20T14:28:40.553455Z","end":"2026-04-20T14:28:40.694564Z","steps":["trace[899932146] 'process raft request' (duration: 140.991688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:42.795426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.117653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T14:28:42.795551Z","caller":"traceutil/trace.go:171","msg":"trace[208871353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5427; }","duration":"225.287575ms","start":"2026-04-20T14:28:42.570248Z","end":"2026-04-20T14:28:42.795535Z","steps":["trace[208871353] 'range keys from in-memory index tree' (duration: 224.705716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:43.11165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.144561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:28:43.111854Z","caller":"traceutil/trace.go:171","msg":"trace[1184652807] linearizableReadLoop","detail":"{readStateIndex:5636; appliedIndex:5632; }","duration":"143.639038ms","start":"2026-04-20T14:28:42.968203Z","end":"2026-04-20T14:28:43.111842Z","steps":["trace[1184652807] 'read index received' (duration: 39.4µs)","trace[1184652807] 'applied index is now lower than readState.Index' (duration: 143.598948ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:43.111935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.73499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:43.111955Z","caller":"traceutil/trace.go:171","msg":"trace[782137156] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5432; }","duration":"143.793431ms","start":"2026-04-20T14:28:42.968153Z","end":"2026-04-20T14:28:43.111947Z","steps":["trace[782137156] 'agreement among raft nodes before linearized reading' (duration: 143.7282ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:43.112104Z","caller":"traceutil/trace.go:171","msg":"trace[1889274068] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"312.88692ms","start":"2026-04-20T14:28:42.799209Z","end":"2026-04-20T14:28:43.112096Z","steps":["trace[1889274068] 'process raft request' (duration: 312.528985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:43.112151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:42.799146Z","time spent":"312.979272ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:28:43.112225Z","caller":"traceutil/trace.go:171","msg":"trace[1526078856] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"312.771829ms","start":"2026-04-20T14:28:42.799425Z","end":"2026-04-20T14:28:43.112197Z","steps":["trace[1526078856] 'process raft request' (duration: 312.385393ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:43.112313Z","caller":"traceutil/trace.go:171","msg":"trace[372168857] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"313.135344ms","start":"2026-04-20T14:28:42.799166Z","end":"2026-04-20T14:28:43.112301Z","steps":["trace[372168857] 'process raft request' (duration: 119.271832ms)","trace[372168857] 'compare' (duration: 193.05905ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:43.112327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:42.799418Z","time spent":"312.86295ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:28:43.112353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:42.799144Z","time spent":"313.186185ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:28:43.112334Z","caller":"traceutil/trace.go:171","msg":"trace[633870045] transaction","detail":"{read_only:false; response_revision:5431; number_of_response:1; }","duration":"313.070803ms","start":"2026-04-20T14:28:42.799224Z","end":"2026-04-20T14:28:43.112294Z","steps":["trace[633870045] 'process raft request' (duration: 312.562026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:43.112494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:42.799214Z","time spent":"313.205195ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:28:43.724021Z","caller":"traceutil/trace.go:171","msg":"trace[908034428] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"153.681753ms","start":"2026-04-20T14:28:43.5703Z","end":"2026-04-20T14:28:43.723981Z","steps":["trace[908034428] 'process raft request' (duration: 114.88769ms)","trace[908034428] 'compare' (duration: 38.59373ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:43.921419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.783071ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T14:28:43.921806Z","caller":"traceutil/trace.go:171","msg":"trace[1488517043] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"111.235343ms","start":"2026-04-20T14:28:43.810548Z","end":"2026-04-20T14:28:43.921784Z","steps":["trace[1488517043] 'process raft request' (duration: 110.966399ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:49.715834Z","caller":"traceutil/trace.go:171","msg":"trace[2083381401] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"104.165348ms","start":"2026-04-20T14:28:49.611648Z","end":"2026-04-20T14:28:49.715814Z","steps":["trace[2083381401] 'process raft request' (duration: 104.004826ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:55.307424Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2927} {"level":"info","ts":"2026-04-20T14:28:55.377632Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2927,"took":"68.770611ms","hash":2891585683,"current-db-size-bytes":53219328,"current-db-size":"53 MB","current-db-size-in-use-bytes":28504064,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-20T14:28:55.377723Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2891585683,"revision":2927,"compact-revision":-1} {"level":"info","ts":"2026-04-20T14:29:30.796561Z","caller":"traceutil/trace.go:171","msg":"trace[1509023387] transaction","detail":"{read_only:false; response_revision:5783; number_of_response:1; }","duration":"224.840457ms","start":"2026-04-20T14:29:30.571693Z","end":"2026-04-20T14:29:30.796534Z","steps":["trace[1509023387] 'process raft request' (duration: 224.705665ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:29:30.801052Z","caller":"traceutil/trace.go:171","msg":"trace[2094315485] linearizableReadLoop","detail":"{readStateIndex:6000; appliedIndex:5997; }","duration":"135.39158ms","start":"2026-04-20T14:29:30.665646Z","end":"2026-04-20T14:29:30.801037Z","steps":["trace[2094315485] 'read index received' (duration: 130.861223ms)","trace[2094315485] 'applied index is now lower than readState.Index' (duration: 4.529717ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:29:30.801313Z","caller":"traceutil/trace.go:171","msg":"trace[1513670661] transaction","detail":"{read_only:false; response_revision:5784; number_of_response:1; }","duration":"199.276574ms","start":"2026-04-20T14:29:30.601798Z","end":"2026-04-20T14:29:30.801075Z","steps":["trace[1513670661] 'process raft request' (duration: 199.118312ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:29:30.801443Z","caller":"traceutil/trace.go:171","msg":"trace[1317548381] transaction","detail":"{read_only:false; response_revision:5785; number_of_response:1; }","duration":"197.450807ms","start":"2026-04-20T14:29:30.603934Z","end":"2026-04-20T14:29:30.801384Z","steps":["trace[1317548381] 'process raft request' (duration: 197.073611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:29:30.80162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.923607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:29:30.801701Z","caller":"traceutil/trace.go:171","msg":"trace[2069610355] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:5785; }","duration":"136.080579ms","start":"2026-04-20T14:29:30.665606Z","end":"2026-04-20T14:29:30.801687Z","steps":["trace[2069610355] 'agreement among raft nodes before linearized reading' (duration: 135.690414ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:34.261877Z","caller":"traceutil/trace.go:171","msg":"trace[931890399] linearizableReadLoop","detail":"{readStateIndex:6344; appliedIndex:6343; }","duration":"472.993407ms","start":"2026-04-20T14:30:33.788866Z","end":"2026-04-20T14:30:34.261859Z","steps":["trace[931890399] 'read index received' (duration: 472.568591ms)","trace[931890399] 'applied index is now lower than readState.Index' (duration: 424.176µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:30:34.261997Z","caller":"traceutil/trace.go:171","msg":"trace[731796633] transaction","detail":"{read_only:false; response_revision:6115; number_of_response:1; }","duration":"476.89185ms","start":"2026-04-20T14:30:33.785095Z","end":"2026-04-20T14:30:34.261986Z","steps":["trace[731796633] 'process raft request' (duration: 476.409773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:30:34.262121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:30:33.785075Z","time spent":"476.942041ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:30:34.262333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.466383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T14:30:34.262361Z","caller":"traceutil/trace.go:171","msg":"trace[181047319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6115; }","duration":"473.510813ms","start":"2026-04-20T14:30:33.788843Z","end":"2026-04-20T14:30:34.262353Z","steps":["trace[181047319] 'agreement among raft nodes before linearized reading' (duration: 473.422522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:30:34.262378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:30:33.788834Z","time spent":"473.540474ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T14:30:34.26246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.54189ms","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-04-20T14:30:34.262475Z","caller":"traceutil/trace.go:171","msg":"trace[1957872373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6115; }","duration":"472.57041ms","start":"2026-04-20T14:30:33.7899Z","end":"2026-04-20T14:30:34.26247Z","steps":["trace[1957872373] 'agreement among raft nodes before linearized reading' (duration: 472.524439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:30:34.262489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:30:33.78988Z","time spent":"472.60542ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:30:34.262568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.283514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:30:34.262589Z","caller":"traceutil/trace.go:171","msg":"trace[585465211] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:6115; }","duration":"252.304045ms","start":"2026-04-20T14:30:34.010277Z","end":"2026-04-20T14:30:34.262581Z","steps":["trace[585465211] 'agreement among raft nodes before linearized reading' (duration: 252.267874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:30:34.262731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.322102ms","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-04-20T14:30:34.262754Z","caller":"traceutil/trace.go:171","msg":"trace[179346814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6115; }","duration":"468.359603ms","start":"2026-04-20T14:30:33.794389Z","end":"2026-04-20T14:30:34.262748Z","steps":["trace[179346814] 'agreement among raft nodes before linearized reading' (duration: 468.298772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:30:34.262793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:30:33.794382Z","time spent":"468.405214ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T14:30:52.600077Z","caller":"traceutil/trace.go:171","msg":"trace[704120532] transaction","detail":"{read_only:false; response_revision:6230; number_of_response:1; }","duration":"118.099556ms","start":"2026-04-20T14:30:52.481953Z","end":"2026-04-20T14:30:52.600053Z","steps":["trace[704120532] 'process raft request' (duration: 118.064646ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:52.600222Z","caller":"traceutil/trace.go:171","msg":"trace[865345488] transaction","detail":"{read_only:false; response_revision:6227; number_of_response:1; }","duration":"157.064507ms","start":"2026-04-20T14:30:52.443095Z","end":"2026-04-20T14:30:52.60016Z","steps":["trace[865345488] 'process raft request' (duration: 146.893368ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:52.600302Z","caller":"traceutil/trace.go:171","msg":"trace[808875416] transaction","detail":"{read_only:false; response_revision:6228; number_of_response:1; }","duration":"156.894484ms","start":"2026-04-20T14:30:52.443397Z","end":"2026-04-20T14:30:52.600291Z","steps":["trace[808875416] 'process raft request' (duration: 156.540409ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:52.600509Z","caller":"traceutil/trace.go:171","msg":"trace[769207694] transaction","detail":"{read_only:false; response_revision:6229; number_of_response:1; }","duration":"156.959994ms","start":"2026-04-20T14:30:52.44341Z","end":"2026-04-20T14:30:52.60037Z","steps":["trace[769207694] 'process raft request' (duration: 156.578918ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:31:14.653015Z","caller":"traceutil/trace.go:171","msg":"trace[2134149133] transaction","detail":"{read_only:false; response_revision:6385; number_of_response:1; }","duration":"101.281089ms","start":"2026-04-20T14:31:14.5517Z","end":"2026-04-20T14:31:14.652981Z","steps":["trace[2134149133] 'process raft request' (duration: 100.803533ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:31:34.965797Z","caller":"traceutil/trace.go:171","msg":"trace[1334433093] transaction","detail":"{read_only:false; response_revision:6609; number_of_response:1; }","duration":"117.901856ms","start":"2026-04-20T14:31:34.847871Z","end":"2026-04-20T14:31:34.965773Z","steps":["trace[1334433093] 'process raft request' (duration: 117.779034ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:31:41.440215Z","caller":"traceutil/trace.go:171","msg":"trace[277191680] transaction","detail":"{read_only:false; response_revision:6651; number_of_response:1; }","duration":"115.202295ms","start":"2026-04-20T14:31:41.324981Z","end":"2026-04-20T14:31:41.440184Z","steps":["trace[277191680] 'process raft request' (duration: 29.674223ms)","trace[277191680] 'compare' (duration: 85.197208ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:31:50.088979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.140021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:31:50.089174Z","caller":"traceutil/trace.go:171","msg":"trace[749354180] transaction","detail":"{read_only:false; response_revision:6739; number_of_response:1; }","duration":"197.768968ms","start":"2026-04-20T14:31:49.891359Z","end":"2026-04-20T14:31:50.089128Z","steps":["trace[749354180] 'process raft request' (duration: 66.259594ms)","trace[749354180] 'compare' (duration: 130.903347ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:31:50.089514Z","caller":"traceutil/trace.go:171","msg":"trace[1877317579] transaction","detail":"{read_only:false; response_revision:6740; number_of_response:1; }","duration":"193.652544ms","start":"2026-04-20T14:31:49.895841Z","end":"2026-04-20T14:31:50.089493Z","steps":["trace[1877317579] 'process raft request' (duration: 193.250459ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:06.242211Z","caller":"traceutil/trace.go:171","msg":"trace[350356200] transaction","detail":"{read_only:false; response_revision:6833; number_of_response:1; }","duration":"149.254546ms","start":"2026-04-20T14:32:06.092927Z","end":"2026-04-20T14:32:06.242181Z","steps":["trace[350356200] 'process raft request' (duration: 148.04328ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:55.31279Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5526} {"level":"info","ts":"2026-04-20T14:33:55.41157Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5526,"took":"97.208422ms","hash":980260330,"current-db-size-bytes":53219328,"current-db-size":"53 MB","current-db-size-in-use-bytes":18415616,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-20T14:33:55.411705Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":980260330,"revision":5526,"compact-revision":2927}