{"level":"warn","ts":"2026-04-24T01:25:19.850734Z","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-24T01:25:19.850844Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-24T01:25:19.850924Z","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-24T01:25:19.850931Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-24T01:25:19.850949Z","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-24T01:25:19.851754Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-04-24T01:25:19.851867Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-24T01:25:19.857141Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.945979ms"} {"level":"info","ts":"2026-04-24T01:25:19.871194Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-04-24T01:25:19.871303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-04-24T01:25:19.871352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-04-24T01:25:19.871362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-24T01:25:19.871372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-04-24T01:25:19.871466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-04-24T01:25:19.878942Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-24T01:25:19.881259Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-24T01:25:19.882292Z","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-24T01:25:19.884633Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-24T01:25:19.885233Z","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-24T01:25:19.88544Z","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-24T01:25:19.885278Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-24T01:25:19.885626Z","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-24T01:25:19.88749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-04-24T01:25:19.887831Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-24T01:25:19.891706Z","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-24T01:25:19.891937Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-24T01:25:19.89197Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-24T01:25:19.892364Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-24T01:25:19.892437Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-24T01:25:20.273661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-04-24T01:25:20.273737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-04-24T01:25:20.273782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-04-24T01:25:20.273805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-04-24T01:25:20.273816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-24T01:25:20.27384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-04-24T01:25:20.273855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-24T01:25:20.274732Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-24T01:25:20.274962Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-24T01:25:20.275124Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-24T01:25:20.275566Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T01:25:20.277494Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-04-24T01:25:20.277859Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-24T01:25:20.277889Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-24T01:25:20.278253Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T01:25:20.278464Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T01:25:20.278617Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T01:25:20.280507Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-24T01:25:51.133101Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367216709895027,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T01:25:51.633952Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367216709895027,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T01:25:52.134312Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367216709895027,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T01:25:52.635263Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367216709895027,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T01:25:53.019728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000606483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-24T01:25:53.01988Z","caller":"traceutil/trace.go:171","msg":"trace[1969833520] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000841057s","start":"2026-04-24T01:25:51.019011Z","end":"2026-04-24T01:25:53.019852Z","steps":["trace[1969833520] 'agreement among raft nodes before linearized reading' (duration: 2.000651643s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:53.019956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:51.018988Z","time spent":"2.000953829s","remote":"127.0.0.1:50274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/24 01:25:53 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-24T01:25:53.136064Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367216709895027,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T01:25:53.636885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367216709895027,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T01:25:53.640124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.362256968s","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-24T01:25:53.640378Z","caller":"traceutil/trace.go:171","msg":"trace[1074739068] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:547; }","duration":"2.362516542s","start":"2026-04-24T01:25:51.277823Z","end":"2026-04-24T01:25:53.64034Z","steps":["trace[1074739068] 'range keys from in-memory index tree' (duration: 2.362226577s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:53.700243Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.14862117s","expected-duration":"1s"} {"level":"info","ts":"2026-04-24T01:25:53.700735Z","caller":"traceutil/trace.go:171","msg":"trace[1384950057] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"3.149615929s","start":"2026-04-24T01:25:50.551085Z","end":"2026-04-24T01:25:53.7007Z","steps":["trace[1384950057] 'process raft request' (duration: 3.149417396s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:53.701397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:50.551061Z","time spent":"3.149747031s","remote":"127.0.0.1:50332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:25:54.072023Z","caller":"traceutil/trace.go:171","msg":"trace[670692500] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"3.504271907s","start":"2026-04-24T01:25:50.567706Z","end":"2026-04-24T01:25:54.071978Z","steps":["trace[670692500] 'process raft request' (duration: 3.503704667s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:54.072268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.439210107s","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-24T01:25:54.072314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:50.567672Z","time spent":"3.504496962s","remote":"127.0.0.1:50332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:25:54.072371Z","caller":"traceutil/trace.go:171","msg":"trace[872374282] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.549849607s","start":"2026-04-24T01:25:52.522512Z","end":"2026-04-24T01:25:54.072361Z","steps":["trace[872374282] 'process raft request' (duration: 1.54944841s)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:54.07243Z","caller":"traceutil/trace.go:171","msg":"trace[164968616] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"3.446568079s","start":"2026-04-24T01:25:50.625823Z","end":"2026-04-24T01:25:54.072391Z","steps":["trace[164968616] 'process raft request' (duration: 3.445993969s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:54.072518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:52.522472Z","time spent":"1.549934849s","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:25:54.072068Z","caller":"traceutil/trace.go:171","msg":"trace[1436545955] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:568; }","duration":"3.439016734s","start":"2026-04-24T01:25:50.633003Z","end":"2026-04-24T01:25:54.07202Z","steps":["trace[1436545955] 'read index received' (duration: 3.067684045s)","trace[1436545955] 'applied index is now lower than readState.Index' (duration: 371.331299ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:25:54.07233Z","caller":"traceutil/trace.go:171","msg":"trace[538833330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"3.439344479s","start":"2026-04-24T01:25:50.632974Z","end":"2026-04-24T01:25:54.072319Z","steps":["trace[538833330] 'agreement among raft nodes before linearized reading' (duration: 3.439163266s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:54.072566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:50.625795Z","time spent":"3.446715963s","remote":"127.0.0.1:50332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T01:25:54.072666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:50.632966Z","time spent":"3.439645814s","remote":"127.0.0.1:50524","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-24T01:25:54.16263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14243306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T01:25:54.162665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.18712432s","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-24T01:25:54.162733Z","caller":"traceutil/trace.go:171","msg":"trace[1249756556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"1.142601383s","start":"2026-04-24T01:25:53.020107Z","end":"2026-04-24T01:25:54.162708Z","steps":["trace[1249756556] 'agreement among raft nodes before linearized reading' (duration: 1.14240894s)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:54.162752Z","caller":"traceutil/trace.go:171","msg":"trace[2020282847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:552; }","duration":"2.187283293s","start":"2026-04-24T01:25:51.975448Z","end":"2026-04-24T01:25:54.162731Z","steps":["trace[2020282847] 'agreement among raft nodes before linearized reading' (duration: 2.187021869s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:54.162745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.481827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-24T01:25:54.162797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:53.020082Z","time spent":"1.142705554s","remote":"127.0.0.1:50266","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-24T01:25:54.162835Z","caller":"traceutil/trace.go:171","msg":"trace[2070586606] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:552; }","duration":"826.627709ms","start":"2026-04-24T01:25:53.336186Z","end":"2026-04-24T01:25:54.162814Z","steps":["trace[2070586606] 'agreement among raft nodes before linearized reading' (duration: 826.422645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:54.162834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.93253835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-24T01:25:54.163024Z","caller":"traceutil/trace.go:171","msg":"trace[384373007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"1.932738744s","start":"2026-04-24T01:25:52.230208Z","end":"2026-04-24T01:25:54.162946Z","steps":["trace[384373007] 'agreement among raft nodes before linearized reading' (duration: 1.932421799s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:54.162996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.715547078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T01:25:54.163014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.155481201s","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-24T01:25:54.163124Z","caller":"traceutil/trace.go:171","msg":"trace[125664748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"1.715792213s","start":"2026-04-24T01:25:52.447296Z","end":"2026-04-24T01:25:54.163088Z","steps":["trace[125664748] 'agreement among raft nodes before linearized reading' (duration: 1.715459917s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:54.163132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:51.975422Z","time spent":"2.1876978s","remote":"127.0.0.1:50524","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-24T01:25:54.163193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:52.447277Z","time spent":"1.715904604s","remote":"127.0.0.1:50274","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-24T01:25:54.163092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:52.230179Z","time spent":"1.932900617s","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-24T01:25:54.163132Z","caller":"traceutil/trace.go:171","msg":"trace[1341887918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:552; }","duration":"2.155660045s","start":"2026-04-24T01:25:52.007448Z","end":"2026-04-24T01:25:54.163108Z","steps":["trace[1341887918] 'agreement among raft nodes before linearized reading' (duration: 2.155252188s)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:54.162595Z","caller":"traceutil/trace.go:171","msg":"trace[493847174] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"446.257128ms","start":"2026-04-24T01:25:53.716301Z","end":"2026-04-24T01:25:54.162559Z","steps":["trace[493847174] 'process raft request' (duration: 445.980743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:54.163604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:53.716272Z","time spent":"447.234435ms","remote":"127.0.0.1:50332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:25:54.162904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:53.336169Z","time spent":"826.724781ms","remote":"127.0.0.1:50310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-04-24T01:25:54.163381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:52.007432Z","time spent":"2.155928029s","remote":"127.0.0.1:50524","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-24T01:25:58.22901Z","caller":"traceutil/trace.go:171","msg":"trace[206578615] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"105.881266ms","start":"2026-04-24T01:25:58.123095Z","end":"2026-04-24T01:25:58.228976Z","steps":["trace[206578615] 'read index received' (duration: 105.571211ms)","trace[206578615] 'applied index is now lower than readState.Index' (duration: 308.805µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:25:58.229369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.240982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-04-24T01:25:58.230664Z","caller":"traceutil/trace.go:171","msg":"trace[568689154] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:602; }","duration":"107.545926ms","start":"2026-04-24T01:25:58.123056Z","end":"2026-04-24T01:25:58.230602Z","steps":["trace[568689154] 'agreement among raft nodes before linearized reading' (duration: 106.067519ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:58.231019Z","caller":"traceutil/trace.go:171","msg":"trace[879065827] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"175.140841ms","start":"2026-04-24T01:25:58.053942Z","end":"2026-04-24T01:25:58.229083Z","steps":["trace[879065827] 'process raft request' (duration: 174.836596ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:58.458541Z","caller":"traceutil/trace.go:171","msg":"trace[1789716179] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"221.279072ms","start":"2026-04-24T01:25:58.237215Z","end":"2026-04-24T01:25:58.458494Z","steps":["trace[1789716179] 'process raft request' (duration: 205.841135ms)","trace[1789716179] 'compare' (duration: 15.141702ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:25:58.75207Z","caller":"traceutil/trace.go:171","msg":"trace[436022357] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"514.543109ms","start":"2026-04-24T01:25:58.237502Z","end":"2026-04-24T01:25:58.752045Z","steps":["trace[436022357] 'process raft request' (duration: 514.418846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:58.752239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:58.237464Z","time spent":"514.68802ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:25:58.752049Z","caller":"traceutil/trace.go:171","msg":"trace[302355169] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"514.616659ms","start":"2026-04-24T01:25:58.237395Z","end":"2026-04-24T01:25:58.752011Z","steps":["trace[302355169] 'process raft request' (duration: 514.377145ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:58.752641Z","caller":"traceutil/trace.go:171","msg":"trace[1968962723] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"513.779613ms","start":"2026-04-24T01:25:58.238773Z","end":"2026-04-24T01:25:58.752552Z","steps":["trace[1968962723] 'process raft request' (duration: 513.226394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:58.752897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:58.237361Z","time spent":"515.317652ms","remote":"127.0.0.1:50524","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-24T01:25:58.753284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:58.238758Z","time spent":"514.041058ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:25:58.767129Z","caller":"traceutil/trace.go:171","msg":"trace[2004776107] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:623; }","duration":"519.598728ms","start":"2026-04-24T01:25:58.247482Z","end":"2026-04-24T01:25:58.76708Z","steps":["trace[2004776107] 'read index received' (duration: 195.580529ms)","trace[2004776107] 'applied index is now lower than readState.Index' (duration: 324.016179ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:25:58.767414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.175749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:25:58.767472Z","caller":"traceutil/trace.go:171","msg":"trace[398908044] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:606; }","duration":"385.269911ms","start":"2026-04-24T01:25:58.382186Z","end":"2026-04-24T01:25:58.767455Z","steps":["trace[398908044] 'agreement among raft nodes before linearized reading' (duration: 385.1732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:58.767509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:58.382166Z","time spent":"385.330842ms","remote":"127.0.0.1:35384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-24T01:25:58.767871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.57959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:25:58.767898Z","caller":"traceutil/trace.go:171","msg":"trace[487088595] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:606; }","duration":"220.62511ms","start":"2026-04-24T01:25:58.547264Z","end":"2026-04-24T01:25:58.767889Z","steps":["trace[487088595] 'agreement among raft nodes before linearized reading' (duration: 220.579019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:58.767901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.904127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:25:58.767949Z","caller":"traceutil/trace.go:171","msg":"trace[1844802413] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:606; }","duration":"219.977129ms","start":"2026-04-24T01:25:58.54796Z","end":"2026-04-24T01:25:58.767937Z","steps":["trace[1844802413] 'agreement among raft nodes before linearized reading' (duration: 219.880947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:58.768031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.850054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:25:58.768062Z","caller":"traceutil/trace.go:171","msg":"trace[365342985] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:606; }","duration":"220.907195ms","start":"2026-04-24T01:25:58.547143Z","end":"2026-04-24T01:25:58.768051Z","steps":["trace[365342985] 'agreement among raft nodes before linearized reading' (duration: 220.862154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:58.768111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.661481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T01:25:58.768177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.796334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T01:25:58.767426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.280254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:25:58.768214Z","caller":"traceutil/trace.go:171","msg":"trace[956649633] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:606; }","duration":"145.758122ms","start":"2026-04-24T01:25:58.622376Z","end":"2026-04-24T01:25:58.768134Z","steps":["trace[956649633] 'agreement among raft nodes before linearized reading' (duration: 145.711372ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:58.768252Z","caller":"traceutil/trace.go:171","msg":"trace[979308495] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:606; }","duration":"106.1444ms","start":"2026-04-24T01:25:58.662083Z","end":"2026-04-24T01:25:58.768227Z","steps":["trace[979308495] 'agreement among raft nodes before linearized reading' (duration: 105.286014ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:58.768203Z","caller":"traceutil/trace.go:171","msg":"trace[1928290575] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:606; }","duration":"235.871494ms","start":"2026-04-24T01:25:58.532324Z","end":"2026-04-24T01:25:58.768196Z","steps":["trace[1928290575] 'agreement among raft nodes before linearized reading' (duration: 235.832004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:58.768456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.212779ms","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-24T01:25:58.768483Z","caller":"traceutil/trace.go:171","msg":"trace[2067493569] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:606; }","duration":"386.247399ms","start":"2026-04-24T01:25:58.382228Z","end":"2026-04-24T01:25:58.768476Z","steps":["trace[2067493569] 'agreement among raft nodes before linearized reading' (duration: 386.206559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:58.768507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:58.382225Z","time spent":"386.27566ms","remote":"127.0.0.1:35384","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-24T01:25:58.768575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.948494ms","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-24T01:25:58.768598Z","caller":"traceutil/trace.go:171","msg":"trace[1326161917] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:606; }","duration":"237.009425ms","start":"2026-04-24T01:25:58.531581Z","end":"2026-04-24T01:25:58.768591Z","steps":["trace[1326161917] 'agreement among raft nodes before linearized reading' (duration: 236.974035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:58.768726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.519264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:25:58.768748Z","caller":"traceutil/trace.go:171","msg":"trace[1736652386] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:606; }","duration":"237.573935ms","start":"2026-04-24T01:25:58.531168Z","end":"2026-04-24T01:25:58.768742Z","steps":["trace[1736652386] 'agreement among raft nodes before linearized reading' (duration: 237.536304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:58.768917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.562125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:25:58.768944Z","caller":"traceutil/trace.go:171","msg":"trace[1040742203] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:606; }","duration":"197.626077ms","start":"2026-04-24T01:25:58.571309Z","end":"2026-04-24T01:25:58.768935Z","steps":["trace[1040742203] 'agreement among raft nodes before linearized reading' (duration: 197.582596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:58.768953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.536074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:25:58.769006Z","caller":"traceutil/trace.go:171","msg":"trace[1314438901] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:606; }","duration":"197.642587ms","start":"2026-04-24T01:25:58.57135Z","end":"2026-04-24T01:25:58.768993Z","steps":["trace[1314438901] 'agreement among raft nodes before linearized reading' (duration: 197.540765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:58.769015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.722529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:25:58.769092Z","caller":"traceutil/trace.go:171","msg":"trace[2078343006] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:606; }","duration":"146.861922ms","start":"2026-04-24T01:25:58.622213Z","end":"2026-04-24T01:25:58.769074Z","steps":["trace[2078343006] 'agreement among raft nodes before linearized reading' (duration: 146.75095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:58.769084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.258459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:25:58.769325Z","caller":"traceutil/trace.go:171","msg":"trace[1116875812] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:606; }","duration":"197.485723ms","start":"2026-04-24T01:25:58.571789Z","end":"2026-04-24T01:25:58.769275Z","steps":["trace[1116875812] 'agreement among raft nodes before linearized reading' (duration: 197.215768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:58.769391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.224768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T01:25:58.769431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.646918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:25:58.769462Z","caller":"traceutil/trace.go:171","msg":"trace[1076906570] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:606; }","duration":"107.327701ms","start":"2026-04-24T01:25:58.662125Z","end":"2026-04-24T01:25:58.769452Z","steps":["trace[1076906570] 'agreement among raft nodes before linearized reading' (duration: 107.235849ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:58.769463Z","caller":"traceutil/trace.go:171","msg":"trace[287866820] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:606; }","duration":"106.68412ms","start":"2026-04-24T01:25:58.66277Z","end":"2026-04-24T01:25:58.769454Z","steps":["trace[287866820] 'agreement among raft nodes before linearized reading' (duration: 106.631279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:58.770122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.641503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:208485"} {"level":"info","ts":"2026-04-24T01:25:58.77016Z","caller":"traceutil/trace.go:171","msg":"trace[1677744729] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:606; }","duration":"522.710354ms","start":"2026-04-24T01:25:58.24744Z","end":"2026-04-24T01:25:58.77015Z","steps":["trace[1677744729] 'agreement among raft nodes before linearized reading' (duration: 519.853883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:58.77018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:58.247428Z","time spent":"522.746994ms","remote":"127.0.0.1:50332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":208508,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-04-24T01:26:14.680051Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367216709895541,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-24T01:26:14.811085Z","caller":"traceutil/trace.go:171","msg":"trace[745062252] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"640.017795ms","start":"2026-04-24T01:26:14.171033Z","end":"2026-04-24T01:26:14.811051Z","steps":["trace[745062252] 'process raft request' (duration: 639.828908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:14.811461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:14.171012Z","time spent":"640.262604ms","remote":"127.0.0.1:50450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:26:14.818586Z","caller":"traceutil/trace.go:171","msg":"trace[919541994] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:809; }","duration":"638.962435ms","start":"2026-04-24T01:26:14.17961Z","end":"2026-04-24T01:26:14.818572Z","steps":["trace[919541994] 'read index received' (duration: 631.238159ms)","trace[919541994] 'applied index is now lower than readState.Index' (duration: 7.723446ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:26:14.818825Z","caller":"traceutil/trace.go:171","msg":"trace[512338399] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"542.894149ms","start":"2026-04-24T01:26:14.275922Z","end":"2026-04-24T01:26:14.818816Z","steps":["trace[512338399] 'process raft request' (duration: 542.586948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:14.818895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:14.275886Z","time spent":"542.965811ms","remote":"127.0.0.1:50524","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-24T01:26:14.819154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.549277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-24T01:26:14.81919Z","caller":"traceutil/trace.go:171","msg":"trace[491405373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:785; }","duration":"639.635711ms","start":"2026-04-24T01:26:14.179545Z","end":"2026-04-24T01:26:14.819181Z","steps":["trace[491405373] 'agreement among raft nodes before linearized reading' (duration: 639.493035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:14.819218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:14.179515Z","time spent":"639.695373ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-24T01:26:14.819376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.494769ms","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-24T01:26:14.819408Z","caller":"traceutil/trace.go:171","msg":"trace[466152061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:785; }","duration":"285.578013ms","start":"2026-04-24T01:26:14.533823Z","end":"2026-04-24T01:26:14.819401Z","steps":["trace[466152061] 'agreement among raft nodes before linearized reading' (duration: 285.483059ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:45.488141Z","caller":"traceutil/trace.go:171","msg":"trace[1852666992] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"488.641789ms","start":"2026-04-24T01:26:44.999466Z","end":"2026-04-24T01:26:45.488108Z","steps":["trace[1852666992] 'process raft request' (duration: 488.400742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:45.488354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:44.999449Z","time spent":"488.794433ms","remote":"127.0.0.1:50524","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-24T01:26:45.806568Z","caller":"traceutil/trace.go:171","msg":"trace[2017699993] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"806.373412ms","start":"2026-04-24T01:26:45.000164Z","end":"2026-04-24T01:26:45.806537Z","steps":["trace[2017699993] 'process raft request' (duration: 806.121334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:45.806757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:45.000139Z","time spent":"806.517737ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:26:45.807017Z","caller":"traceutil/trace.go:171","msg":"trace[1678988988] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"805.402462ms","start":"2026-04-24T01:26:45.001574Z","end":"2026-04-24T01:26:45.806976Z","steps":["trace[1678988988] 'process raft request' (duration: 804.908507ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:45.807066Z","caller":"traceutil/trace.go:171","msg":"trace[1521372412] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1218; }","duration":"804.917977ms","start":"2026-04-24T01:26:45.002114Z","end":"2026-04-24T01:26:45.807032Z","steps":["trace[1521372412] 'read index received' (duration: 485.76751ms)","trace[1521372412] 'applied index is now lower than readState.Index' (duration: 319.148877ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:26:45.807207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:45.001544Z","time spent":"805.560717ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:26:45.807231Z","caller":"traceutil/trace.go:171","msg":"trace[1313094065] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"804.610128ms","start":"2026-04-24T01:26:45.002606Z","end":"2026-04-24T01:26:45.807216Z","steps":["trace[1313094065] 'process raft request' (duration: 804.172454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:45.8073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:45.002593Z","time spent":"804.672769ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:26:45.807469Z","caller":"traceutil/trace.go:171","msg":"trace[381103596] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"353.988464ms","start":"2026-04-24T01:26:45.453463Z","end":"2026-04-24T01:26:45.807452Z","steps":["trace[381103596] 'process raft request' (duration: 353.460428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:45.807539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:45.453437Z","time spent":"354.066406ms","remote":"127.0.0.1:50524","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-24T01:26:45.807566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.398081ms","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-24T01:26:45.80764Z","caller":"traceutil/trace.go:171","msg":"trace[2057562040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1189; }","duration":"805.536945ms","start":"2026-04-24T01:26:45.002065Z","end":"2026-04-24T01:26:45.807602Z","steps":["trace[2057562040] 'agreement among raft nodes before linearized reading' (duration: 805.086182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:45.807688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:45.002054Z","time spent":"805.621568ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-24T01:26:59.824889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.55446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:26:59.825038Z","caller":"traceutil/trace.go:171","msg":"trace[1293602773] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1292; }","duration":"194.708174ms","start":"2026-04-24T01:26:59.630262Z","end":"2026-04-24T01:26:59.82497Z","steps":["trace[1293602773] 'count revisions from in-memory index tree' (duration: 194.464747ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:59.861897Z","caller":"traceutil/trace.go:171","msg":"trace[782067550] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"219.40011ms","start":"2026-04-24T01:26:59.642482Z","end":"2026-04-24T01:26:59.861882Z","steps":["trace[782067550] 'process raft request' (duration: 219.278277ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:27:07.755234Z","caller":"traceutil/trace.go:171","msg":"trace[916903063] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"132.6566ms","start":"2026-04-24T01:27:07.622553Z","end":"2026-04-24T01:27:07.75521Z","steps":["trace[916903063] 'process raft request' (duration: 132.505876ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:27:10.194172Z","caller":"traceutil/trace.go:171","msg":"trace[1295200794] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"430.246355ms","start":"2026-04-24T01:27:09.763904Z","end":"2026-04-24T01:27:10.19415Z","steps":["trace[1295200794] 'process raft request' (duration: 430.116511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:10.194313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:09.763888Z","time spent":"430.352127ms","remote":"127.0.0.1:50524","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-24T01:27:10.354617Z","caller":"traceutil/trace.go:171","msg":"trace[1204483931] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"427.537973ms","start":"2026-04-24T01:27:09.927042Z","end":"2026-04-24T01:27:10.35458Z","steps":["trace[1204483931] 'process raft request' (duration: 333.087242ms)","trace[1204483931] 'compare' (duration: 94.260696ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:27:10.354731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.316262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:27:10.354842Z","caller":"traceutil/trace.go:171","msg":"trace[606970740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1453; }","duration":"336.469917ms","start":"2026-04-24T01:27:10.01834Z","end":"2026-04-24T01:27:10.35481Z","steps":["trace[606970740] 'agreement among raft nodes before linearized reading' (duration: 336.321513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:10.354873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:09.927028Z","time spent":"427.683947ms","remote":"127.0.0.1:50524","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-24T01:27:10.354891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:10.018319Z","time spent":"336.562889ms","remote":"127.0.0.1:50274","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-24T01:27:10.354622Z","caller":"traceutil/trace.go:171","msg":"trace[1291138559] linearizableReadLoop","detail":"{readStateIndex:1499; appliedIndex:1498; }","duration":"336.148107ms","start":"2026-04-24T01:27:10.0184Z","end":"2026-04-24T01:27:10.354548Z","steps":["trace[1291138559] 'read index received' (duration: 175.757114ms)","trace[1291138559] 'applied index is now lower than readState.Index' (duration: 160.389483ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:27:10.355756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.270621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-24T01:27:10.355882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.251691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-24T01:27:10.355959Z","caller":"traceutil/trace.go:171","msg":"trace[2140876708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1453; }","duration":"321.391604ms","start":"2026-04-24T01:27:10.034554Z","end":"2026-04-24T01:27:10.355946Z","steps":["trace[2140876708] 'agreement among raft nodes before linearized reading' (duration: 321.170968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:10.356027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:10.034544Z","time spent":"321.472857ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-24T01:27:10.35591Z","caller":"traceutil/trace.go:171","msg":"trace[338582480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1453; }","duration":"328.446186ms","start":"2026-04-24T01:27:10.027447Z","end":"2026-04-24T01:27:10.355893Z","steps":["trace[338582480] 'agreement among raft nodes before linearized reading' (duration: 328.19963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:10.356377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.27621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-24T01:27:10.356428Z","caller":"traceutil/trace.go:171","msg":"trace[584942429] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1453; }","duration":"325.371773ms","start":"2026-04-24T01:27:10.031046Z","end":"2026-04-24T01:27:10.356418Z","steps":["trace[584942429] 'agreement among raft nodes before linearized reading' (duration: 325.247639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:10.35636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.875672ms","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-24T01:27:10.356461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:10.031024Z","time spent":"325.430304ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-24T01:27:10.356604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:10.027436Z","time spent":"328.97597ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-24T01:27:10.356616Z","caller":"traceutil/trace.go:171","msg":"trace[815068116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1453; }","duration":"324.022386ms","start":"2026-04-24T01:27:10.032447Z","end":"2026-04-24T01:27:10.35647Z","steps":["trace[815068116] 'agreement among raft nodes before linearized reading' (duration: 323.835341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:10.356837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:10.032437Z","time spent":"324.368845ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-24T01:27:34.638194Z","caller":"traceutil/trace.go:171","msg":"trace[390110029] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"127.752747ms","start":"2026-04-24T01:27:34.510427Z","end":"2026-04-24T01:27:34.638179Z","steps":["trace[390110029] 'read index received' (duration: 127.623434ms)","trace[390110029] 'applied index is now lower than readState.Index' (duration: 128.733µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:27:34.638247Z","caller":"traceutil/trace.go:171","msg":"trace[1431196052] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"207.267768ms","start":"2026-04-24T01:27:34.430955Z","end":"2026-04-24T01:27:34.638222Z","steps":["trace[1431196052] 'process raft request' (duration: 207.122155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:34.638465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.88013ms","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-24T01:27:34.638439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.992683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-24T01:27:34.638493Z","caller":"traceutil/trace.go:171","msg":"trace[677483900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1865; }","duration":"102.936842ms","start":"2026-04-24T01:27:34.535548Z","end":"2026-04-24T01:27:34.638485Z","steps":["trace[677483900] 'agreement among raft nodes before linearized reading' (duration: 102.834979ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:27:34.638515Z","caller":"traceutil/trace.go:171","msg":"trace[1378300688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1865; }","duration":"128.133606ms","start":"2026-04-24T01:27:34.510366Z","end":"2026-04-24T01:27:34.6385Z","steps":["trace[1378300688] 'agreement among raft nodes before linearized reading' (duration: 127.88064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:34.638589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.741871ms","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-24T01:27:34.638608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.590099ms","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-24T01:27:34.638615Z","caller":"traceutil/trace.go:171","msg":"trace[136190207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1865; }","duration":"103.788473ms","start":"2026-04-24T01:27:34.534819Z","end":"2026-04-24T01:27:34.638608Z","steps":["trace[136190207] 'agreement among raft nodes before linearized reading' (duration: 103.722461ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:27:34.638627Z","caller":"traceutil/trace.go:171","msg":"trace[1517676812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1865; }","duration":"113.6514ms","start":"2026-04-24T01:27:34.52497Z","end":"2026-04-24T01:27:34.638621Z","steps":["trace[1517676812] 'agreement among raft nodes before linearized reading' (duration: 113.319982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:35.180706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367216709897648,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-24T01:27:35.398357Z","caller":"traceutil/trace.go:171","msg":"trace[1218811833] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"756.241939ms","start":"2026-04-24T01:27:34.64209Z","end":"2026-04-24T01:27:35.398332Z","steps":["trace[1218811833] 'process raft request' (duration: 717.483626ms)","trace[1218811833] 'compare' (duration: 38.567499ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:27:35.398955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:34.642076Z","time spent":"756.347702ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:27:35.525337Z","caller":"traceutil/trace.go:171","msg":"trace[2127202148] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"881.274767ms","start":"2026-04-24T01:27:34.644042Z","end":"2026-04-24T01:27:35.525317Z","steps":["trace[2127202148] 'process raft request' (duration: 881.200646ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:27:35.52536Z","caller":"traceutil/trace.go:171","msg":"trace[28495884] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"881.804331ms","start":"2026-04-24T01:27:34.643514Z","end":"2026-04-24T01:27:35.525318Z","steps":["trace[28495884] 'process raft request' (duration: 870.132957ms)","trace[28495884] 'compare' (duration: 11.479169ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:27:35.52545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:34.644022Z","time spent":"881.37036ms","remote":"127.0.0.1:50524","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-24T01:27:35.525557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:34.643507Z","time spent":"881.939684ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:27:35.525571Z","caller":"traceutil/trace.go:171","msg":"trace[426598575] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1938; }","duration":"845.302882ms","start":"2026-04-24T01:27:34.680229Z","end":"2026-04-24T01:27:35.525532Z","steps":["trace[426598575] 'read index received' (duration: 679.360536ms)","trace[426598575] 'applied index is now lower than readState.Index' (duration: 165.940966ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:27:35.525757Z","caller":"traceutil/trace.go:171","msg":"trace[705653480] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"881.619466ms","start":"2026-04-24T01:27:34.644122Z","end":"2026-04-24T01:27:35.525742Z","steps":["trace[705653480] 'process raft request' (duration: 881.155905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:35.525807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.093391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:27:35.525981Z","caller":"traceutil/trace.go:171","msg":"trace[91608155] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1869; }","duration":"672.277416ms","start":"2026-04-24T01:27:34.853656Z","end":"2026-04-24T01:27:35.525934Z","steps":["trace[91608155] 'agreement among raft nodes before linearized reading' (duration: 672.090791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:35.526064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.474694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T01:27:35.526082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.415397ms","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-24T01:27:35.526115Z","caller":"traceutil/trace.go:171","msg":"trace[498028692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1869; }","duration":"508.557756ms","start":"2026-04-24T01:27:35.017544Z","end":"2026-04-24T01:27:35.526102Z","steps":["trace[498028692] 'agreement among raft nodes before linearized reading' (duration: 508.457593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:35.525856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:34.644116Z","time spent":"881.685507ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:27:35.526135Z","caller":"traceutil/trace.go:171","msg":"trace[1172931992] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1869; }","duration":"507.486558ms","start":"2026-04-24T01:27:35.018633Z","end":"2026-04-24T01:27:35.526119Z","steps":["trace[1172931992] 'agreement among raft nodes before linearized reading' (duration: 507.329164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:35.526155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:35.017531Z","time spent":"508.617487ms","remote":"127.0.0.1:50266","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-24T01:27:35.526177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:35.018618Z","time spent":"507.54864ms","remote":"127.0.0.1:47134","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-24T01:27:35.526081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:34.853639Z","time spent":"672.42095ms","remote":"127.0.0.1:35420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-24T01:27:35.526609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.387409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f982dbef-564a-42f3-896f-c9e956c59e8b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-24T01:27:35.526651Z","caller":"traceutil/trace.go:171","msg":"trace[993724812] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f982dbef-564a-42f3-896f-c9e956c59e8b; range_end:; response_count:1; response_revision:1869; }","duration":"846.44387ms","start":"2026-04-24T01:27:34.680197Z","end":"2026-04-24T01:27:35.526641Z","steps":["trace[993724812] 'agreement among raft nodes before linearized reading' (duration: 846.286066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:35.526705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:34.680123Z","time spent":"846.575234ms","remote":"127.0.0.1:50450","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-f982dbef-564a-42f3-896f-c9e956c59e8b\" "} {"level":"info","ts":"2026-04-24T01:27:35.687526Z","caller":"traceutil/trace.go:171","msg":"trace[2025259447] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"154.656542ms","start":"2026-04-24T01:27:35.532849Z","end":"2026-04-24T01:27:35.687505Z","steps":["trace[2025259447] 'process raft request' (duration: 154.20322ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:27:35.871507Z","caller":"traceutil/trace.go:171","msg":"trace[150778211] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"168.690414ms","start":"2026-04-24T01:27:35.702785Z","end":"2026-04-24T01:27:35.871475Z","steps":["trace[150778211] 'process raft request' (duration: 117.465058ms)","trace[150778211] 'compare' (duration: 51.125214ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:27:36.570263Z","caller":"traceutil/trace.go:171","msg":"trace[1388453237] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"692.224245ms","start":"2026-04-24T01:27:35.878009Z","end":"2026-04-24T01:27:36.570234Z","steps":["trace[1388453237] 'process raft request' (duration: 639.67555ms)","trace[1388453237] 'compare' (duration: 52.437973ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:27:36.570429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:35.877982Z","time spent":"692.366069ms","remote":"127.0.0.1:50534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:27:36.749402Z","caller":"traceutil/trace.go:171","msg":"trace[1436743941] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"871.315967ms","start":"2026-04-24T01:27:35.878058Z","end":"2026-04-24T01:27:36.749374Z","steps":["trace[1436743941] 'process raft request' (duration: 871.009159ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:27:36.7495Z","caller":"traceutil/trace.go:171","msg":"trace[1500237173] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"871.283217ms","start":"2026-04-24T01:27:35.878124Z","end":"2026-04-24T01:27:36.749407Z","steps":["trace[1500237173] 'process raft request' (duration: 871.103792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:36.749578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:35.878007Z","time spent":"871.485263ms","remote":"127.0.0.1:50430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:27:36.749656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:35.878114Z","time spent":"871.451001ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:27:36.749704Z","caller":"traceutil/trace.go:171","msg":"trace[479164341] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"871.511852ms","start":"2026-04-24T01:27:35.878153Z","end":"2026-04-24T01:27:36.749665Z","steps":["trace[479164341] 'process raft request' (duration: 871.133023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:36.749846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:35.877982Z","time spent":"871.78549ms","remote":"127.0.0.1:50534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:27:36.749903Z","caller":"traceutil/trace.go:171","msg":"trace[490870287] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"869.532414ms","start":"2026-04-24T01:27:35.880342Z","end":"2026-04-24T01:27:36.749875Z","steps":["trace[490870287] 'process raft request' (duration: 868.9893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:36.750038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.170216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:27:36.750074Z","caller":"traceutil/trace.go:171","msg":"trace[1281831071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1879; }","duration":"730.260688ms","start":"2026-04-24T01:27:36.019804Z","end":"2026-04-24T01:27:36.750064Z","steps":["trace[1281831071] 'agreement among raft nodes before linearized reading' (duration: 730.151786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:36.75007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:35.880332Z","time spent":"869.637466ms","remote":"127.0.0.1:50430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:27:36.750103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:36.019775Z","time spent":"730.32125ms","remote":"127.0.0.1:50274","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-24T01:27:36.750118Z","caller":"traceutil/trace.go:171","msg":"trace[1721642832] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"818.317507ms","start":"2026-04-24T01:27:35.931787Z","end":"2026-04-24T01:27:36.750105Z","steps":["trace[1721642832] 'process raft request' (duration: 818.01291ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:27:36.749926Z","caller":"traceutil/trace.go:171","msg":"trace[261710450] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1944; }","duration":"730.036222ms","start":"2026-04-24T01:27:36.019871Z","end":"2026-04-24T01:27:36.749907Z","steps":["trace[261710450] 'read index received' (duration: 497.82631ms)","trace[261710450] 'applied index is now lower than readState.Index' (duration: 232.208682ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:27:36.750188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:35.931756Z","time spent":"818.393999ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:27:36.750183Z","caller":"traceutil/trace.go:171","msg":"trace[902705728] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"626.141122ms","start":"2026-04-24T01:27:36.124028Z","end":"2026-04-24T01:27:36.75017Z","steps":["trace[902705728] 'process raft request' (duration: 625.811814ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:27:36.75024Z","caller":"traceutil/trace.go:171","msg":"trace[680275781] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"573.439483ms","start":"2026-04-24T01:27:36.17679Z","end":"2026-04-24T01:27:36.750229Z","steps":["trace[680275781] 'process raft request' (duration: 573.081114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:36.750265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:36.123995Z","time spent":"626.233754ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:27:36.75029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:36.176766Z","time spent":"573.496424ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:27:36.776972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.778247ms","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-24T01:27:36.777044Z","caller":"traceutil/trace.go:171","msg":"trace[1893911040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1880; }","duration":"449.888049ms","start":"2026-04-24T01:27:36.327132Z","end":"2026-04-24T01:27:36.77702Z","steps":["trace[1893911040] 'agreement among raft nodes before linearized reading' (duration: 449.704285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:36.77703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.091443ms","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-24T01:27:36.777086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:36.327113Z","time spent":"449.959202ms","remote":"127.0.0.1:50524","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-24T01:27:36.777129Z","caller":"traceutil/trace.go:171","msg":"trace[1592083835] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1880; }","duration":"243.264927ms","start":"2026-04-24T01:27:36.533837Z","end":"2026-04-24T01:27:36.777102Z","steps":["trace[1592083835] 'agreement among raft nodes before linearized reading' (duration: 242.96479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:36.777346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.116317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-24T01:27:36.77741Z","caller":"traceutil/trace.go:171","msg":"trace[780173022] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1880; }","duration":"645.248311ms","start":"2026-04-24T01:27:36.132143Z","end":"2026-04-24T01:27:36.777392Z","steps":["trace[780173022] 'agreement among raft nodes before linearized reading' (duration: 645.067675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:36.777446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:36.132108Z","time spent":"645.329363ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":580,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-24T01:27:36.777413Z","caller":"traceutil/trace.go:171","msg":"trace[482921851] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"132.537508ms","start":"2026-04-24T01:27:36.64486Z","end":"2026-04-24T01:27:36.777398Z","steps":["trace[482921851] 'process raft request' (duration: 131.790968ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:27:49.008473Z","caller":"traceutil/trace.go:171","msg":"trace[686380292] linearizableReadLoop","detail":"{readStateIndex:2059; appliedIndex:2055; }","duration":"168.431763ms","start":"2026-04-24T01:27:48.840021Z","end":"2026-04-24T01:27:49.008452Z","steps":["trace[686380292] 'read index received' (duration: 21.48737ms)","trace[686380292] 'applied index is now lower than readState.Index' (duration: 146.943643ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:27:49.008604Z","caller":"traceutil/trace.go:171","msg":"trace[2119783132] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"186.752828ms","start":"2026-04-24T01:27:48.821839Z","end":"2026-04-24T01:27:49.008591Z","steps":["trace[2119783132] 'process raft request' (duration: 129.645283ms)","trace[2119783132] 'compare' (duration: 56.767856ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:27:49.008558Z","caller":"traceutil/trace.go:171","msg":"trace[1861376244] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"184.000881ms","start":"2026-04-24T01:27:48.824526Z","end":"2026-04-24T01:27:49.008527Z","steps":["trace[1861376244] 'process raft request' (duration: 183.888868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:49.008991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.354118ms","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-24T01:27:49.009062Z","caller":"traceutil/trace.go:171","msg":"trace[657192741] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1982; }","duration":"146.46189ms","start":"2026-04-24T01:27:48.862581Z","end":"2026-04-24T01:27:49.009043Z","steps":["trace[657192741] 'agreement among raft nodes before linearized reading' (duration: 146.309697ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:27:49.008528Z","caller":"traceutil/trace.go:171","msg":"trace[867098539] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"185.049056ms","start":"2026-04-24T01:27:48.82344Z","end":"2026-04-24T01:27:49.00849Z","steps":["trace[867098539] 'process raft request' (duration: 184.934063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:49.008798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.756272ms","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-24T01:27:49.009376Z","caller":"traceutil/trace.go:171","msg":"trace[1124592871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1982; }","duration":"169.345806ms","start":"2026-04-24T01:27:48.83997Z","end":"2026-04-24T01:27:49.009316Z","steps":["trace[1124592871] 'agreement among raft nodes before linearized reading' (duration: 168.643509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:49.518916Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367216709897882,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-24T01:27:49.677085Z","caller":"traceutil/trace.go:171","msg":"trace[898877126] linearizableReadLoop","detail":"{readStateIndex:2060; appliedIndex:2059; }","duration":"658.440056ms","start":"2026-04-24T01:27:49.018619Z","end":"2026-04-24T01:27:49.677059Z","steps":["trace[898877126] 'read index received' (duration: 646.033606ms)","trace[898877126] 'applied index is now lower than readState.Index' (duration: 12.40495ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:27:49.677144Z","caller":"traceutil/trace.go:171","msg":"trace[318619433] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"662.973096ms","start":"2026-04-24T01:27:49.014125Z","end":"2026-04-24T01:27:49.677098Z","steps":["trace[318619433] 'process raft request' (duration: 650.580146ms)","trace[318619433] 'compare' (duration: 11.896358ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:27:49.677388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:49.014095Z","time spent":"663.157329ms","remote":"127.0.0.1:50524","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-24T01:27:49.677402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.718293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:27:49.677542Z","caller":"traceutil/trace.go:171","msg":"trace[1732824712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1983; }","duration":"658.885586ms","start":"2026-04-24T01:27:49.018586Z","end":"2026-04-24T01:27:49.677471Z","steps":["trace[1732824712] 'agreement among raft nodes before linearized reading' (duration: 658.63155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:49.677657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:49.018573Z","time spent":"659.066671ms","remote":"127.0.0.1:50274","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-24T01:27:49.67791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.148516ms","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":"info","ts":"2026-04-24T01:27:49.677964Z","caller":"traceutil/trace.go:171","msg":"trace[1701943106] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1983; }","duration":"619.244358ms","start":"2026-04-24T01:27:49.058707Z","end":"2026-04-24T01:27:49.677951Z","steps":["trace[1701943106] 'agreement among raft nodes before linearized reading' (duration: 618.52957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:49.678002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:49.058696Z","time spent":"619.295969ms","remote":"127.0.0.1:35538","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":"warn","ts":"2026-04-24T01:27:49.678358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.964281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-24T01:27:49.678472Z","caller":"traceutil/trace.go:171","msg":"trace[160767608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1983; }","duration":"213.168036ms","start":"2026-04-24T01:27:49.465258Z","end":"2026-04-24T01:27:49.678426Z","steps":["trace[160767608] 'agreement among raft nodes before linearized reading' (duration: 212.709315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:53.541779Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367216709897941,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T01:27:54.042486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367216709897941,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T01:27:54.317451Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.2969746s","expected-duration":"1s"} {"level":"info","ts":"2026-04-24T01:27:54.318125Z","caller":"traceutil/trace.go:171","msg":"trace[827420018] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"1.297943413s","start":"2026-04-24T01:27:53.020142Z","end":"2026-04-24T01:27:54.318085Z","steps":["trace[827420018] 'process raft request' (duration: 1.297604665s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:54.318356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:53.020109Z","time spent":"1.298124107s","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:27:54.389692Z","caller":"traceutil/trace.go:171","msg":"trace[331711327] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"1.3659866s","start":"2026-04-24T01:27:53.023669Z","end":"2026-04-24T01:27:54.389656Z","steps":["trace[331711327] 'process raft request' (duration: 1.365710713s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:54.389912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:53.023646Z","time spent":"1.366122193s","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:27:54.389908Z","caller":"traceutil/trace.go:171","msg":"trace[1832515159] linearizableReadLoop","detail":"{readStateIndex:2092; appliedIndex:2089; }","duration":"1.348646214s","start":"2026-04-24T01:27:53.041218Z","end":"2026-04-24T01:27:54.389865Z","steps":["trace[1832515159] 'read index received' (duration: 1.276551561s)","trace[1832515159] 'applied index is now lower than readState.Index' (duration: 72.092773ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:27:54.390048Z","caller":"traceutil/trace.go:171","msg":"trace[1792878408] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"1.365530549s","start":"2026-04-24T01:27:53.024505Z","end":"2026-04-24T01:27:54.390035Z","steps":["trace[1792878408] 'process raft request' (duration: 1.365077719s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:54.390159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:53.024476Z","time spent":"1.365598531s","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:27:54.390276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349057505s","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-24T01:27:54.390332Z","caller":"traceutil/trace.go:171","msg":"trace[774438330] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2015; }","duration":"1.349133216s","start":"2026-04-24T01:27:53.041181Z","end":"2026-04-24T01:27:54.390314Z","steps":["trace[774438330] 'agreement among raft nodes before linearized reading' (duration: 1.348935881s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:54.390388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:53.041168Z","time spent":"1.349211568s","remote":"127.0.0.1:47134","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-24T01:27:54.390923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.594146ms","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-24T01:27:54.391018Z","caller":"traceutil/trace.go:171","msg":"trace[1740257988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2015; }","duration":"657.714459ms","start":"2026-04-24T01:27:53.733283Z","end":"2026-04-24T01:27:54.390998Z","steps":["trace[1740257988] 'agreement among raft nodes before linearized reading' (duration: 657.456223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:54.391035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.591802ms","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":"warn","ts":"2026-04-24T01:27:54.391066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:53.733278Z","time spent":"657.77849ms","remote":"127.0.0.1:50524","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-24T01:27:54.391084Z","caller":"traceutil/trace.go:171","msg":"trace[57703546] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2015; }","duration":"355.668193ms","start":"2026-04-24T01:27:54.035403Z","end":"2026-04-24T01:27:54.391071Z","steps":["trace[57703546] 'agreement among raft nodes before linearized reading' (duration: 355.593341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:54.391122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:54.035392Z","time spent":"355.721055ms","remote":"127.0.0.1:50646","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-24T01:27:54.390966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.965518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:27:54.391415Z","caller":"traceutil/trace.go:171","msg":"trace[402611149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2015; }","duration":"371.43029ms","start":"2026-04-24T01:27:54.019966Z","end":"2026-04-24T01:27:54.391397Z","steps":["trace[402611149] 'agreement among raft nodes before linearized reading' (duration: 370.962228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:54.391474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:54.019954Z","time spent":"371.503821ms","remote":"127.0.0.1:50274","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-24T01:27:54.391535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.385208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-24T01:27:54.39158Z","caller":"traceutil/trace.go:171","msg":"trace[1450138851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2015; }","duration":"659.45794ms","start":"2026-04-24T01:27:53.73211Z","end":"2026-04-24T01:27:54.391568Z","steps":["trace[1450138851] 'agreement among raft nodes before linearized reading' (duration: 659.333547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:54.39161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:53.732099Z","time spent":"659.504421ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-24T01:27:54.391841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.566699ms","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-24T01:27:54.391876Z","caller":"traceutil/trace.go:171","msg":"trace[469269077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2015; }","duration":"658.626591ms","start":"2026-04-24T01:27:53.73324Z","end":"2026-04-24T01:27:54.391867Z","steps":["trace[469269077] 'agreement among raft nodes before linearized reading' (duration: 658.512818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:54.391904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:53.73323Z","time spent":"658.668121ms","remote":"127.0.0.1:50524","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-24T01:27:54.39206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.773697ms","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-24T01:27:54.392104Z","caller":"traceutil/trace.go:171","msg":"trace[1655669072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2015; }","duration":"679.859909ms","start":"2026-04-24T01:27:53.712234Z","end":"2026-04-24T01:27:54.392094Z","steps":["trace[1655669072] 'agreement among raft nodes before linearized reading' (duration: 679.712885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:54.391419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.240822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T01:27:54.392162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:53.712209Z","time spent":"679.94579ms","remote":"127.0.0.1:50524","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-24T01:27:54.392328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.257357ms","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-24T01:27:54.392361Z","caller":"traceutil/trace.go:171","msg":"trace[1993563830] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2015; }","duration":"700.3524ms","start":"2026-04-24T01:27:53.692Z","end":"2026-04-24T01:27:54.392352Z","steps":["trace[1993563830] 'agreement among raft nodes before linearized reading' (duration: 700.268407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:54.392395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:53.69196Z","time spent":"700.429363ms","remote":"127.0.0.1:50524","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-24T01:27:54.392649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049252611s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-24T01:27:54.392707Z","caller":"traceutil/trace.go:171","msg":"trace[805340083] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:2015; }","duration":"1.049313932s","start":"2026-04-24T01:27:53.34338Z","end":"2026-04-24T01:27:54.392694Z","steps":["trace[805340083] 'agreement among raft nodes before linearized reading' (duration: 1.047324744s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:54.392743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:53.343305Z","time spent":"1.049428975s","remote":"127.0.0.1:50310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"info","ts":"2026-04-24T01:27:54.392848Z","caller":"traceutil/trace.go:171","msg":"trace[781036759] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2015; }","duration":"586.03407ms","start":"2026-04-24T01:27:53.806126Z","end":"2026-04-24T01:27:54.39216Z","steps":["trace[781036759] 'agreement among raft nodes before linearized reading' (duration: 585.235012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:27:54.393089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:27:53.806113Z","time spent":"586.905202ms","remote":"127.0.0.1:35544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-24T01:28:16.559747Z","caller":"traceutil/trace.go:171","msg":"trace[1692171042] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"134.336707ms","start":"2026-04-24T01:28:16.425386Z","end":"2026-04-24T01:28:16.559723Z","steps":["trace[1692171042] 'process raft request' (duration: 134.201853ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:16.7086Z","caller":"traceutil/trace.go:171","msg":"trace[28116749] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"229.033786ms","start":"2026-04-24T01:28:16.479551Z","end":"2026-04-24T01:28:16.708584Z","steps":["trace[28116749] 'process raft request' (duration: 228.880732ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:16.708802Z","caller":"traceutil/trace.go:171","msg":"trace[1677859470] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2218; }","duration":"192.799846ms","start":"2026-04-24T01:28:16.515973Z","end":"2026-04-24T01:28:16.708773Z","steps":["trace[1677859470] 'read index received' (duration: 43.626429ms)","trace[1677859470] 'applied index is now lower than readState.Index' (duration: 149.171827ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:28:16.709096Z","caller":"traceutil/trace.go:171","msg":"trace[384558446] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"228.287079ms","start":"2026-04-24T01:28:16.480792Z","end":"2026-04-24T01:28:16.709079Z","steps":["trace[384558446] 'process raft request' (duration: 227.747397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:16.709456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.634124ms","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-24T01:28:16.709509Z","caller":"traceutil/trace.go:171","msg":"trace[483381598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2139; }","duration":"172.716526ms","start":"2026-04-24T01:28:16.53678Z","end":"2026-04-24T01:28:16.709497Z","steps":["trace[483381598] 'agreement among raft nodes before linearized reading' (duration: 172.562783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:16.709674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.542471ms","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-24T01:28:16.709756Z","caller":"traceutil/trace.go:171","msg":"trace[244779904] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2139; }","duration":"161.654663ms","start":"2026-04-24T01:28:16.548078Z","end":"2026-04-24T01:28:16.709732Z","steps":["trace[244779904] 'agreement among raft nodes before linearized reading' (duration: 161.432898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:16.710037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.058144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-24T01:28:16.710092Z","caller":"traceutil/trace.go:171","msg":"trace[572372309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2139; }","duration":"194.214988ms","start":"2026-04-24T01:28:16.515865Z","end":"2026-04-24T01:28:16.71008Z","steps":["trace[572372309] 'agreement among raft nodes before linearized reading' (duration: 194.080765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:16.710125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.17057ms","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-24T01:28:16.710244Z","caller":"traceutil/trace.go:171","msg":"trace[543863390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2139; }","duration":"164.268701ms","start":"2026-04-24T01:28:16.545894Z","end":"2026-04-24T01:28:16.710163Z","steps":["trace[543863390] 'agreement among raft nodes before linearized reading' (duration: 164.125048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:16.7104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.062351ms","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-24T01:28:16.710463Z","caller":"traceutil/trace.go:171","msg":"trace[1043514255] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2139; }","duration":"145.148854ms","start":"2026-04-24T01:28:16.565298Z","end":"2026-04-24T01:28:16.710446Z","steps":["trace[1043514255] 'agreement among raft nodes before linearized reading' (duration: 144.98696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:16.710528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.812406ms","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-24T01:28:16.710593Z","caller":"traceutil/trace.go:171","msg":"trace[1733188375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2139; }","duration":"168.895148ms","start":"2026-04-24T01:28:16.541687Z","end":"2026-04-24T01:28:16.710582Z","steps":["trace[1733188375] 'agreement among raft nodes before linearized reading' (duration: 168.749535ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:17.353437Z","caller":"traceutil/trace.go:171","msg":"trace[1448745632] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"387.45608ms","start":"2026-04-24T01:28:16.965952Z","end":"2026-04-24T01:28:17.353408Z","steps":["trace[1448745632] 'process raft request' (duration: 387.278346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:17.353814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:16.96593Z","time spent":"387.577344ms","remote":"127.0.0.1:50524","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-24T01:28:18.468374Z","caller":"traceutil/trace.go:171","msg":"trace[1065884668] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2227; }","duration":"464.74032ms","start":"2026-04-24T01:28:18.003604Z","end":"2026-04-24T01:28:18.468344Z","steps":["trace[1065884668] 'read index received' (duration: 464.702559ms)","trace[1065884668] 'applied index is now lower than readState.Index' (duration: 36.441µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:28:18.468616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.991115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-04-24T01:28:18.468654Z","caller":"traceutil/trace.go:171","msg":"trace[666673551] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2146; }","duration":"465.065737ms","start":"2026-04-24T01:28:18.003578Z","end":"2026-04-24T01:28:18.468644Z","steps":["trace[666673551] 'agreement among raft nodes before linearized reading' (duration: 464.900394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:18.46869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:18.003565Z","time spent":"465.116228ms","remote":"127.0.0.1:50450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12406,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-24T01:28:18.468902Z","caller":"traceutil/trace.go:171","msg":"trace[625452813] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"485.31461ms","start":"2026-04-24T01:28:17.983511Z","end":"2026-04-24T01:28:18.468825Z","steps":["trace[625452813] 'process raft request' (duration: 484.691346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:18.470067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:17.983498Z","time spent":"486.315233ms","remote":"127.0.0.1:50354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:28:18.476351Z","caller":"traceutil/trace.go:171","msg":"trace[1411676960] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"420.655683ms","start":"2026-04-24T01:28:18.055669Z","end":"2026-04-24T01:28:18.476325Z","steps":["trace[1411676960] 'process raft request' (duration: 420.50184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:18.476383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.014863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:28:18.476389Z","caller":"traceutil/trace.go:171","msg":"trace[976060637] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"356.859048ms","start":"2026-04-24T01:28:18.119497Z","end":"2026-04-24T01:28:18.476356Z","steps":["trace[976060637] 'process raft request' (duration: 356.771246ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:18.476431Z","caller":"traceutil/trace.go:171","msg":"trace[60490791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2148; }","duration":"457.156767ms","start":"2026-04-24T01:28:18.01926Z","end":"2026-04-24T01:28:18.476417Z","steps":["trace[60490791] 'agreement among raft nodes before linearized reading' (duration: 457.049535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:18.476465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:18.055632Z","time spent":"420.762636ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:28:18.476467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:18.019224Z","time spent":"457.236608ms","remote":"127.0.0.1:50274","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-24T01:28:18.476584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:18.119453Z","time spent":"357.036352ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:28:18.731658Z","caller":"traceutil/trace.go:171","msg":"trace[1489585300] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"165.787305ms","start":"2026-04-24T01:28:18.565856Z","end":"2026-04-24T01:28:18.731644Z","steps":["trace[1489585300] 'process raft request' (duration: 165.661392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:19.535238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.152957ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-24T01:28:19.537265Z","caller":"traceutil/trace.go:171","msg":"trace[712479230] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"822.506657ms","start":"2026-04-24T01:28:18.714729Z","end":"2026-04-24T01:28:19.537235Z","steps":["trace[712479230] 'process raft request' (duration: 820.641625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:19.537445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:18.714706Z","time spent":"822.64593ms","remote":"127.0.0.1:50524","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-24T01:28:20.379573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.220012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:28:20.379876Z","caller":"traceutil/trace.go:171","msg":"trace[518965358] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"1.020744911s","start":"2026-04-24T01:28:19.359106Z","end":"2026-04-24T01:28:20.379851Z","steps":["trace[518965358] 'process raft request' (duration: 1.020615308s)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:20.37992Z","caller":"traceutil/trace.go:171","msg":"trace[290628844] linearizableReadLoop","detail":"{readStateIndex:2234; appliedIndex:2230; }","duration":"1.649547973s","start":"2026-04-24T01:28:18.730324Z","end":"2026-04-24T01:28:20.379872Z","steps":["trace[290628844] 'read index received' (duration: 1.250739ms)","trace[290628844] 'applied index is now lower than readState.Index' (duration: 1.648293004s)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:28:20.380021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:19.359082Z","time spent":"1.020901455s","remote":"127.0.0.1:50524","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-24T01:28:20.379993Z","caller":"traceutil/trace.go:171","msg":"trace[886929166] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"1.660706958s","start":"2026-04-24T01:28:18.71924Z","end":"2026-04-24T01:28:20.379947Z","steps":["trace[886929166] 'process raft request' (duration: 817.975844ms)","trace[886929166] 'compare' (duration: 841.936386ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:28:20.380204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.615654059s","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-24T01:28:20.380214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:18.719217Z","time spent":"1.660872222s","remote":"127.0.0.1:50524","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-24T01:28:20.380182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.622434294s","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-24T01:28:20.380255Z","caller":"traceutil/trace.go:171","msg":"trace[617324114] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2152; }","duration":"1.615730761s","start":"2026-04-24T01:28:18.76451Z","end":"2026-04-24T01:28:20.380241Z","steps":["trace[617324114] 'agreement among raft nodes before linearized reading' (duration: 1.615545336s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:20.380307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:18.764504Z","time spent":"1.615781902s","remote":"127.0.0.1:50524","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-24T01:28:20.380318Z","caller":"traceutil/trace.go:171","msg":"trace[1295071217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2152; }","duration":"1.622598698s","start":"2026-04-24T01:28:18.757672Z","end":"2026-04-24T01:28:20.380271Z","steps":["trace[1295071217] 'agreement among raft nodes before linearized reading' (duration: 1.622378933s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:20.380376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:18.757645Z","time spent":"1.62270489s","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-24T01:28:20.380317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.990401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T01:28:20.380568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.650241609s","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-24T01:28:20.38061Z","caller":"traceutil/trace.go:171","msg":"trace[1160167093] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2152; }","duration":"1.650296671s","start":"2026-04-24T01:28:18.730299Z","end":"2026-04-24T01:28:20.380596Z","steps":["trace[1160167093] 'agreement among raft nodes before linearized reading' (duration: 1.650190908s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:20.380638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:18.730292Z","time spent":"1.650339871s","remote":"127.0.0.1:47134","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-24T01:28:20.380701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.617721417s","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-24T01:28:20.380705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.617712777s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-24T01:28:20.380746Z","caller":"traceutil/trace.go:171","msg":"trace[1784875094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2152; }","duration":"1.617767229s","start":"2026-04-24T01:28:18.762965Z","end":"2026-04-24T01:28:20.380732Z","steps":["trace[1784875094] 'agreement among raft nodes before linearized reading' (duration: 1.617661695s)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:20.380756Z","caller":"traceutil/trace.go:171","msg":"trace[1651315119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2152; }","duration":"1.617803479s","start":"2026-04-24T01:28:18.762937Z","end":"2026-04-24T01:28:20.380741Z","steps":["trace[1651315119] 'agreement among raft nodes before linearized reading' (duration: 1.617623064s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:20.380777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:18.762928Z","time spent":"1.617841499s","remote":"127.0.0.1:50524","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-24T01:28:20.380794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:18.762913Z","time spent":"1.617873581s","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-24T01:28:20.380565Z","caller":"traceutil/trace.go:171","msg":"trace[1714138020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2152; }","duration":"360.404211ms","start":"2026-04-24T01:28:20.020142Z","end":"2026-04-24T01:28:20.380546Z","steps":["trace[1714138020] 'agreement among raft nodes before linearized reading' (duration: 360.006793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:20.381061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:20.020107Z","time spent":"360.934644ms","remote":"127.0.0.1:50274","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-24T01:28:20.380459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509071955s","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-24T01:28:20.381299Z","caller":"traceutil/trace.go:171","msg":"trace[1703166316] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2152; }","duration":"1.509985507s","start":"2026-04-24T01:28:18.871297Z","end":"2026-04-24T01:28:20.381282Z","steps":["trace[1703166316] 'agreement among raft nodes before linearized reading' (duration: 1.509124147s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:20.38095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.617950082s","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":"warn","ts":"2026-04-24T01:28:20.381353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:18.871274Z","time spent":"1.510063609s","remote":"127.0.0.1:50524","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":"info","ts":"2026-04-24T01:28:20.381381Z","caller":"traceutil/trace.go:171","msg":"trace[1073812292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2152; }","duration":"1.618384962s","start":"2026-04-24T01:28:18.762982Z","end":"2026-04-24T01:28:20.381367Z","steps":["trace[1073812292] 'agreement among raft nodes before linearized reading' (duration: 1.617896711s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:20.381423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:18.762977Z","time spent":"1.618432813s","remote":"127.0.0.1:50524","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-24T01:28:20.862057Z","caller":"traceutil/trace.go:171","msg":"trace[1850140760] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"476.024562ms","start":"2026-04-24T01:28:20.386004Z","end":"2026-04-24T01:28:20.862029Z","steps":["trace[1850140760] 'process raft request' (duration: 471.625273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:20.862441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:20.385992Z","time spent":"476.141055ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:28:21.794696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.995246ms","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-24T01:28:21.794988Z","caller":"traceutil/trace.go:171","msg":"trace[1822052793] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2153; }","duration":"517.290023ms","start":"2026-04-24T01:28:21.277674Z","end":"2026-04-24T01:28:21.794964Z","steps":["trace[1822052793] 'range keys from in-memory index tree' (duration: 516.981806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:21.795155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.212433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:28:21.795544Z","caller":"traceutil/trace.go:171","msg":"trace[541133684] linearizableReadLoop","detail":"{readStateIndex:2241; appliedIndex:2235; }","duration":"955.931156ms","start":"2026-04-24T01:28:20.839595Z","end":"2026-04-24T01:28:21.795526Z","steps":["trace[541133684] 'read index received' (duration: 17.9973ms)","trace[541133684] 'applied index is now lower than readState.Index' (duration: 937.932636ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:28:21.795684Z","caller":"traceutil/trace.go:171","msg":"trace[1630466920] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"1.409066887s","start":"2026-04-24T01:28:20.3866Z","end":"2026-04-24T01:28:21.795667Z","steps":["trace[1630466920] 'process raft request' (duration: 666.149467ms)","trace[1630466920] 'compare' (duration: 741.959088ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:28:21.795688Z","caller":"traceutil/trace.go:171","msg":"trace[109099868] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"1.409011166s","start":"2026-04-24T01:28:20.386657Z","end":"2026-04-24T01:28:21.795668Z","steps":["trace[109099868] 'process raft request' (duration: 1.408703599s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:21.795786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:20.386639Z","time spent":"1.409093788s","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:28:21.795864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:20.386591Z","time spent":"1.409135929s","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:28:21.795943Z","caller":"traceutil/trace.go:171","msg":"trace[169598253] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"1.05717444s","start":"2026-04-24T01:28:20.738735Z","end":"2026-04-24T01:28:21.79591Z","steps":["trace[169598253] 'process raft request' (duration: 1.0567325s)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:21.796021Z","caller":"traceutil/trace.go:171","msg":"trace[988635442] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"1.409370194s","start":"2026-04-24T01:28:20.386636Z","end":"2026-04-24T01:28:21.796006Z","steps":["trace[988635442] 'process raft request' (duration: 1.408609616s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:21.796083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:20.738701Z","time spent":"1.057310583s","remote":"127.0.0.1:50524","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-24T01:28:21.796109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:20.386609Z","time spent":"1.409455856s","remote":"127.0.0.1:50524","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-24T01:28:21.796123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.471898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T01:28:21.796139Z","caller":"traceutil/trace.go:171","msg":"trace[1798258093] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"1.408019773s","start":"2026-04-24T01:28:20.388095Z","end":"2026-04-24T01:28:21.796115Z","steps":["trace[1798258093] 'process raft request' (duration: 1.407314838s)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:21.796226Z","caller":"traceutil/trace.go:171","msg":"trace[49308122] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2158; }","duration":"956.582781ms","start":"2026-04-24T01:28:20.839587Z","end":"2026-04-24T01:28:21.796169Z","steps":["trace[49308122] 'agreement among raft nodes before linearized reading' (duration: 956.336095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:21.796256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:20.388087Z","time spent":"1.408118106s","remote":"127.0.0.1:50524","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-24T01:28:21.796329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:20.839503Z","time spent":"956.804966ms","remote":"127.0.0.1:50332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":54,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-04-24T01:28:21.797127Z","caller":"traceutil/trace.go:171","msg":"trace[453525410] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"254.431651ms","start":"2026-04-24T01:28:21.542681Z","end":"2026-04-24T01:28:21.797113Z","steps":["trace[453525410] 'process raft request' (duration: 254.352999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:21.797346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.969265ms","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-24T01:28:21.797569Z","caller":"traceutil/trace.go:171","msg":"trace[883324462] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2159; }","duration":"883.233311ms","start":"2026-04-24T01:28:20.914323Z","end":"2026-04-24T01:28:21.797556Z","steps":["trace[883324462] 'agreement among raft nodes before linearized reading' (duration: 882.965775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:21.797663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:20.914301Z","time spent":"883.346544ms","remote":"127.0.0.1:50358","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-24T01:28:21.798036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.257497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128090"} {"level":"info","ts":"2026-04-24T01:28:21.798101Z","caller":"traceutil/trace.go:171","msg":"trace[645254598] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2159; }","duration":"129.38154ms","start":"2026-04-24T01:28:21.668703Z","end":"2026-04-24T01:28:21.798085Z","steps":["trace[645254598] 'agreement among raft nodes before linearized reading' (duration: 129.132664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:21.79849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.887492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T01:28:21.79856Z","caller":"traceutil/trace.go:171","msg":"trace[1503261035] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2160; }","duration":"130.020435ms","start":"2026-04-24T01:28:21.668514Z","end":"2026-04-24T01:28:21.798534Z","steps":["trace[1503261035] 'agreement among raft nodes before linearized reading' (duration: 129.80156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:21.797602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.942729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-24T01:28:21.798929Z","caller":"traceutil/trace.go:171","msg":"trace[1674607002] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2159; }","duration":"130.279601ms","start":"2026-04-24T01:28:21.66863Z","end":"2026-04-24T01:28:21.79891Z","steps":["trace[1674607002] 'agreement among raft nodes before linearized reading' (duration: 128.91777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:21.799354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.855716ms","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-24T01:28:21.799445Z","caller":"traceutil/trace.go:171","msg":"trace[666933978] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2160; }","duration":"411.02225ms","start":"2026-04-24T01:28:21.38841Z","end":"2026-04-24T01:28:21.799433Z","steps":["trace[666933978] 'agreement among raft nodes before linearized reading' (duration: 410.817475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:21.799507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:21.388388Z","time spent":"411.107561ms","remote":"127.0.0.1:47134","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-24T01:28:21.799309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.410077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:28:21.800232Z","caller":"traceutil/trace.go:171","msg":"trace[453431051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2160; }","duration":"779.381478ms","start":"2026-04-24T01:28:21.020841Z","end":"2026-04-24T01:28:21.800223Z","steps":["trace[453431051] 'agreement among raft nodes before linearized reading' (duration: 778.428997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:21.800315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:21.020811Z","time spent":"779.49478ms","remote":"127.0.0.1:50274","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-24T01:28:55.195988Z","caller":"traceutil/trace.go:171","msg":"trace[175649840] linearizableReadLoop","detail":"{readStateIndex:2445; appliedIndex:2444; }","duration":"152.937412ms","start":"2026-04-24T01:28:55.043034Z","end":"2026-04-24T01:28:55.195972Z","steps":["trace[175649840] 'read index received' (duration: 152.803569ms)","trace[175649840] 'applied index is now lower than readState.Index' (duration: 133.483µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:28:55.196162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.119417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5428"} {"level":"info","ts":"2026-04-24T01:28:55.196189Z","caller":"traceutil/trace.go:171","msg":"trace[515207812] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2354; }","duration":"153.178059ms","start":"2026-04-24T01:28:55.043001Z","end":"2026-04-24T01:28:55.196179Z","steps":["trace[515207812] 'agreement among raft nodes before linearized reading' (duration: 153.047105ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:55.196132Z","caller":"traceutil/trace.go:171","msg":"trace[587508766] transaction","detail":"{read_only:false; response_revision:2354; number_of_response:1; }","duration":"163.258528ms","start":"2026-04-24T01:28:55.032791Z","end":"2026-04-24T01:28:55.19605Z","steps":["trace[587508766] 'process raft request' (duration: 163.085004ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:56.143095Z","caller":"traceutil/trace.go:171","msg":"trace[1792425615] transaction","detail":"{read_only:false; response_revision:2357; number_of_response:1; }","duration":"116.414745ms","start":"2026-04-24T01:28:56.026644Z","end":"2026-04-24T01:28:56.143059Z","steps":["trace[1792425615] 'process raft request' (duration: 29.477991ms)","trace[1792425615] 'compare' (duration: 86.760569ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:28:56.23071Z","caller":"traceutil/trace.go:171","msg":"trace[1748376271] transaction","detail":"{read_only:false; response_revision:2358; number_of_response:1; }","duration":"203.709275ms","start":"2026-04-24T01:28:56.02696Z","end":"2026-04-24T01:28:56.230669Z","steps":["trace[1748376271] 'process raft request' (duration: 202.722833ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:56.23123Z","caller":"traceutil/trace.go:171","msg":"trace[24291021] transaction","detail":"{read_only:false; response_revision:2359; number_of_response:1; }","duration":"204.221587ms","start":"2026-04-24T01:28:56.026985Z","end":"2026-04-24T01:28:56.231207Z","steps":["trace[24291021] 'process raft request' (duration: 203.080352ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:56.230733Z","caller":"traceutil/trace.go:171","msg":"trace[1615950704] transaction","detail":"{read_only:false; response_revision:2360; number_of_response:1; }","duration":"202.722134ms","start":"2026-04-24T01:28:56.027945Z","end":"2026-04-24T01:28:56.230667Z","steps":["trace[1615950704] 'process raft request' (duration: 202.173442ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:58.108819Z","caller":"traceutil/trace.go:171","msg":"trace[306257310] transaction","detail":"{read_only:false; response_revision:2364; number_of_response:1; }","duration":"124.315082ms","start":"2026-04-24T01:28:57.984472Z","end":"2026-04-24T01:28:58.108787Z","steps":["trace[306257310] 'process raft request' (duration: 123.958474ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:58.330177Z","caller":"traceutil/trace.go:171","msg":"trace[160331957] linearizableReadLoop","detail":"{readStateIndex:2456; appliedIndex:2455; }","duration":"219.337597ms","start":"2026-04-24T01:28:58.110813Z","end":"2026-04-24T01:28:58.330151Z","steps":["trace[160331957] 'read index received' (duration: 177.844745ms)","trace[160331957] 'applied index is now lower than readState.Index' (duration: 41.491492ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:28:58.33025Z","caller":"traceutil/trace.go:171","msg":"trace[917122913] transaction","detail":"{read_only:false; response_revision:2365; number_of_response:1; }","duration":"302.600316ms","start":"2026-04-24T01:28:58.027613Z","end":"2026-04-24T01:28:58.330213Z","steps":["trace[917122913] 'process raft request' (duration: 261.097474ms)","trace[917122913] 'compare' (duration: 41.258937ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:28:58.330461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.464775ms","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-24T01:28:58.330511Z","caller":"traceutil/trace.go:171","msg":"trace[1656591129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2365; }","duration":"182.590068ms","start":"2026-04-24T01:28:58.147908Z","end":"2026-04-24T01:28:58.330498Z","steps":["trace[1656591129] 'agreement among raft nodes before linearized reading' (duration: 182.409824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:58.330412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.563781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T01:28:58.330569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:28:58.027576Z","time spent":"302.78937ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:28:58.330633Z","caller":"traceutil/trace.go:171","msg":"trace[234349065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2365; }","duration":"219.812816ms","start":"2026-04-24T01:28:58.110793Z","end":"2026-04-24T01:28:58.330606Z","steps":["trace[234349065] 'agreement among raft nodes before linearized reading' (duration: 219.49753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:28:59.466376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.86716ms","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-24T01:28:59.46647Z","caller":"traceutil/trace.go:171","msg":"trace[1743120765] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2374; }","duration":"158.995473ms","start":"2026-04-24T01:28:59.307456Z","end":"2026-04-24T01:28:59.466451Z","steps":["trace[1743120765] 'count revisions from in-memory index tree' (duration: 158.797549ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:59.467051Z","caller":"traceutil/trace.go:171","msg":"trace[892844328] linearizableReadLoop","detail":"{readStateIndex:2467; appliedIndex:2466; }","duration":"126.458505ms","start":"2026-04-24T01:28:59.34058Z","end":"2026-04-24T01:28:59.467038Z","steps":["trace[892844328] 'read index received' (duration: 92.313924ms)","trace[892844328] 'applied index is now lower than readState.Index' (duration: 34.143431ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:28:59.467247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.66951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5428"} {"level":"info","ts":"2026-04-24T01:28:59.467376Z","caller":"traceutil/trace.go:171","msg":"trace[53615571] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2375; }","duration":"126.800173ms","start":"2026-04-24T01:28:59.340565Z","end":"2026-04-24T01:28:59.467365Z","steps":["trace[53615571] 'agreement among raft nodes before linearized reading' (duration: 126.523977ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:59.467408Z","caller":"traceutil/trace.go:171","msg":"trace[266881396] transaction","detail":"{read_only:false; response_revision:2375; number_of_response:1; }","duration":"127.293304ms","start":"2026-04-24T01:28:59.340096Z","end":"2026-04-24T01:28:59.467389Z","steps":["trace[266881396] 'process raft request' (duration: 92.865796ms)","trace[266881396] 'compare' (duration: 33.838815ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:29:04.252841Z","caller":"traceutil/trace.go:171","msg":"trace[1352645770] transaction","detail":"{read_only:false; response_revision:2423; number_of_response:1; }","duration":"154.796726ms","start":"2026-04-24T01:29:04.098021Z","end":"2026-04-24T01:29:04.252817Z","steps":["trace[1352645770] 'process raft request' (duration: 154.650183ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:29:04.349793Z","caller":"traceutil/trace.go:171","msg":"trace[1374277048] linearizableReadLoop","detail":"{readStateIndex:2518; appliedIndex:2516; }","duration":"139.423355ms","start":"2026-04-24T01:29:04.210352Z","end":"2026-04-24T01:29:04.349775Z","steps":["trace[1374277048] 'read index received' (duration: 42.319675ms)","trace[1374277048] 'applied index is now lower than readState.Index' (duration: 97.10254ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:29:04.350048Z","caller":"traceutil/trace.go:171","msg":"trace[311246134] transaction","detail":"{read_only:false; response_revision:2424; number_of_response:1; }","duration":"217.129874ms","start":"2026-04-24T01:29:04.132904Z","end":"2026-04-24T01:29:04.350034Z","steps":["trace[311246134] 'process raft request' (duration: 161.807328ms)","trace[311246134] 'compare' (duration: 54.947848ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:29:04.350162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.781532ms","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-24T01:29:04.35027Z","caller":"traceutil/trace.go:171","msg":"trace[50790682] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2424; }","duration":"139.940796ms","start":"2026-04-24T01:29:04.21031Z","end":"2026-04-24T01:29:04.350251Z","steps":["trace[50790682] 'agreement among raft nodes before linearized reading' (duration: 139.769332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:29:04.353268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.140855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:29:04.353363Z","caller":"traceutil/trace.go:171","msg":"trace[1280527307] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:2425; }","duration":"131.255008ms","start":"2026-04-24T01:29:04.22209Z","end":"2026-04-24T01:29:04.353345Z","steps":["trace[1280527307] 'agreement among raft nodes before linearized reading' (duration: 131.060953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:29:14.968443Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367216709899142,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-24T01:29:15.033416Z","caller":"traceutil/trace.go:171","msg":"trace[851474952] transaction","detail":"{read_only:false; response_revision:2542; number_of_response:1; }","duration":"643.766671ms","start":"2026-04-24T01:29:14.389608Z","end":"2026-04-24T01:29:15.033375Z","steps":["trace[851474952] 'process raft request' (duration: 643.562067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:29:15.033643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:29:14.389587Z","time spent":"643.935184ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:29:15.062163Z","caller":"traceutil/trace.go:171","msg":"trace[340296845] linearizableReadLoop","detail":"{readStateIndex:2642; appliedIndex:2638; }","duration":"593.752608ms","start":"2026-04-24T01:29:14.468398Z","end":"2026-04-24T01:29:15.06215Z","steps":["trace[340296845] 'read index received' (duration: 566.573035ms)","trace[340296845] 'applied index is now lower than readState.Index' (duration: 27.178663ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:29:15.062199Z","caller":"traceutil/trace.go:171","msg":"trace[1392359699] transaction","detail":"{read_only:false; response_revision:2545; number_of_response:1; }","duration":"338.017271ms","start":"2026-04-24T01:29:14.724156Z","end":"2026-04-24T01:29:15.062173Z","steps":["trace[1392359699] 'process raft request' (duration: 337.902889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:29:15.062474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:29:14.724137Z","time spent":"338.225915ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:29:15.06252Z","caller":"traceutil/trace.go:171","msg":"trace[745682870] transaction","detail":"{read_only:false; response_revision:2546; number_of_response:1; }","duration":"278.402012ms","start":"2026-04-24T01:29:14.784099Z","end":"2026-04-24T01:29:15.062501Z","steps":["trace[745682870] 'process raft request' (duration: 278.005964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:29:15.06269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.308102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-04-24T01:29:15.062563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.588173ms","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-24T01:29:15.062757Z","caller":"traceutil/trace.go:171","msg":"trace[2099078755] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2546; }","duration":"527.421084ms","start":"2026-04-24T01:29:14.535312Z","end":"2026-04-24T01:29:15.062733Z","steps":["trace[2099078755] 'agreement among raft nodes before linearized reading' (duration: 527.299191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:29:15.062768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.715272ms","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":"warn","ts":"2026-04-24T01:29:15.062804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:29:14.535297Z","time spent":"527.499345ms","remote":"127.0.0.1:50454","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":"warn","ts":"2026-04-24T01:29:15.062843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.421521ms","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-24T01:29:15.062859Z","caller":"traceutil/trace.go:171","msg":"trace[699870127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2546; }","duration":"585.859228ms","start":"2026-04-24T01:29:14.47689Z","end":"2026-04-24T01:29:15.06275Z","steps":["trace[699870127] 'agreement among raft nodes before linearized reading' (duration: 585.526991ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:29:15.062911Z","caller":"traceutil/trace.go:171","msg":"trace[1383921025] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2546; }","duration":"594.528354ms","start":"2026-04-24T01:29:14.468352Z","end":"2026-04-24T01:29:15.062881Z","steps":["trace[1383921025] 'agreement among raft nodes before linearized reading' (duration: 593.939912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:29:15.062948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:29:14.468338Z","time spent":"594.601555ms","remote":"127.0.0.1:50524","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-24T01:29:15.062927Z","caller":"traceutil/trace.go:171","msg":"trace[706351485] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2546; }","duration":"171.818445ms","start":"2026-04-24T01:29:14.890991Z","end":"2026-04-24T01:29:15.062809Z","steps":["trace[706351485] 'agreement among raft nodes before linearized reading' (duration: 171.740483ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:29:15.063039Z","caller":"traceutil/trace.go:171","msg":"trace[1588597791] transaction","detail":"{read_only:false; response_revision:2544; number_of_response:1; }","duration":"582.409535ms","start":"2026-04-24T01:29:14.480598Z","end":"2026-04-24T01:29:15.063007Z","steps":["trace[1588597791] 'process raft request' (duration: 581.271891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:29:15.063176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.995838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T01:29:15.063192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:29:14.480548Z","time spent":"582.565228ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:29:15.063202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.926769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-24T01:29:15.06324Z","caller":"traceutil/trace.go:171","msg":"trace[781022143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2546; }","duration":"578.98303ms","start":"2026-04-24T01:29:14.484247Z","end":"2026-04-24T01:29:15.06323Z","steps":["trace[781022143] 'agreement among raft nodes before linearized reading' (duration: 578.875148ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:29:15.063206Z","caller":"traceutil/trace.go:171","msg":"trace[853016176] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2546; }","duration":"337.02741ms","start":"2026-04-24T01:29:14.726171Z","end":"2026-04-24T01:29:15.063198Z","steps":["trace[853016176] 'agreement among raft nodes before linearized reading' (duration: 336.957698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:29:15.062964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:29:14.476882Z","time spent":"586.052012ms","remote":"127.0.0.1:50524","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-24T01:29:15.063279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:29:14.484237Z","time spent":"579.035651ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-24T01:29:15.063289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:29:14.726129Z","time spent":"337.151011ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-04-24T01:29:15.06268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.571652ms","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-24T01:29:15.06341Z","caller":"traceutil/trace.go:171","msg":"trace[683897787] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2546; }","duration":"524.355588ms","start":"2026-04-24T01:29:14.539042Z","end":"2026-04-24T01:29:15.063397Z","steps":["trace[683897787] 'agreement among raft nodes before linearized reading' (duration: 523.435058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:29:15.063456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:29:14.538997Z","time spent":"524.44353ms","remote":"127.0.0.1:35526","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":"warn","ts":"2026-04-24T01:29:15.063596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.517695ms","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-24T01:29:15.063653Z","caller":"traceutil/trace.go:171","msg":"trace[696993083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2546; }","duration":"580.654927ms","start":"2026-04-24T01:29:14.482987Z","end":"2026-04-24T01:29:15.063642Z","steps":["trace[696993083] 'agreement among raft nodes before linearized reading' (duration: 580.010292ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:29:15.06199Z","caller":"traceutil/trace.go:171","msg":"trace[561693766] transaction","detail":"{read_only:false; response_revision:2543; number_of_response:1; }","duration":"670.79955ms","start":"2026-04-24T01:29:14.391141Z","end":"2026-04-24T01:29:15.06194Z","steps":["trace[561693766] 'process raft request' (duration: 670.601926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:29:15.063901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:29:14.482961Z","time spent":"580.925123ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-24T01:29:15.063928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:29:14.391102Z","time spent":"672.783443ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:29:47.410914Z","caller":"traceutil/trace.go:171","msg":"trace[718465087] linearizableReadLoop","detail":"{readStateIndex:2825; appliedIndex:2821; }","duration":"179.346017ms","start":"2026-04-24T01:29:47.231546Z","end":"2026-04-24T01:29:47.410892Z","steps":["trace[718465087] 'read index received' (duration: 32.907023ms)","trace[718465087] 'applied index is now lower than readState.Index' (duration: 146.437824ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:29:47.411149Z","caller":"traceutil/trace.go:171","msg":"trace[2026387750] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"215.728942ms","start":"2026-04-24T01:29:47.195375Z","end":"2026-04-24T01:29:47.411104Z","steps":["trace[2026387750] 'process raft request' (duration: 215.478307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:29:47.411549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.01946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-24T01:29:47.411637Z","caller":"traceutil/trace.go:171","msg":"trace[889447991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2722; }","duration":"171.155962ms","start":"2026-04-24T01:29:47.240457Z","end":"2026-04-24T01:29:47.411613Z","steps":["trace[889447991] 'agreement among raft nodes before linearized reading' (duration: 170.851356ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:29:47.411657Z","caller":"traceutil/trace.go:171","msg":"trace[613628913] transaction","detail":"{read_only:false; response_revision:2720; number_of_response:1; }","duration":"217.820826ms","start":"2026-04-24T01:29:47.193816Z","end":"2026-04-24T01:29:47.411637Z","steps":["trace[613628913] 'process raft request' (duration: 166.972406ms)","trace[613628913] 'compare' (duration: 49.559822ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:29:47.411833Z","caller":"traceutil/trace.go:171","msg":"trace[2031578067] transaction","detail":"{read_only:false; response_revision:2721; number_of_response:1; }","duration":"217.839036ms","start":"2026-04-24T01:29:47.193985Z","end":"2026-04-24T01:29:47.411824Z","steps":["trace[2031578067] 'process raft request' (duration: 216.817754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:29:47.411875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.278976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-24T01:29:47.411924Z","caller":"traceutil/trace.go:171","msg":"trace[850626145] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2722; }","duration":"171.327567ms","start":"2026-04-24T01:29:47.240582Z","end":"2026-04-24T01:29:47.41191Z","steps":["trace[850626145] 'agreement among raft nodes before linearized reading' (duration: 171.201794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:29:47.411984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.429108ms","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-24T01:29:47.412014Z","caller":"traceutil/trace.go:171","msg":"trace[1654403235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2722; }","duration":"171.47715ms","start":"2026-04-24T01:29:47.240528Z","end":"2026-04-24T01:29:47.412005Z","steps":["trace[1654403235] 'agreement among raft nodes before linearized reading' (duration: 171.376437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:29:47.411317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.745885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-24T01:29:47.412118Z","caller":"traceutil/trace.go:171","msg":"trace[1138403359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2722; }","duration":"180.598443ms","start":"2026-04-24T01:29:47.231511Z","end":"2026-04-24T01:29:47.41211Z","steps":["trace[1138403359] 'agreement among raft nodes before linearized reading' (duration: 179.687464ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:29:52.978114Z","caller":"traceutil/trace.go:171","msg":"trace[1750515900] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"150.069824ms","start":"2026-04-24T01:29:52.828025Z","end":"2026-04-24T01:29:52.978095Z","steps":["trace[1750515900] 'process raft request' (duration: 149.930031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:33:48.712942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.65578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:33:48.713272Z","caller":"traceutil/trace.go:171","msg":"trace[1438372923] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"285.361575ms","start":"2026-04-24T01:33:48.427878Z","end":"2026-04-24T01:33:48.713239Z","steps":["trace[1438372923] 'process raft request' (duration: 285.19161ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:33:48.71329Z","caller":"traceutil/trace.go:171","msg":"trace[1982274746] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"286.543362ms","start":"2026-04-24T01:33:48.426675Z","end":"2026-04-24T01:33:48.713218Z","steps":["trace[1982274746] 'process raft request' (duration: 19.510103ms)","trace[1982274746] 'compare' (duration: 266.500516ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:33:48.741595Z","caller":"traceutil/trace.go:171","msg":"trace[759168904] linearizableReadLoop","detail":"{readStateIndex:4930; appliedIndex:4928; }","duration":"313.669926ms","start":"2026-04-24T01:33:48.427904Z","end":"2026-04-24T01:33:48.741574Z","steps":["trace[759168904] 'read index received' (duration: 18.280134ms)","trace[759168904] 'applied index is now lower than readState.Index' (duration: 295.388572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:33:48.741863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.934352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1453"} {"level":"info","ts":"2026-04-24T01:33:48.742078Z","caller":"traceutil/trace.go:171","msg":"trace[395358892] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4747; }","duration":"314.139967ms","start":"2026-04-24T01:33:48.427889Z","end":"2026-04-24T01:33:48.742029Z","steps":["trace[395358892] 'agreement among raft nodes before linearized reading' (duration: 313.78969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:33:48.742121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:33:48.427881Z","time spent":"314.227679ms","remote":"127.0.0.1:50454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":1476,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-24T01:33:48.841272Z","caller":"traceutil/trace.go:171","msg":"trace[254456540] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"124.32058ms","start":"2026-04-24T01:33:48.716921Z","end":"2026-04-24T01:33:48.841241Z","steps":["trace[254456540] 'process raft request' (duration: 123.942042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:33:48.841447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.062078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-04-24T01:33:48.841563Z","caller":"traceutil/trace.go:171","msg":"trace[1424799203] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4748; }","duration":"222.255773ms","start":"2026-04-24T01:33:48.61928Z","end":"2026-04-24T01:33:48.841536Z","steps":["trace[1424799203] 'agreement among raft nodes before linearized reading' (duration: 221.886494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:33:48.841613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.629261ms","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-24T01:33:48.841749Z","caller":"traceutil/trace.go:171","msg":"trace[1473523863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4748; }","duration":"103.809035ms","start":"2026-04-24T01:33:48.737911Z","end":"2026-04-24T01:33:48.84172Z","steps":["trace[1473523863] 'agreement among raft nodes before linearized reading' (duration: 103.58941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:33:48.841767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.151665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-24T01:33:48.841875Z","caller":"traceutil/trace.go:171","msg":"trace[1026113133] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4748; }","duration":"328.327298ms","start":"2026-04-24T01:33:48.513514Z","end":"2026-04-24T01:33:48.841841Z","steps":["trace[1026113133] 'agreement among raft nodes before linearized reading' (duration: 327.95986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:33:48.841943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:33:48.513488Z","time spent":"328.436821ms","remote":"127.0.0.1:50470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":920,"request content":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" "} {"level":"warn","ts":"2026-04-24T01:33:48.842064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.670348ms","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-24T01:33:48.842128Z","caller":"traceutil/trace.go:171","msg":"trace[1033551191] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4748; }","duration":"406.76642ms","start":"2026-04-24T01:33:48.435347Z","end":"2026-04-24T01:33:48.842113Z","steps":["trace[1033551191] 'agreement among raft nodes before linearized reading' (duration: 406.639807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:33:48.842137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.045501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-04-24T01:33:48.842179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:33:48.435338Z","time spent":"406.827311ms","remote":"127.0.0.1:47134","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":"info","ts":"2026-04-24T01:33:48.842214Z","caller":"traceutil/trace.go:171","msg":"trace[1325045755] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4748; }","duration":"175.821769ms","start":"2026-04-24T01:33:48.666372Z","end":"2026-04-24T01:33:48.842194Z","steps":["trace[1325045755] 'agreement among raft nodes before linearized reading' (duration: 174.776995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:33:48.84698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.937691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194901"} {"level":"info","ts":"2026-04-24T01:33:48.847086Z","caller":"traceutil/trace.go:171","msg":"trace[593641979] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4748; }","duration":"362.114595ms","start":"2026-04-24T01:33:48.484951Z","end":"2026-04-24T01:33:48.847066Z","steps":["trace[593641979] 'agreement among raft nodes before linearized reading' (duration: 356.244452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:33:48.847364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:33:48.484927Z","time spent":"362.420192ms","remote":"127.0.0.1:50378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2194924,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-04-24T01:33:48.856911Z","caller":"traceutil/trace.go:171","msg":"trace[406134581] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"109.591976ms","start":"2026-04-24T01:33:48.7473Z","end":"2026-04-24T01:33:48.856892Z","steps":["trace[406134581] 'process raft request' (duration: 109.503474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:33:48.857645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.335423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-04-24T01:33:48.857746Z","caller":"traceutil/trace.go:171","msg":"trace[2102198340] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4749; }","duration":"110.486656ms","start":"2026-04-24T01:33:48.747244Z","end":"2026-04-24T01:33:48.85773Z","steps":["trace[2102198340] 'agreement among raft nodes before linearized reading' (duration: 110.057246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:33:49.444481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.202383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-24T01:33:49.444561Z","caller":"traceutil/trace.go:171","msg":"trace[1388233688] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4766; }","duration":"343.306135ms","start":"2026-04-24T01:33:49.101232Z","end":"2026-04-24T01:33:49.444539Z","steps":["trace[1388233688] 'agreement among raft nodes before linearized reading' (duration: 59.614131ms)","trace[1388233688] 'range keys from in-memory index tree' (duration: 283.444059ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:33:49.444601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:33:49.101227Z","time spent":"343.366316ms","remote":"127.0.0.1:50656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1147,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"info","ts":"2026-04-24T01:33:49.471234Z","caller":"traceutil/trace.go:171","msg":"trace[230585594] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"297.719462ms","start":"2026-04-24T01:33:49.173484Z","end":"2026-04-24T01:33:49.471203Z","steps":["trace[230585594] 'process raft request' (duration: 297.527837ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:33:49.471711Z","caller":"traceutil/trace.go:171","msg":"trace[1979083696] linearizableReadLoop","detail":"{readStateIndex:4951; appliedIndex:4951; }","duration":"131.502032ms","start":"2026-04-24T01:33:49.340145Z","end":"2026-04-24T01:33:49.471647Z","steps":["trace[1979083696] 'read index received' (duration: 131.450991ms)","trace[1979083696] 'applied index is now lower than readState.Index' (duration: 12.081µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:33:49.47202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.8462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8802109-933a-4e4a-9b88-51cd198a6ed4\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-24T01:33:49.472171Z","caller":"traceutil/trace.go:171","msg":"trace[1838183499] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a8802109-933a-4e4a-9b88-51cd198a6ed4; range_end:; response_count:1; response_revision:4767; }","duration":"132.015543ms","start":"2026-04-24T01:33:49.340073Z","end":"2026-04-24T01:33:49.472089Z","steps":["trace[1838183499] 'agreement among raft nodes before linearized reading' (duration: 131.626425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:33:49.803742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.315037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16085"} {"level":"info","ts":"2026-04-24T01:33:49.803844Z","caller":"traceutil/trace.go:171","msg":"trace[1322764024] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4767; }","duration":"179.494071ms","start":"2026-04-24T01:33:49.624326Z","end":"2026-04-24T01:33:49.80382Z","steps":["trace[1322764024] 'range keys from in-memory index tree' (duration: 179.007159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:33:49.804324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.858009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-688bdd864d-jz5hx.18a9270495788bcd\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-04-24T01:33:49.804399Z","caller":"traceutil/trace.go:171","msg":"trace[709982261] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-688bdd864d-jz5hx.18a9270495788bcd; range_end:; response_count:1; response_revision:4767; }","duration":"180.001162ms","start":"2026-04-24T01:33:49.62438Z","end":"2026-04-24T01:33:49.804381Z","steps":["trace[709982261] 'range keys from in-memory index tree' (duration: 179.212685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:33:49.805128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.582476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194901"} {"level":"info","ts":"2026-04-24T01:33:49.805305Z","caller":"traceutil/trace.go:171","msg":"trace[1867760759] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4767; }","duration":"117.832722ms","start":"2026-04-24T01:33:49.68745Z","end":"2026-04-24T01:33:49.805283Z","steps":["trace[1867760759] 'range keys from in-memory index tree' (duration: 115.980081ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:33:54.325671Z","caller":"traceutil/trace.go:171","msg":"trace[839201299] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"114.467569ms","start":"2026-04-24T01:33:54.211168Z","end":"2026-04-24T01:33:54.325636Z","steps":["trace[839201299] 'process raft request' (duration: 109.034867ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:33:57.560907Z","caller":"traceutil/trace.go:171","msg":"trace[145039210] linearizableReadLoop","detail":"{readStateIndex:5080; appliedIndex:5079; }","duration":"263.864132ms","start":"2026-04-24T01:33:57.297018Z","end":"2026-04-24T01:33:57.560883Z","steps":["trace[145039210] 'read index received' (duration: 263.731179ms)","trace[145039210] 'applied index is now lower than readState.Index' (duration: 131.863µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:33:57.561Z","caller":"traceutil/trace.go:171","msg":"trace[1431685249] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"283.849574ms","start":"2026-04-24T01:33:57.277121Z","end":"2026-04-24T01:33:57.56097Z","steps":["trace[1431685249] 'process raft request' (duration: 283.589628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:33:57.561058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.019605ms","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-24T01:33:57.561113Z","caller":"traceutil/trace.go:171","msg":"trace[1223165126] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:4892; }","duration":"264.102758ms","start":"2026-04-24T01:33:57.296998Z","end":"2026-04-24T01:33:57.561101Z","steps":["trace[1223165126] 'agreement among raft nodes before linearized reading' (duration: 263.978625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:03.218528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198099383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T01:34:03.218822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:02.797822Z","time spent":"420.994181ms","remote":"127.0.0.1:51236","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-24T01:34:03.218636Z","caller":"traceutil/trace.go:171","msg":"trace[1750437448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4942; }","duration":"1.198292848s","start":"2026-04-24T01:34:02.020319Z","end":"2026-04-24T01:34:03.218611Z","steps":["trace[1750437448] 'range keys from in-memory index tree' (duration: 1.198013s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:03.220326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.160785871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:34:03.2204Z","caller":"traceutil/trace.go:171","msg":"trace[1527300459] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:4942; }","duration":"1.160894504s","start":"2026-04-24T01:34:02.059483Z","end":"2026-04-24T01:34:03.220378Z","steps":["trace[1527300459] 'count revisions from in-memory index tree' (duration: 1.1607067s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:03.220455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:02.05947Z","time spent":"1.160968435s","remote":"127.0.0.1:35420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-24T01:34:03.220451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:02.020296Z","time spent":"1.199277689s","remote":"127.0.0.1:50274","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-24T01:34:03.220636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.07992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:34:03.220777Z","caller":"traceutil/trace.go:171","msg":"trace[498455881] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"870.803714ms","start":"2026-04-24T01:34:02.349961Z","end":"2026-04-24T01:34:03.220765Z","steps":["trace[498455881] 'process raft request' (duration: 870.745402ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:34:03.220788Z","caller":"traceutil/trace.go:171","msg":"trace[220347488] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"870.814694ms","start":"2026-04-24T01:34:02.349961Z","end":"2026-04-24T01:34:03.220775Z","steps":["trace[220347488] 'process raft request' (duration: 30.521469ms)","trace[220347488] 'compare' (duration: 837.793548ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:34:03.22084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:02.349933Z","time spent":"870.875665ms","remote":"127.0.0.1:50524","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-24T01:34:03.220843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:02.349934Z","time spent":"870.878185ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:34:03.258235Z","caller":"traceutil/trace.go:171","msg":"trace[675451473] linearizableReadLoop","detail":"{readStateIndex:5135; appliedIndex:5133; }","duration":"469.676618ms","start":"2026-04-24T01:34:02.788541Z","end":"2026-04-24T01:34:03.258218Z","steps":["trace[675451473] 'read index received' (duration: 469.547455ms)","trace[675451473] 'applied index is now lower than readState.Index' (duration: 128.423µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:34:03.25831Z","caller":"traceutil/trace.go:171","msg":"trace[757073188] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"791.699659ms","start":"2026-04-24T01:34:02.466578Z","end":"2026-04-24T01:34:03.258278Z","steps":["trace[757073188] 'process raft request' (duration: 791.516795ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:34:03.258349Z","caller":"traceutil/trace.go:171","msg":"trace[844183030] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"770.478381ms","start":"2026-04-24T01:34:02.487857Z","end":"2026-04-24T01:34:03.258335Z","steps":["trace[844183030] 'process raft request' (duration: 770.326037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:03.258566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:02.487851Z","time spent":"770.646124ms","remote":"127.0.0.1:50524","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-24T01:34:03.258686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.920113ms","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-24T01:34:03.258769Z","caller":"traceutil/trace.go:171","msg":"trace[782257084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4946; }","duration":"250.014855ms","start":"2026-04-24T01:34:03.008731Z","end":"2026-04-24T01:34:03.258746Z","steps":["trace[782257084] 'agreement among raft nodes before linearized reading' (duration: 249.853571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:03.258955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.490527ms","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-24T01:34:03.259002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.383547ms","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-24T01:34:03.259789Z","caller":"traceutil/trace.go:171","msg":"trace[59330860] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4946; }","duration":"268.189035ms","start":"2026-04-24T01:34:02.991561Z","end":"2026-04-24T01:34:03.25975Z","steps":["trace[59330860] 'agreement among raft nodes before linearized reading' (duration: 267.184873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:03.258923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.376408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-24T01:34:03.259945Z","caller":"traceutil/trace.go:171","msg":"trace[1441431183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4946; }","duration":"261.405752ms","start":"2026-04-24T01:34:02.998521Z","end":"2026-04-24T01:34:03.259926Z","steps":["trace[1441431183] 'agreement among raft nodes before linearized reading' (duration: 260.208915ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:34:03.259021Z","caller":"traceutil/trace.go:171","msg":"trace[736127111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4946; }","duration":"270.578209ms","start":"2026-04-24T01:34:02.988432Z","end":"2026-04-24T01:34:03.25901Z","steps":["trace[736127111] 'agreement among raft nodes before linearized reading' (duration: 270.448526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:03.258921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:02.466555Z","time spent":"791.835162ms","remote":"127.0.0.1:50524","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-24T01:34:03.260232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.715452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"warn","ts":"2026-04-24T01:34:03.25869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.890192ms","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-24T01:34:03.2604Z","caller":"traceutil/trace.go:171","msg":"trace[1587189488] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4946; }","duration":"215.976618ms","start":"2026-04-24T01:34:03.044317Z","end":"2026-04-24T01:34:03.260294Z","steps":["trace[1587189488] 'agreement among raft nodes before linearized reading' (duration: 214.681909ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:34:03.260446Z","caller":"traceutil/trace.go:171","msg":"trace[397857026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4946; }","duration":"298.711482ms","start":"2026-04-24T01:34:02.961716Z","end":"2026-04-24T01:34:03.260428Z","steps":["trace[397857026] 'agreement among raft nodes before linearized reading' (duration: 296.704697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:03.259073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.531787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-24T01:34:03.260582Z","caller":"traceutil/trace.go:171","msg":"trace[1971023634] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4946; }","duration":"472.083531ms","start":"2026-04-24T01:34:02.788482Z","end":"2026-04-24T01:34:03.260566Z","steps":["trace[1971023634] 'agreement among raft nodes before linearized reading' (duration: 470.462415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:03.260634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:02.788458Z","time spent":"472.160294ms","remote":"127.0.0.1:50424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-24T01:34:03.625101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.521182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:34:03.625433Z","caller":"traceutil/trace.go:171","msg":"trace[2018929423] linearizableReadLoop","detail":"{readStateIndex:5136; appliedIndex:5135; }","duration":"359.35476ms","start":"2026-04-24T01:34:03.266056Z","end":"2026-04-24T01:34:03.625411Z","steps":["trace[2018929423] 'read index received' (duration: 238.166µs)","trace[2018929423] 'applied index is now lower than readState.Index' (duration: 359.114774ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:34:03.625461Z","caller":"traceutil/trace.go:171","msg":"trace[811299393] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"359.422712ms","start":"2026-04-24T01:34:03.266007Z","end":"2026-04-24T01:34:03.62543Z","steps":["trace[811299393] 'process raft request' (duration: 193.45812ms)","trace[811299393] 'compare' (duration: 165.211904ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:34:03.625563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:03.265983Z","time spent":"359.532445ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:34:03.625724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.634346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-24T01:34:03.625813Z","caller":"traceutil/trace.go:171","msg":"trace[1546449256] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4947; }","duration":"359.736349ms","start":"2026-04-24T01:34:03.266053Z","end":"2026-04-24T01:34:03.625789Z","steps":["trace[1546449256] 'agreement among raft nodes before linearized reading' (duration: 359.464013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:03.625865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:03.266045Z","time spent":"359.81047ms","remote":"127.0.0.1:50424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-24T01:34:03.681722Z","caller":"traceutil/trace.go:171","msg":"trace[619891394] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"414.033052ms","start":"2026-04-24T01:34:03.267675Z","end":"2026-04-24T01:34:03.681708Z","steps":["trace[619891394] 'process raft request' (duration: 413.969711ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:34:03.681777Z","caller":"traceutil/trace.go:171","msg":"trace[1457055240] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"415.388422ms","start":"2026-04-24T01:34:03.266379Z","end":"2026-04-24T01:34:03.681767Z","steps":["trace[1457055240] 'process raft request' (duration: 415.210878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:03.681868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:03.267662Z","time spent":"414.127234ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:34:03.681868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:03.266373Z","time spent":"415.434093ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:34:03.682042Z","caller":"traceutil/trace.go:171","msg":"trace[734600373] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"415.73338ms","start":"2026-04-24T01:34:03.266294Z","end":"2026-04-24T01:34:03.682027Z","steps":["trace[734600373] 'process raft request' (duration: 415.165968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:03.682107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:03.266281Z","time spent":"415.795571ms","remote":"127.0.0.1:50524","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-24T01:34:03.682111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.413891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:34:03.682154Z","caller":"traceutil/trace.go:171","msg":"trace[246543801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4951; }","duration":"414.481032ms","start":"2026-04-24T01:34:03.267661Z","end":"2026-04-24T01:34:03.682142Z","steps":["trace[246543801] 'agreement among raft nodes before linearized reading' (duration: 414.41251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:03.682183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:03.267654Z","time spent":"414.521852ms","remote":"127.0.0.1:50274","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-24T01:34:03.682364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.958556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-24T01:34:03.683178Z","caller":"traceutil/trace.go:171","msg":"trace[1322690228] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:4951; }","duration":"318.822845ms","start":"2026-04-24T01:34:03.364347Z","end":"2026-04-24T01:34:03.68317Z","steps":["trace[1322690228] 'agreement among raft nodes before linearized reading' (duration: 317.708571ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:34:03.681724Z","caller":"traceutil/trace.go:171","msg":"trace[1765206862] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"415.29577ms","start":"2026-04-24T01:34:03.266399Z","end":"2026-04-24T01:34:03.681695Z","steps":["trace[1765206862] 'process raft request' (duration: 415.218718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:03.683293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:03.364326Z","time spent":"318.931787ms","remote":"127.0.0.1:50310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-04-24T01:34:03.683309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:03.266385Z","time spent":"416.884196ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:34:04.086318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.813878ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-24T01:34:04.086437Z","caller":"traceutil/trace.go:171","msg":"trace[1841712827] linearizableReadLoop","detail":"{readStateIndex:5141; appliedIndex:5140; }","duration":"299.780085ms","start":"2026-04-24T01:34:03.786635Z","end":"2026-04-24T01:34:04.086415Z","steps":["trace[1841712827] 'read index received' (duration: 73.796363ms)","trace[1841712827] 'applied index is now lower than readState.Index' (duration: 225.981762ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:34:04.086544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:03.684563Z","time spent":"401.977329ms","remote":"127.0.0.1:50310","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-24T01:34:04.08692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.283717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-24T01:34:04.086988Z","caller":"traceutil/trace.go:171","msg":"trace[773818122] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4951; }","duration":"300.401789ms","start":"2026-04-24T01:34:03.786564Z","end":"2026-04-24T01:34:04.086966Z","steps":["trace[773818122] 'agreement among raft nodes before linearized reading' (duration: 300.202505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:04.087033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:03.786543Z","time spent":"300.477193ms","remote":"127.0.0.1:50424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-24T01:34:04.228643Z","caller":"traceutil/trace.go:171","msg":"trace[1128897974] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"112.551055ms","start":"2026-04-24T01:34:04.116065Z","end":"2026-04-24T01:34:04.228616Z","steps":["trace[1128897974] 'process raft request' (duration: 112.482774ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:34:04.228696Z","caller":"traceutil/trace.go:171","msg":"trace[1373796874] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"117.960067ms","start":"2026-04-24T01:34:04.110698Z","end":"2026-04-24T01:34:04.228658Z","steps":["trace[1373796874] 'process raft request' (duration: 78.876636ms)","trace[1373796874] 'compare' (duration: 38.804085ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:34:04.564592Z","caller":"traceutil/trace.go:171","msg":"trace[1813363732] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"328.971791ms","start":"2026-04-24T01:34:04.235589Z","end":"2026-04-24T01:34:04.564561Z","steps":["trace[1813363732] 'process raft request' (duration: 328.661484ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:34:04.564722Z","caller":"traceutil/trace.go:171","msg":"trace[916314012] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"322.818573ms","start":"2026-04-24T01:34:04.241873Z","end":"2026-04-24T01:34:04.564692Z","steps":["trace[916314012] 'process raft request' (duration: 322.582827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:04.564894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:04.241851Z","time spent":"322.956875ms","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8123,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:34:04.564659Z","caller":"traceutil/trace.go:171","msg":"trace[1104616000] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"313.689118ms","start":"2026-04-24T01:34:04.250953Z","end":"2026-04-24T01:34:04.564642Z","steps":["trace[1104616000] 'process raft request' (duration: 313.573215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:04.565005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:04.235575Z","time spent":"329.180626ms","remote":"127.0.0.1:50430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1329,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:34:04.565102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:34:04.250927Z","time spent":"314.116377ms","remote":"127.0.0.1:50450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11655,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:34:04.744475Z","caller":"traceutil/trace.go:171","msg":"trace[1999211540] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"172.525146ms","start":"2026-04-24T01:34:04.5719Z","end":"2026-04-24T01:34:04.744425Z","steps":["trace[1999211540] 'process raft request' (duration: 116.737469ms)","trace[1999211540] 'compare' (duration: 54.737394ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:34:04.744597Z","caller":"traceutil/trace.go:171","msg":"trace[273639952] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"172.575808ms","start":"2026-04-24T01:34:04.571978Z","end":"2026-04-24T01:34:04.744554Z","steps":["trace[273639952] 'process raft request' (duration: 172.284551ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:34:04.744529Z","caller":"traceutil/trace.go:171","msg":"trace[1462751082] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"169.238833ms","start":"2026-04-24T01:34:04.575264Z","end":"2026-04-24T01:34:04.744503Z","steps":["trace[1462751082] 'process raft request' (duration: 169.14455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:34:05.168183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.178316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:34:05.168269Z","caller":"traceutil/trace.go:171","msg":"trace[1014416358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4963; }","duration":"148.334429ms","start":"2026-04-24T01:34:05.019916Z","end":"2026-04-24T01:34:05.168251Z","steps":["trace[1014416358] 'range keys from in-memory index tree' (duration: 148.042313ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:35:15.632096Z","caller":"traceutil/trace.go:171","msg":"trace[516753226] linearizableReadLoop","detail":"{readStateIndex:5734; appliedIndex:5733; }","duration":"285.457867ms","start":"2026-04-24T01:35:15.346611Z","end":"2026-04-24T01:35:15.632069Z","steps":["trace[516753226] 'read index received' (duration: 285.328185ms)","trace[516753226] 'applied index is now lower than readState.Index' (duration: 128.532µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:35:15.632245Z","caller":"traceutil/trace.go:171","msg":"trace[1753423332] transaction","detail":"{read_only:false; response_revision:5528; number_of_response:1; }","duration":"392.628307ms","start":"2026-04-24T01:35:15.239576Z","end":"2026-04-24T01:35:15.632204Z","steps":["trace[1753423332] 'process raft request' (duration: 392.28657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:35:15.632428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:35:15.239486Z","time spent":"392.838602ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:35:15.632416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.785054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8242"} {"level":"info","ts":"2026-04-24T01:35:15.632496Z","caller":"traceutil/trace.go:171","msg":"trace[1760149580] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:5528; }","duration":"285.911967ms","start":"2026-04-24T01:35:15.346565Z","end":"2026-04-24T01:35:15.632477Z","steps":["trace[1760149580] 'agreement among raft nodes before linearized reading' (duration: 285.672182ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:35:15.748295Z","caller":"traceutil/trace.go:171","msg":"trace[890800537] transaction","detail":"{read_only:false; response_revision:5529; number_of_response:1; }","duration":"310.726944ms","start":"2026-04-24T01:35:15.437546Z","end":"2026-04-24T01:35:15.748273Z","steps":["trace[890800537] 'process raft request' (duration: 310.55369ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:35:15.748521Z","caller":"traceutil/trace.go:171","msg":"trace[1729775241] transaction","detail":"{read_only:false; response_revision:5530; number_of_response:1; }","duration":"270.370924ms","start":"2026-04-24T01:35:15.478134Z","end":"2026-04-24T01:35:15.748505Z","steps":["trace[1729775241] 'process raft request' (duration: 270.095498ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:35:15.748714Z","caller":"traceutil/trace.go:171","msg":"trace[1822372234] transaction","detail":"{read_only:false; response_revision:5531; number_of_response:1; }","duration":"269.297121ms","start":"2026-04-24T01:35:15.479397Z","end":"2026-04-24T01:35:15.748694Z","steps":["trace[1822372234] 'process raft request' (duration: 269.039176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:35:15.74874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:35:15.437509Z","time spent":"310.850096ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:35:15.749036Z","caller":"traceutil/trace.go:171","msg":"trace[1702309032] transaction","detail":"{read_only:false; response_revision:5533; number_of_response:1; }","duration":"234.999185ms","start":"2026-04-24T01:35:15.514021Z","end":"2026-04-24T01:35:15.74902Z","steps":["trace[1702309032] 'process raft request' (duration: 234.907593ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:35:15.749064Z","caller":"traceutil/trace.go:171","msg":"trace[599066619] transaction","detail":"{read_only:false; response_revision:5532; number_of_response:1; }","duration":"247.149104ms","start":"2026-04-24T01:35:15.501902Z","end":"2026-04-24T01:35:15.749051Z","steps":["trace[599066619] 'process raft request' (duration: 246.718994ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:35:16.330788Z","caller":"traceutil/trace.go:171","msg":"trace[1652786606] transaction","detail":"{read_only:false; response_revision:5539; number_of_response:1; }","duration":"104.49319ms","start":"2026-04-24T01:35:16.226268Z","end":"2026-04-24T01:35:16.330761Z","steps":["trace[1652786606] 'process raft request' (duration: 104.346637ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:35:16.412241Z","caller":"traceutil/trace.go:171","msg":"trace[896949278] transaction","detail":"{read_only:false; response_revision:5540; number_of_response:1; }","duration":"153.408927ms","start":"2026-04-24T01:35:16.258809Z","end":"2026-04-24T01:35:16.412218Z","steps":["trace[896949278] 'process raft request' (duration: 152.668351ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:35:20.313629Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2896} {"level":"info","ts":"2026-04-24T01:35:20.419855Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2896,"took":"104.753965ms","hash":2310995842,"current-db-size-bytes":54067200,"current-db-size":"54 MB","current-db-size-in-use-bytes":28946432,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-24T01:35:20.419907Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2310995842,"revision":2896,"compact-revision":-1} {"level":"info","ts":"2026-04-24T01:35:23.979994Z","caller":"traceutil/trace.go:171","msg":"trace[1773119823] transaction","detail":"{read_only:false; response_revision:5580; number_of_response:1; }","duration":"186.243164ms","start":"2026-04-24T01:35:23.793733Z","end":"2026-04-24T01:35:23.979976Z","steps":["trace[1773119823] 'process raft request' (duration: 186.106431ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:35:23.980996Z","caller":"traceutil/trace.go:171","msg":"trace[564690755] linearizableReadLoop","detail":"{readStateIndex:5790; appliedIndex:5788; }","duration":"165.951858ms","start":"2026-04-24T01:35:23.815024Z","end":"2026-04-24T01:35:23.980976Z","steps":["trace[564690755] 'read index received' (duration: 164.816533ms)","trace[564690755] 'applied index is now lower than readState.Index' (duration: 1.134105ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:35:23.981235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.159342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T01:35:23.981311Z","caller":"traceutil/trace.go:171","msg":"trace[1957180882] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:5581; }","duration":"166.301796ms","start":"2026-04-24T01:35:23.814997Z","end":"2026-04-24T01:35:23.981299Z","steps":["trace[1957180882] 'agreement among raft nodes before linearized reading' (duration: 166.129712ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:35:23.981487Z","caller":"traceutil/trace.go:171","msg":"trace[260997765] transaction","detail":"{read_only:false; response_revision:5581; number_of_response:1; }","duration":"187.370499ms","start":"2026-04-24T01:35:23.794075Z","end":"2026-04-24T01:35:23.981445Z","steps":["trace[260997765] 'process raft request' (duration: 186.805597ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:36:08.148948Z","caller":"traceutil/trace.go:171","msg":"trace[672852127] transaction","detail":"{read_only:false; response_revision:5852; number_of_response:1; }","duration":"163.231398ms","start":"2026-04-24T01:36:07.985694Z","end":"2026-04-24T01:36:08.148925Z","steps":["trace[672852127] 'process raft request' (duration: 163.066675ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:36:08.174058Z","caller":"traceutil/trace.go:171","msg":"trace[954670834] transaction","detail":"{read_only:false; response_revision:5853; number_of_response:1; }","duration":"186.304081ms","start":"2026-04-24T01:36:07.98773Z","end":"2026-04-24T01:36:08.174034Z","steps":["trace[954670834] 'process raft request' (duration: 186.137677ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:37:43.251187Z","caller":"traceutil/trace.go:171","msg":"trace[769885633] linearizableReadLoop","detail":"{readStateIndex:6746; appliedIndex:6745; }","duration":"216.052218ms","start":"2026-04-24T01:37:43.035115Z","end":"2026-04-24T01:37:43.251167Z","steps":["trace[769885633] 'read index received' (duration: 215.838633ms)","trace[769885633] 'applied index is now lower than readState.Index' (duration: 212.575µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:37:43.251309Z","caller":"traceutil/trace.go:171","msg":"trace[748133025] transaction","detail":"{read_only:false; response_revision:6505; number_of_response:1; }","duration":"262.95881ms","start":"2026-04-24T01:37:42.988339Z","end":"2026-04-24T01:37:43.251298Z","steps":["trace[748133025] 'process raft request' (duration: 262.692564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:37:43.251457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.649507ms","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-24T01:37:43.251536Z","caller":"traceutil/trace.go:171","msg":"trace[1464238119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6505; }","duration":"214.755619ms","start":"2026-04-24T01:37:43.036763Z","end":"2026-04-24T01:37:43.251519Z","steps":["trace[1464238119] 'agreement among raft nodes before linearized reading' (duration: 214.585466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:37:43.251494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.373734ms","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-24T01:37:43.251686Z","caller":"traceutil/trace.go:171","msg":"trace[616886939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6505; }","duration":"216.60291ms","start":"2026-04-24T01:37:43.035067Z","end":"2026-04-24T01:37:43.25167Z","steps":["trace[616886939] 'agreement among raft nodes before linearized reading' (duration: 216.357754ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:37:43.424734Z","caller":"traceutil/trace.go:171","msg":"trace[2037812386] transaction","detail":"{read_only:false; response_revision:6506; number_of_response:1; }","duration":"169.678967ms","start":"2026-04-24T01:37:43.255038Z","end":"2026-04-24T01:37:43.424717Z","steps":["trace[2037812386] 'process raft request' (duration: 169.494963ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:37:43.425289Z","caller":"traceutil/trace.go:171","msg":"trace[1593642475] transaction","detail":"{read_only:false; response_revision:6507; number_of_response:1; }","duration":"169.206038ms","start":"2026-04-24T01:37:43.256064Z","end":"2026-04-24T01:37:43.425271Z","steps":["trace[1593642475] 'process raft request' (duration: 169.079835ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:37:43.425672Z","caller":"traceutil/trace.go:171","msg":"trace[2022474262] transaction","detail":"{read_only:false; response_revision:6508; number_of_response:1; }","duration":"169.300109ms","start":"2026-04-24T01:37:43.256363Z","end":"2026-04-24T01:37:43.425663Z","steps":["trace[2022474262] 'process raft request' (duration: 168.87998ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:37:52.775592Z","caller":"traceutil/trace.go:171","msg":"trace[972919015] transaction","detail":"{read_only:false; response_revision:6559; number_of_response:1; }","duration":"113.597217ms","start":"2026-04-24T01:37:52.661959Z","end":"2026-04-24T01:37:52.775556Z","steps":["trace[972919015] 'process raft request' (duration: 113.546106ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:37:52.776073Z","caller":"traceutil/trace.go:171","msg":"trace[854695675] transaction","detail":"{read_only:false; response_revision:6557; number_of_response:1; }","duration":"116.226573ms","start":"2026-04-24T01:37:52.659834Z","end":"2026-04-24T01:37:52.77606Z","steps":["trace[854695675] 'process raft request' (duration: 115.319674ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:37:52.776855Z","caller":"traceutil/trace.go:171","msg":"trace[1817755126] transaction","detail":"{read_only:false; response_revision:6558; number_of_response:1; }","duration":"114.364374ms","start":"2026-04-24T01:37:52.661896Z","end":"2026-04-24T01:37:52.77626Z","steps":["trace[1817755126] 'process raft request' (duration: 113.560397ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:37:53.615032Z","caller":"traceutil/trace.go:171","msg":"trace[2010274769] transaction","detail":"{read_only:false; response_revision:6566; number_of_response:1; }","duration":"105.150705ms","start":"2026-04-24T01:37:53.509863Z","end":"2026-04-24T01:37:53.615014Z","steps":["trace[2010274769] 'process raft request' (duration: 105.082094ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:37:53.615505Z","caller":"traceutil/trace.go:171","msg":"trace[849607885] transaction","detail":"{read_only:false; response_revision:6564; number_of_response:1; }","duration":"145.76766ms","start":"2026-04-24T01:37:53.469727Z","end":"2026-04-24T01:37:53.615495Z","steps":["trace[849607885] 'process raft request' (duration: 98.009162ms)","trace[849607885] 'compare' (duration: 47.071444ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:37:53.6156Z","caller":"traceutil/trace.go:171","msg":"trace[196188218] transaction","detail":"{read_only:false; response_revision:6565; number_of_response:1; }","duration":"143.369318ms","start":"2026-04-24T01:37:53.472223Z","end":"2026-04-24T01:37:53.615592Z","steps":["trace[196188218] 'process raft request' (duration: 142.683684ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:37:53.615734Z","caller":"traceutil/trace.go:171","msg":"trace[1536285725] linearizableReadLoop","detail":"{readStateIndex:6810; appliedIndex:6808; }","duration":"108.559129ms","start":"2026-04-24T01:37:53.507158Z","end":"2026-04-24T01:37:53.615717Z","steps":["trace[1536285725] 'read index received' (duration: 60.566735ms)","trace[1536285725] 'applied index is now lower than readState.Index' (duration: 47.991164ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:37:53.616262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.08465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-24T01:37:53.616296Z","caller":"traceutil/trace.go:171","msg":"trace[1098570284] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:6566; }","duration":"109.154751ms","start":"2026-04-24T01:37:53.507133Z","end":"2026-04-24T01:37:53.616288Z","steps":["trace[1098570284] 'agreement among raft nodes before linearized reading' (duration: 108.62219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:37:55.789887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.060761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T01:37:55.789903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.950438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-24T01:37:55.789958Z","caller":"traceutil/trace.go:171","msg":"trace[306330933] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:6572; }","duration":"279.203635ms","start":"2026-04-24T01:37:55.510739Z","end":"2026-04-24T01:37:55.789943Z","steps":["trace[306330933] 'count revisions from in-memory index tree' (duration: 278.926158ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:37:55.789985Z","caller":"traceutil/trace.go:171","msg":"trace[1733622042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6572; }","duration":"168.099071ms","start":"2026-04-24T01:37:55.621868Z","end":"2026-04-24T01:37:55.789967Z","steps":["trace[1733622042] 'range keys from in-memory index tree' (duration: 167.742334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:37:55.789956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.799444ms","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-24T01:37:55.79005Z","caller":"traceutil/trace.go:171","msg":"trace[1452038104] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6572; }","duration":"167.920358ms","start":"2026-04-24T01:37:55.622116Z","end":"2026-04-24T01:37:55.790036Z","steps":["trace[1452038104] 'range keys from in-memory index tree' (duration: 167.56724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:37:55.790332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.655011ms","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-24T01:37:55.790409Z","caller":"traceutil/trace.go:171","msg":"trace[1228000448] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6572; }","duration":"167.761534ms","start":"2026-04-24T01:37:55.622629Z","end":"2026-04-24T01:37:55.790391Z","steps":["trace[1228000448] 'range keys from in-memory index tree' (duration: 167.513789ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:38:55.095417Z","caller":"traceutil/trace.go:171","msg":"trace[1908958450] transaction","detail":"{read_only:false; response_revision:6927; number_of_response:1; }","duration":"140.681555ms","start":"2026-04-24T01:38:54.954705Z","end":"2026-04-24T01:38:55.095386Z","steps":["trace[1908958450] 'process raft request' (duration: 140.485961ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:38:55.139313Z","caller":"traceutil/trace.go:171","msg":"trace[1135271652] transaction","detail":"{read_only:false; response_revision:6928; number_of_response:1; }","duration":"106.432076ms","start":"2026-04-24T01:38:55.032851Z","end":"2026-04-24T01:38:55.139283Z","steps":["trace[1135271652] 'process raft request' (duration: 106.227401ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:39:03.372873Z","caller":"traceutil/trace.go:171","msg":"trace[1202441088] transaction","detail":"{read_only:false; response_revision:6966; number_of_response:1; }","duration":"256.078647ms","start":"2026-04-24T01:39:03.116775Z","end":"2026-04-24T01:39:03.372854Z","steps":["trace[1202441088] 'process raft request' (duration: 255.946524ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:39:03.37497Z","caller":"traceutil/trace.go:171","msg":"trace[279120353] linearizableReadLoop","detail":"{readStateIndex:7229; appliedIndex:7225; }","duration":"172.992543ms","start":"2026-04-24T01:39:03.201956Z","end":"2026-04-24T01:39:03.374948Z","steps":["trace[279120353] 'read index received' (duration: 170.79095ms)","trace[279120353] 'applied index is now lower than readState.Index' (duration: 2.200543ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:39:03.375396Z","caller":"traceutil/trace.go:171","msg":"trace[455001827] transaction","detail":"{read_only:false; response_revision:6967; number_of_response:1; }","duration":"212.033485ms","start":"2026-04-24T01:39:03.163345Z","end":"2026-04-24T01:39:03.375379Z","steps":["trace[455001827] 'process raft request' (duration: 211.4131ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:39:03.375622Z","caller":"traceutil/trace.go:171","msg":"trace[145605736] transaction","detail":"{read_only:false; response_revision:6968; number_of_response:1; }","duration":"208.422928ms","start":"2026-04-24T01:39:03.167187Z","end":"2026-04-24T01:39:03.37561Z","steps":["trace[145605736] 'process raft request' (duration: 207.67836ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:39:03.375854Z","caller":"traceutil/trace.go:171","msg":"trace[459581667] transaction","detail":"{read_only:false; response_revision:6969; number_of_response:1; }","duration":"208.629713ms","start":"2026-04-24T01:39:03.16721Z","end":"2026-04-24T01:39:03.37584Z","steps":["trace[459581667] 'process raft request' (duration: 207.701621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:39:03.376392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.424028ms","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-24T01:39:03.376483Z","caller":"traceutil/trace.go:171","msg":"trace[530950705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6969; }","duration":"174.568281ms","start":"2026-04-24T01:39:03.201904Z","end":"2026-04-24T01:39:03.376472Z","steps":["trace[530950705] 'agreement among raft nodes before linearized reading' (duration: 174.378117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:39:03.376764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.842323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T01:39:03.376937Z","caller":"traceutil/trace.go:171","msg":"trace[1487350773] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:6969; }","duration":"169.039098ms","start":"2026-04-24T01:39:03.207882Z","end":"2026-04-24T01:39:03.376921Z","steps":["trace[1487350773] 'agreement among raft nodes before linearized reading' (duration: 168.844093ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:40:20.320589Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5564} {"level":"info","ts":"2026-04-24T01:40:20.409721Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5564,"took":"87.728341ms","hash":3874962893,"current-db-size-bytes":54067200,"current-db-size":"54 MB","current-db-size-in-use-bytes":17993728,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-24T01:40:20.409786Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3874962893,"revision":5564,"compact-revision":2896}