{"level":"warn","ts":"2026-06-15T20:51:12.665594Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-15T20:51:12.665772Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-15T20:51:12.665912Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-15T20:51:12.665938Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-06-15T20:51:12.665986Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-15T20:51:12.667241Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-06-15T20:51:12.667709Z","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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-15T20:51:12.672867Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.70159ms"} {"level":"info","ts":"2026-06-15T20:51:12.689751Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-06-15T20:51:12.689882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-06-15T20:51:12.689918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-06-15T20:51:12.689934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-15T20:51:12.689952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-06-15T20:51:12.690008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-06-15T20:51:12.709608Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-15T20:51:12.712186Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-15T20:51:12.714362Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-15T20:51:12.717164Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-15T20:51:12.7203Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-15T20:51:12.720411Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-15T20:51:12.720426Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-15T20:51:12.720878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-06-15T20:51:12.721059Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-06-15T20:51:12.720162Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-15T20:51:12.723755Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-15T20:51:12.723939Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-06-15T20:51:12.723989Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-06-15T20:51:12.724063Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-15T20:51:12.724113Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-15T20:51:13.090512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-06-15T20:51:13.090668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-15T20:51:13.090771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-06-15T20:51:13.090814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-06-15T20:51:13.090887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-06-15T20:51:13.090955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-06-15T20:51:13.09099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-06-15T20:51:13.099509Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-06-15T20:51:13.099601Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T20:51:13.099941Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T20:51:13.100121Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T20:51:13.103643Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-15T20:51:13.1038Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-15T20:51:13.10368Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-06-15T20:51:13.103839Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-15T20:51:13.195363Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T20:51:13.19564Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T20:51:13.195712Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T20:51:21.994896Z","caller":"traceutil/trace.go:171","msg":"trace[1533568904] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:363; }","duration":"103.703982ms","start":"2026-06-15T20:51:21.891169Z","end":"2026-06-15T20:51:21.994873Z","steps":["trace[1533568904] 'read index received' (duration: 103.49369ms)","trace[1533568904] 'applied index is now lower than readState.Index' (duration: 209.322µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:51:21.994973Z","caller":"traceutil/trace.go:171","msg":"trace[68165733] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"144.452442ms","start":"2026-06-15T20:51:21.850473Z","end":"2026-06-15T20:51:21.994925Z","steps":["trace[68165733] 'process raft request' (duration: 144.2629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:21.995227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.964445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-06-15T20:51:21.995365Z","caller":"traceutil/trace.go:171","msg":"trace[887170441] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:350; }","duration":"104.236317ms","start":"2026-06-15T20:51:21.891107Z","end":"2026-06-15T20:51:21.995343Z","steps":["trace[887170441] 'agreement among raft nodes before linearized reading' (duration: 103.921994ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:22.182511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.940133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:51:22.182659Z","caller":"traceutil/trace.go:171","msg":"trace[361897459] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"182.508352ms","start":"2026-06-15T20:51:22.000127Z","end":"2026-06-15T20:51:22.182636Z","steps":["trace[361897459] 'process raft request' (duration: 54.849271ms)","trace[361897459] 'compare' (duration: 126.769981ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:51:22.193992Z","caller":"traceutil/trace.go:171","msg":"trace[367767126] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"192.180094ms","start":"2026-06-15T20:51:22.001782Z","end":"2026-06-15T20:51:22.193963Z","steps":["trace[367767126] 'process raft request' (duration: 191.969352ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:51:22.194086Z","caller":"traceutil/trace.go:171","msg":"trace[928851299] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"190.1544ms","start":"2026-06-15T20:51:22.003911Z","end":"2026-06-15T20:51:22.194066Z","steps":["trace[928851299] 'process raft request' (duration: 189.964678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:22.194108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.715735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-06-15T20:51:22.194165Z","caller":"traceutil/trace.go:171","msg":"trace[2010594892] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:353; }","duration":"153.822766ms","start":"2026-06-15T20:51:22.040327Z","end":"2026-06-15T20:51:22.194149Z","steps":["trace[2010594892] 'agreement among raft nodes before linearized reading' (duration: 153.710045ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:51:22.193982Z","caller":"traceutil/trace.go:171","msg":"trace[1355432471] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:364; }","duration":"153.585924ms","start":"2026-06-15T20:51:22.040372Z","end":"2026-06-15T20:51:22.193958Z","steps":["trace[1355432471] 'read index received' (duration: 14.700132ms)","trace[1355432471] 'applied index is now lower than readState.Index' (duration: 138.884492ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:51:22.204926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.564456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-06-15T20:51:22.204985Z","caller":"traceutil/trace.go:171","msg":"trace[891877448] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:354; }","duration":"113.631267ms","start":"2026-06-15T20:51:22.091338Z","end":"2026-06-15T20:51:22.204969Z","steps":["trace[891877448] 'agreement among raft nodes before linearized reading' (duration: 113.465125ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:51:27.026171Z","caller":"traceutil/trace.go:171","msg":"trace[1727212976] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"108.545121ms","start":"2026-06-15T20:51:26.91758Z","end":"2026-06-15T20:51:27.026125Z","steps":["trace[1727212976] 'process raft request' (duration: 70.853479ms)","trace[1727212976] 'compare' (duration: 37.47127ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:51:33.268981Z","caller":"traceutil/trace.go:171","msg":"trace[1748830821] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"115.443961ms","start":"2026-06-15T20:51:33.153498Z","end":"2026-06-15T20:51:33.268942Z","steps":["trace[1748830821] 'process raft request' (duration: 57.127879ms)","trace[1748830821] 'compare' (duration: 58.184221ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:51:38.744054Z","caller":"traceutil/trace.go:171","msg":"trace[406071701] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"126.16443ms","start":"2026-06-15T20:51:38.617849Z","end":"2026-06-15T20:51:38.744013Z","steps":["trace[406071701] 'read index received' (duration: 125.925668ms)","trace[406071701] 'applied index is now lower than readState.Index' (duration: 237.592µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:51:38.744088Z","caller":"traceutil/trace.go:171","msg":"trace[1547500034] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"230.676638ms","start":"2026-06-15T20:51:38.513375Z","end":"2026-06-15T20:51:38.744051Z","steps":["trace[1547500034] 'process raft request' (duration: 230.445756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:38.744347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.468684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T20:51:38.74439Z","caller":"traceutil/trace.go:171","msg":"trace[50202240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:462; }","duration":"126.599946ms","start":"2026-06-15T20:51:38.617777Z","end":"2026-06-15T20:51:38.744377Z","steps":["trace[50202240] 'agreement among raft nodes before linearized reading' (duration: 126.416884ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:51:39.231818Z","caller":"traceutil/trace.go:171","msg":"trace[1964647370] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"147.820234ms","start":"2026-06-15T20:51:39.083939Z","end":"2026-06-15T20:51:39.231759Z","steps":["trace[1964647370] 'read index received' (duration: 143.029125ms)","trace[1964647370] 'applied index is now lower than readState.Index' (duration: 4.789949ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:51:39.231962Z","caller":"traceutil/trace.go:171","msg":"trace[228871405] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"482.299657ms","start":"2026-06-15T20:51:38.749643Z","end":"2026-06-15T20:51:39.231943Z","steps":["trace[228871405] 'process raft request' (duration: 477.282695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:39.232067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.123356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-15T20:51:39.232115Z","caller":"traceutil/trace.go:171","msg":"trace[1433029992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:463; }","duration":"148.231638ms","start":"2026-06-15T20:51:39.083871Z","end":"2026-06-15T20:51:39.232103Z","steps":["trace[1433029992] 'agreement among raft nodes before linearized reading' (duration: 148.095377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:39.232334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:51:38.749599Z","time spent":"482.409088ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:51:51.434765Z","caller":"traceutil/trace.go:171","msg":"trace[1303509193] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"104.286242ms","start":"2026-06-15T20:51:51.330447Z","end":"2026-06-15T20:51:51.434734Z","steps":["trace[1303509193] 'process raft request' (duration: 104.172762ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:51:51.434771Z","caller":"traceutil/trace.go:171","msg":"trace[1787453665] transaction","detail":"{read_only:false; number_of_response:1; response_revision:534; }","duration":"106.583605ms","start":"2026-06-15T20:51:51.328136Z","end":"2026-06-15T20:51:51.43472Z","steps":["trace[1787453665] 'process raft request' (duration: 87.208149ms)","trace[1787453665] 'compare' (duration: 19.169144ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:51:51.436547Z","caller":"traceutil/trace.go:171","msg":"trace[350405221] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:556; }","duration":"102.635306ms","start":"2026-06-15T20:51:51.333879Z","end":"2026-06-15T20:51:51.436514Z","steps":["trace[350405221] 'read index received' (duration: 81.521493ms)","trace[350405221] 'applied index is now lower than readState.Index' (duration: 21.113153ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:51:51.437079Z","caller":"traceutil/trace.go:171","msg":"trace[1045612596] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"104.451553ms","start":"2026-06-15T20:51:51.332603Z","end":"2026-06-15T20:51:51.437054Z","steps":["trace[1045612596] 'process raft request' (duration: 103.201761ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:51:51.437413Z","caller":"traceutil/trace.go:171","msg":"trace[2128795697] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"104.738266ms","start":"2026-06-15T20:51:51.332661Z","end":"2026-06-15T20:51:51.437399Z","steps":["trace[2128795697] 'process raft request' (duration: 103.270441ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:51.437672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.771307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-06-15T20:51:51.437717Z","caller":"traceutil/trace.go:171","msg":"trace[891267734] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:547; }","duration":"103.855728ms","start":"2026-06-15T20:51:51.333851Z","end":"2026-06-15T20:51:51.437707Z","steps":["trace[891267734] 'agreement among raft nodes before linearized reading' (duration: 103.709787ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:51:52.154729Z","caller":"traceutil/trace.go:171","msg":"trace[453344731] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"181.440767ms","start":"2026-06-15T20:51:51.973252Z","end":"2026-06-15T20:51:52.154693Z","steps":["trace[453344731] 'process raft request' (duration: 139.355924ms)","trace[453344731] 'compare' (duration: 41.926951ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:51:52.16476Z","caller":"traceutil/trace.go:171","msg":"trace[1967213482] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"168.939446ms","start":"2026-06-15T20:51:51.99578Z","end":"2026-06-15T20:51:52.164719Z","steps":["trace[1967213482] 'read index received' (duration: 116.877698ms)","trace[1967213482] 'applied index is now lower than readState.Index' (duration: 52.060958ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:51:52.164779Z","caller":"traceutil/trace.go:171","msg":"trace[1468585389] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"184.838979ms","start":"2026-06-15T20:51:51.979904Z","end":"2026-06-15T20:51:52.164743Z","steps":["trace[1468585389] 'process raft request' (duration: 184.629317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:52.16492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.096628ms","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-06-15T20:51:52.165015Z","caller":"traceutil/trace.go:171","msg":"trace[1677000634] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:584; }","duration":"169.26765ms","start":"2026-06-15T20:51:51.995726Z","end":"2026-06-15T20:51:52.164994Z","steps":["trace[1677000634] 'agreement among raft nodes before linearized reading' (duration: 169.078549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:52.183489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.587326ms","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-06-15T20:51:52.1836Z","caller":"traceutil/trace.go:171","msg":"trace[1519397249] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:585; }","duration":"187.717857ms","start":"2026-06-15T20:51:51.995854Z","end":"2026-06-15T20:51:52.183571Z","steps":["trace[1519397249] 'agreement among raft nodes before linearized reading' (duration: 187.488605ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:52.183961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.885568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:51:52.184001Z","caller":"traceutil/trace.go:171","msg":"trace[1800478976] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:585; }","duration":"118.000159ms","start":"2026-06-15T20:51:52.06599Z","end":"2026-06-15T20:51:52.18399Z","steps":["trace[1800478976] 'agreement among raft nodes before linearized reading' (duration: 117.879298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:52.184179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.269881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:51:52.184214Z","caller":"traceutil/trace.go:171","msg":"trace[359495332] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:585; }","duration":"118.324052ms","start":"2026-06-15T20:51:52.065882Z","end":"2026-06-15T20:51:52.184206Z","steps":["trace[359495332] 'agreement among raft nodes before linearized reading' (duration: 118.269601ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:52.184341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.855138ms","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-06-15T20:51:52.184373Z","caller":"traceutil/trace.go:171","msg":"trace[313333117] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:585; }","duration":"138.888159ms","start":"2026-06-15T20:51:52.045477Z","end":"2026-06-15T20:51:52.184365Z","steps":["trace[313333117] 'agreement among raft nodes before linearized reading' (duration: 138.841168ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:52.184483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.634975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:51:52.184586Z","caller":"traceutil/trace.go:171","msg":"trace[152303209] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:585; }","duration":"117.806477ms","start":"2026-06-15T20:51:52.06676Z","end":"2026-06-15T20:51:52.184566Z","steps":["trace[152303209] 'agreement among raft nodes before linearized reading' (duration: 117.592964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:52.184597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.808678ms","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-06-15T20:51:52.184658Z","caller":"traceutil/trace.go:171","msg":"trace[895802107] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:585; }","duration":"139.912498ms","start":"2026-06-15T20:51:52.044732Z","end":"2026-06-15T20:51:52.184645Z","steps":["trace[895802107] 'agreement among raft nodes before linearized reading' (duration: 139.824238ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:52.184531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.484325ms","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-06-15T20:51:52.185011Z","caller":"traceutil/trace.go:171","msg":"trace[1894702415] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:585; }","duration":"139.993679ms","start":"2026-06-15T20:51:52.045006Z","end":"2026-06-15T20:51:52.184999Z","steps":["trace[1894702415] 'agreement among raft nodes before linearized reading' (duration: 139.499345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:52.996538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.815709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:51:52.996672Z","caller":"traceutil/trace.go:171","msg":"trace[420123681] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"779.387035ms","start":"2026-06-15T20:51:52.217262Z","end":"2026-06-15T20:51:52.996649Z","steps":["trace[420123681] 'process raft request' (duration: 504.325444ms)","trace[420123681] 'compare' (duration: 274.636126ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:51:52.996737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:51:52.217236Z","time spent":"779.471877ms","remote":"127.0.0.1:40472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:51:53.049265Z","caller":"traceutil/trace.go:171","msg":"trace[312963724] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"785.008006ms","start":"2026-06-15T20:51:52.264223Z","end":"2026-06-15T20:51:53.049231Z","steps":["trace[312963724] 'process raft request' (duration: 784.38104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:53.049432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:51:52.264193Z","time spent":"785.144308ms","remote":"127.0.0.1:40472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:51:53.050151Z","caller":"traceutil/trace.go:171","msg":"trace[27789561] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"618.991558ms","start":"2026-06-15T20:51:52.431122Z","end":"2026-06-15T20:51:53.050113Z","steps":["trace[27789561] 'process raft request' (duration: 618.12006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:53.050424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:51:52.431086Z","time spent":"619.170519ms","remote":"127.0.0.1:40472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-15T20:51:53.247024Z","caller":"traceutil/trace.go:171","msg":"trace[766344360] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"199.773492ms","start":"2026-06-15T20:51:53.047217Z","end":"2026-06-15T20:51:53.246991Z","steps":["trace[766344360] 'process raft request' (duration: 132.374711ms)","trace[766344360] 'compare' (duration: 67.2431ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:51:53.249749Z","caller":"traceutil/trace.go:171","msg":"trace[1270284291] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"184.136764ms","start":"2026-06-15T20:51:53.065604Z","end":"2026-06-15T20:51:53.249741Z","steps":["trace[1270284291] 'process raft request' (duration: 184.040593ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:51:53.249944Z","caller":"traceutil/trace.go:171","msg":"trace[734512981] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"104.475906ms","start":"2026-06-15T20:51:53.145436Z","end":"2026-06-15T20:51:53.249912Z","steps":["trace[734512981] 'process raft request' (duration: 104.278403ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:51:53.375208Z","caller":"traceutil/trace.go:171","msg":"trace[331961189] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"103.925489ms","start":"2026-06-15T20:51:53.271247Z","end":"2026-06-15T20:51:53.375173Z","steps":["trace[331961189] 'process raft request' (duration: 96.775191ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:51:57.477742Z","caller":"traceutil/trace.go:171","msg":"trace[1433767008] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"124.249035ms","start":"2026-06-15T20:51:57.353461Z","end":"2026-06-15T20:51:57.47771Z","steps":["trace[1433767008] 'process raft request' (duration: 119.663612ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:51:57.698921Z","caller":"traceutil/trace.go:171","msg":"trace[442996577] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"110.751788ms","start":"2026-06-15T20:51:57.588144Z","end":"2026-06-15T20:51:57.698896Z","steps":["trace[442996577] 'read index received' (duration: 110.225644ms)","trace[442996577] 'applied index is now lower than readState.Index' (duration: 525.264µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:51:57.699056Z","caller":"traceutil/trace.go:171","msg":"trace[977054516] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"167.46908ms","start":"2026-06-15T20:51:57.531576Z","end":"2026-06-15T20:51:57.699045Z","steps":["trace[977054516] 'process raft request' (duration: 166.803574ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:57.699378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.212682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T20:51:57.699416Z","caller":"traceutil/trace.go:171","msg":"trace[2141175109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"111.309363ms","start":"2026-06-15T20:51:57.588097Z","end":"2026-06-15T20:51:57.699406Z","steps":["trace[2141175109] 'agreement among raft nodes before linearized reading' (duration: 111.123431ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:57.699501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.240012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-15T20:51:57.699563Z","caller":"traceutil/trace.go:171","msg":"trace[1881005229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"111.362393ms","start":"2026-06-15T20:51:57.588184Z","end":"2026-06-15T20:51:57.699547Z","steps":["trace[1881005229] 'agreement among raft nodes before linearized reading' (duration: 111.212752ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:57.699698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.319633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-15T20:51:57.699729Z","caller":"traceutil/trace.go:171","msg":"trace[1453319459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"111.409723ms","start":"2026-06-15T20:51:57.588312Z","end":"2026-06-15T20:51:57.699722Z","steps":["trace[1453319459] 'agreement among raft nodes before linearized reading' (duration: 111.334153ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:51:58.931658Z","caller":"traceutil/trace.go:171","msg":"trace[1147260378] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"279.366799ms","start":"2026-06-15T20:51:58.652175Z","end":"2026-06-15T20:51:58.931542Z","steps":["trace[1147260378] 'read index received' (duration: 276.541673ms)","trace[1147260378] 'applied index is now lower than readState.Index' (duration: 2.822986ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:51:58.932987Z","caller":"traceutil/trace.go:171","msg":"trace[1950606022] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"548.259729ms","start":"2026-06-15T20:51:58.384539Z","end":"2026-06-15T20:51:58.932798Z","steps":["trace[1950606022] 'process raft request' (duration: 544.599585ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:51:58.933433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:51:58.384502Z","time spent":"548.709913ms","remote":"127.0.0.1:40496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:51:58.935882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.677669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:51:58.93595Z","caller":"traceutil/trace.go:171","msg":"trace[1551720847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"283.76826ms","start":"2026-06-15T20:51:58.652164Z","end":"2026-06-15T20:51:58.935932Z","steps":["trace[1551720847] 'agreement among raft nodes before linearized reading' (duration: 280.072025ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:00.014875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.382571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:52:00.015184Z","caller":"traceutil/trace.go:171","msg":"trace[1943709404] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"629.216562ms","start":"2026-06-15T20:51:59.385941Z","end":"2026-06-15T20:52:00.015157Z","steps":["trace[1943709404] 'process raft request' (duration: 629.094451ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:00.015214Z","caller":"traceutil/trace.go:171","msg":"trace[1635865365] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.007772962s","start":"2026-06-15T20:51:59.007424Z","end":"2026-06-15T20:52:00.015197Z","steps":["trace[1635865365] 'process raft request' (duration: 31.962207ms)","trace[1635865365] 'compare' (duration: 975.200859ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:52:00.015274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:51:59.0074Z","time spent":"1.007846042s","remote":"127.0.0.1:40766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T20:52:00.015297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:51:59.385909Z","time spent":"629.325002ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:52:00.015372Z","caller":"traceutil/trace.go:171","msg":"trace[804926175] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"1.005815763s","start":"2026-06-15T20:51:59.009547Z","end":"2026-06-15T20:52:00.015363Z","steps":["trace[804926175] 'read index received' (duration: 29.727426ms)","trace[804926175] 'applied index is now lower than readState.Index' (duration: 976.087307ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:52:00.015654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.453391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:52:00.015682Z","caller":"traceutil/trace.go:171","msg":"trace[1059856355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"362.481521ms","start":"2026-06-15T20:51:59.65319Z","end":"2026-06-15T20:52:00.015672Z","steps":["trace[1059856355] 'agreement among raft nodes before linearized reading' (duration: 362.43134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:00.015701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:51:59.653096Z","time spent":"362.600702ms","remote":"127.0.0.1:40422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:52:00.015834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.635012ms","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-06-15T20:52:00.015812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.995727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T20:52:00.015854Z","caller":"traceutil/trace.go:171","msg":"trace[1201243343] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"304.673012ms","start":"2026-06-15T20:51:59.711175Z","end":"2026-06-15T20:52:00.015848Z","steps":["trace[1201243343] 'agreement among raft nodes before linearized reading' (duration: 304.611032ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:00.01587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:51:59.711166Z","time spent":"304.700193ms","remote":"127.0.0.1:40646","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-06-15T20:52:00.01588Z","caller":"traceutil/trace.go:171","msg":"trace[1794801548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"304.063757ms","start":"2026-06-15T20:51:59.711796Z","end":"2026-06-15T20:52:00.01586Z","steps":["trace[1794801548] 'agreement among raft nodes before linearized reading' (duration: 303.849996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:00.015914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.979248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-15T20:52:00.015923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:51:59.711703Z","time spent":"304.209168ms","remote":"127.0.0.1:40646","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-06-15T20:52:00.015962Z","caller":"traceutil/trace.go:171","msg":"trace[797715043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"303.044168ms","start":"2026-06-15T20:51:59.712903Z","end":"2026-06-15T20:52:00.015947Z","steps":["trace[797715043] 'agreement among raft nodes before linearized reading' (duration: 302.901507ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:00.015984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.424566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-15T20:52:00.016Z","caller":"traceutil/trace.go:171","msg":"trace[791138783] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"311.496266ms","start":"2026-06-15T20:51:59.704499Z","end":"2026-06-15T20:52:00.015996Z","steps":["trace[791138783] 'agreement among raft nodes before linearized reading' (duration: 311.442966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:00.016007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:51:59.712897Z","time spent":"303.097548ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T20:52:00.016007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006427479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wppzn\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-06-15T20:52:00.016018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:51:59.70447Z","time spent":"311.544826ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-15T20:52:00.016129Z","caller":"traceutil/trace.go:171","msg":"trace[1364102653] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wppzn; range_end:; response_count:1; response_revision:634; }","duration":"1.00659971s","start":"2026-06-15T20:51:59.009498Z","end":"2026-06-15T20:52:00.016097Z","steps":["trace[1364102653] 'agreement among raft nodes before linearized reading' (duration: 1.006268658s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:00.016188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:51:59.00948Z","time spent":"1.006694552s","remote":"127.0.0.1:40586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-wppzn\" "} {"level":"warn","ts":"2026-06-15T20:52:00.529503Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955415720638297,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T20:52:00.74217Z","caller":"traceutil/trace.go:171","msg":"trace[10085833] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"720.154963ms","start":"2026-06-15T20:52:00.021964Z","end":"2026-06-15T20:52:00.742119Z","steps":["trace[10085833] 'process raft request' (duration: 622.989003ms)","trace[10085833] 'compare' (duration: 96.916818ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:52:00.742348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:52:00.021926Z","time spent":"720.331725ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:52:00.742799Z","caller":"traceutil/trace.go:171","msg":"trace[1834397744] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:661; }","duration":"713.735003ms","start":"2026-06-15T20:52:00.029008Z","end":"2026-06-15T20:52:00.742743Z","steps":["trace[1834397744] 'read index received' (duration: 616.101498ms)","trace[1834397744] 'applied index is now lower than readState.Index' (duration: 97.632435ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:52:00.743084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.008886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:52:00.743218Z","caller":"traceutil/trace.go:171","msg":"trace[487593344] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:639; }","duration":"714.204258ms","start":"2026-06-15T20:52:00.028974Z","end":"2026-06-15T20:52:00.743178Z","steps":["trace[487593344] 'agreement among raft nodes before linearized reading' (duration: 713.989046ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:00.743373Z","caller":"traceutil/trace.go:171","msg":"trace[164152045] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"721.184173ms","start":"2026-06-15T20:52:00.02217Z","end":"2026-06-15T20:52:00.743354Z","steps":["trace[164152045] 'process raft request' (duration: 720.314495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:00.743394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:52:00.028963Z","time spent":"714.41663ms","remote":"127.0.0.1:40918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"info","ts":"2026-06-15T20:52:00.743606Z","caller":"traceutil/trace.go:171","msg":"trace[829950949] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"721.327734ms","start":"2026-06-15T20:52:00.022264Z","end":"2026-06-15T20:52:00.743592Z","steps":["trace[829950949] 'process raft request' (duration: 720.320895ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:00.743638Z","caller":"traceutil/trace.go:171","msg":"trace[2100085775] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"712.034358ms","start":"2026-06-15T20:52:00.031589Z","end":"2026-06-15T20:52:00.743623Z","steps":["trace[2100085775] 'process raft request' (duration: 711.074398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:00.743689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:52:00.022231Z","time spent":"721.420296ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:52:00.7437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:52:00.031578Z","time spent":"712.08875ms","remote":"127.0.0.1:40586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:52:00.743518Z","caller":"traceutil/trace.go:171","msg":"trace[164231071] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"721.451665ms","start":"2026-06-15T20:52:00.022057Z","end":"2026-06-15T20:52:00.743509Z","steps":["trace[164231071] 'process raft request' (duration: 720.007711ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:00.743476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:52:00.022164Z","time spent":"721.258213ms","remote":"127.0.0.1:40646","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-06-15T20:52:00.743855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:52:00.022033Z","time spent":"721.797538ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:52:02.159121Z","caller":"traceutil/trace.go:171","msg":"trace[812839760] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"123.622057ms","start":"2026-06-15T20:52:02.03546Z","end":"2026-06-15T20:52:02.159082Z","steps":["trace[812839760] 'process raft request' (duration: 122.472287ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:02.159321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.604705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:52:02.159311Z","caller":"traceutil/trace.go:171","msg":"trace[1391304370] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"123.694179ms","start":"2026-06-15T20:52:02.035591Z","end":"2026-06-15T20:52:02.159285Z","steps":["trace[1391304370] 'process raft request' (duration: 123.418956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:02.159413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.569845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:52:02.15943Z","caller":"traceutil/trace.go:171","msg":"trace[1861647849] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:660; }","duration":"126.748527ms","start":"2026-06-15T20:52:02.032657Z","end":"2026-06-15T20:52:02.159406Z","steps":["trace[1861647849] 'agreement among raft nodes before linearized reading' (duration: 126.550165ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:02.15947Z","caller":"traceutil/trace.go:171","msg":"trace[553500726] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:660; }","duration":"126.692016ms","start":"2026-06-15T20:52:02.032761Z","end":"2026-06-15T20:52:02.159453Z","steps":["trace[553500726] 'agreement among raft nodes before linearized reading' (duration: 126.577625ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:02.827305Z","caller":"traceutil/trace.go:171","msg":"trace[1252907791] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"204.18013ms","start":"2026-06-15T20:52:02.623104Z","end":"2026-06-15T20:52:02.827284Z","steps":["trace[1252907791] 'process raft request' (duration: 203.408613ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:04.192682Z","caller":"traceutil/trace.go:171","msg":"trace[541726804] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"153.037824ms","start":"2026-06-15T20:52:04.039624Z","end":"2026-06-15T20:52:04.192661Z","steps":["trace[541726804] 'process raft request' (duration: 137.050224ms)","trace[541726804] 'compare' (duration: 15.715477ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:52:04.193001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.347567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-15T20:52:04.193065Z","caller":"traceutil/trace.go:171","msg":"trace[190942284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:700; }","duration":"106.443392ms","start":"2026-06-15T20:52:04.086606Z","end":"2026-06-15T20:52:04.193049Z","steps":["trace[190942284] 'agreement among raft nodes before linearized reading' (duration: 106.153137ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:04.193097Z","caller":"traceutil/trace.go:171","msg":"trace[775368290] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"105.976238ms","start":"2026-06-15T20:52:04.086631Z","end":"2026-06-15T20:52:04.192608Z","steps":["trace[775368290] 'read index received' (duration: 90.145085ms)","trace[775368290] 'applied index is now lower than readState.Index' (duration: 15.830093ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:52:04.24518Z","caller":"traceutil/trace.go:171","msg":"trace[1071707798] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"157.332958ms","start":"2026-06-15T20:52:04.087825Z","end":"2026-06-15T20:52:04.245157Z","steps":["trace[1071707798] 'process raft request' (duration: 157.254874ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:04.245646Z","caller":"traceutil/trace.go:171","msg":"trace[387404433] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"158.520586ms","start":"2026-06-15T20:52:04.087112Z","end":"2026-06-15T20:52:04.245633Z","steps":["trace[387404433] 'process raft request' (duration: 157.803111ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:04.369422Z","caller":"traceutil/trace.go:171","msg":"trace[583334571] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"172.380252ms","start":"2026-06-15T20:52:04.197014Z","end":"2026-06-15T20:52:04.369394Z","steps":["trace[583334571] 'process raft request' (duration: 98.861128ms)","trace[583334571] 'compare' (duration: 73.372356ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:52:04.372626Z","caller":"traceutil/trace.go:171","msg":"trace[82925036] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"103.758378ms","start":"2026-06-15T20:52:04.268837Z","end":"2026-06-15T20:52:04.372595Z","steps":["trace[82925036] 'process raft request' (duration: 103.329917ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:04.5579Z","caller":"traceutil/trace.go:171","msg":"trace[604956436] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"106.941166ms","start":"2026-06-15T20:52:04.450935Z","end":"2026-06-15T20:52:04.557876Z","steps":["trace[604956436] 'process raft request' (duration: 106.663831ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:04.642164Z","caller":"traceutil/trace.go:171","msg":"trace[54381079] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"187.269926ms","start":"2026-06-15T20:52:04.454859Z","end":"2026-06-15T20:52:04.642128Z","steps":["trace[54381079] 'process raft request' (duration: 186.979522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:04.808101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.900094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:52:04.808273Z","caller":"traceutil/trace.go:171","msg":"trace[1963173536] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:734; }","duration":"181.685611ms","start":"2026-06-15T20:52:04.626571Z","end":"2026-06-15T20:52:04.808256Z","steps":["trace[1963173536] 'read index received' (duration: 15.252195ms)","trace[1963173536] 'applied index is now lower than readState.Index' (duration: 166.432156ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:52:04.80853Z","caller":"traceutil/trace.go:171","msg":"trace[1575488345] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"199.009219ms","start":"2026-06-15T20:52:04.609344Z","end":"2026-06-15T20:52:04.808353Z","steps":["trace[1575488345] 'process raft request' (duration: 90.800549ms)","trace[1575488345] 'compare' (duration: 107.784728ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:52:04.808741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.156655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-06-15T20:52:04.808849Z","caller":"traceutil/trace.go:171","msg":"trace[1649858564] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:708; }","duration":"182.292491ms","start":"2026-06-15T20:52:04.626542Z","end":"2026-06-15T20:52:04.808834Z","steps":["trace[1649858564] 'agreement among raft nodes before linearized reading' (duration: 181.86116ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:04.810518Z","caller":"traceutil/trace.go:171","msg":"trace[977982918] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"156.407082ms","start":"2026-06-15T20:52:04.654096Z","end":"2026-06-15T20:52:04.810503Z","steps":["trace[977982918] 'process raft request' (duration: 156.207782ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:04.815182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.599467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:52:04.815231Z","caller":"traceutil/trace.go:171","msg":"trace[374286402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:710; }","duration":"163.690821ms","start":"2026-06-15T20:52:04.651529Z","end":"2026-06-15T20:52:04.81522Z","steps":["trace[374286402] 'agreement among raft nodes before linearized reading' (duration: 163.558435ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:12.304673Z","caller":"traceutil/trace.go:171","msg":"trace[1589980197] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"114.313005ms","start":"2026-06-15T20:52:12.190328Z","end":"2026-06-15T20:52:12.304641Z","steps":["trace[1589980197] 'process raft request' (duration: 105.7674ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:12.639393Z","caller":"traceutil/trace.go:171","msg":"trace[909500541] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"232.117199ms","start":"2026-06-15T20:52:12.407247Z","end":"2026-06-15T20:52:12.639364Z","steps":["trace[909500541] 'process raft request' (duration: 229.097673ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:16.087549Z","caller":"traceutil/trace.go:171","msg":"trace[983319335] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"178.037404ms","start":"2026-06-15T20:52:15.90949Z","end":"2026-06-15T20:52:16.087527Z","steps":["trace[983319335] 'process raft request' (duration: 177.902498ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:21.457849Z","caller":"traceutil/trace.go:171","msg":"trace[1263815632] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"247.131631ms","start":"2026-06-15T20:52:21.210698Z","end":"2026-06-15T20:52:21.457829Z","steps":["trace[1263815632] 'process raft request' (duration: 246.976995ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:22.537231Z","caller":"traceutil/trace.go:171","msg":"trace[1417750751] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"167.33102ms","start":"2026-06-15T20:52:22.369881Z","end":"2026-06-15T20:52:22.537212Z","steps":["trace[1417750751] 'process raft request' (duration: 133.237982ms)","trace[1417750751] 'compare' (duration: 33.944101ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:52:22.537349Z","caller":"traceutil/trace.go:171","msg":"trace[1826226277] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:896; }","duration":"162.569637ms","start":"2026-06-15T20:52:22.37476Z","end":"2026-06-15T20:52:22.53733Z","steps":["trace[1826226277] 'read index received' (duration: 128.386376ms)","trace[1826226277] 'applied index is now lower than readState.Index' (duration: 34.181741ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:52:22.537397Z","caller":"traceutil/trace.go:171","msg":"trace[499754070] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"166.430184ms","start":"2026-06-15T20:52:22.370936Z","end":"2026-06-15T20:52:22.537366Z","steps":["trace[499754070] 'process raft request' (duration: 166.242376ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:22.537591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.800696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-06-15T20:52:22.537653Z","caller":"traceutil/trace.go:171","msg":"trace[954597676] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:864; }","duration":"162.89816ms","start":"2026-06-15T20:52:22.374739Z","end":"2026-06-15T20:52:22.537637Z","steps":["trace[954597676] 'agreement among raft nodes before linearized reading' (duration: 162.669731ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:23.272973Z","caller":"traceutil/trace.go:171","msg":"trace[383378281] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"136.337769ms","start":"2026-06-15T20:52:23.136611Z","end":"2026-06-15T20:52:23.272949Z","steps":["trace[383378281] 'process raft request' (duration: 136.289176ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:23.273175Z","caller":"traceutil/trace.go:171","msg":"trace[331681075] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"137.219554ms","start":"2026-06-15T20:52:23.135936Z","end":"2026-06-15T20:52:23.273155Z","steps":["trace[331681075] 'process raft request' (duration: 44.427995ms)","trace[331681075] 'compare' (duration: 92.241927ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:52:23.273254Z","caller":"traceutil/trace.go:171","msg":"trace[1277053959] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"136.989295ms","start":"2026-06-15T20:52:23.13625Z","end":"2026-06-15T20:52:23.273239Z","steps":["trace[1277053959] 'process raft request' (duration: 136.581549ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:26.6402Z","caller":"traceutil/trace.go:171","msg":"trace[1951201886] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:949; }","duration":"324.564113ms","start":"2026-06-15T20:52:26.315616Z","end":"2026-06-15T20:52:26.64018Z","steps":["trace[1951201886] 'read index received' (duration: 103.706344ms)","trace[1951201886] 'applied index is now lower than readState.Index' (duration: 220.856769ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:52:26.640316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.679088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:52:26.640357Z","caller":"traceutil/trace.go:171","msg":"trace[2128494753] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:916; }","duration":"324.766651ms","start":"2026-06-15T20:52:26.31558Z","end":"2026-06-15T20:52:26.640346Z","steps":["trace[2128494753] 'agreement among raft nodes before linearized reading' (duration: 324.680138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:26.640389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:52:26.315563Z","time spent":"324.816443ms","remote":"127.0.0.1:40918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-06-15T20:52:26.640381Z","caller":"traceutil/trace.go:171","msg":"trace[40263205] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"347.113207ms","start":"2026-06-15T20:52:26.293237Z","end":"2026-06-15T20:52:26.64035Z","steps":["trace[40263205] 'process raft request' (duration: 276.544585ms)","trace[40263205] 'compare' (duration: 70.088324ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:52:26.640577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:52:26.293211Z","time spent":"347.275564ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:52:26.708054Z","caller":"traceutil/trace.go:171","msg":"trace[560971765] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"159.752614ms","start":"2026-06-15T20:52:26.548283Z","end":"2026-06-15T20:52:26.708036Z","steps":["trace[560971765] 'process raft request' (duration: 159.704712ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:26.70812Z","caller":"traceutil/trace.go:171","msg":"trace[709129798] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"287.678876ms","start":"2026-06-15T20:52:26.420415Z","end":"2026-06-15T20:52:26.708094Z","steps":["trace[709129798] 'process raft request' (duration: 287.458488ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:38.921281Z","caller":"traceutil/trace.go:171","msg":"trace[1485616041] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"162.61943ms","start":"2026-06-15T20:52:38.758637Z","end":"2026-06-15T20:52:38.921256Z","steps":["trace[1485616041] 'process raft request' (duration: 162.570038ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:38.92133Z","caller":"traceutil/trace.go:171","msg":"trace[1422091827] linearizableReadLoop","detail":"{readStateIndex:1186; appliedIndex:1185; }","duration":"200.86073ms","start":"2026-06-15T20:52:38.720428Z","end":"2026-06-15T20:52:38.921288Z","steps":["trace[1422091827] 'read index received' (duration: 184.198649ms)","trace[1422091827] 'applied index is now lower than readState.Index' (duration: 16.659371ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:52:38.921401Z","caller":"traceutil/trace.go:171","msg":"trace[593539715] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"206.889936ms","start":"2026-06-15T20:52:38.714468Z","end":"2026-06-15T20:52:38.921358Z","steps":["trace[593539715] 'process raft request' (duration: 190.145003ms)","trace[593539715] 'compare' (duration: 16.480504ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:52:38.921531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.00338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:52:38.92158Z","caller":"traceutil/trace.go:171","msg":"trace[1226387259] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1149; }","duration":"160.085733ms","start":"2026-06-15T20:52:38.761481Z","end":"2026-06-15T20:52:38.921567Z","steps":["trace[1226387259] 'agreement among raft nodes before linearized reading' (duration: 159.99728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:38.922372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.940917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/cert-manager-webhook\" ","response":"range_response_count:1 size:2615"} {"level":"info","ts":"2026-06-15T20:52:38.922426Z","caller":"traceutil/trace.go:171","msg":"trace[1129606437] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/cert-manager-webhook; range_end:; response_count:1; response_revision:1149; }","duration":"202.02025ms","start":"2026-06-15T20:52:38.720393Z","end":"2026-06-15T20:52:38.922413Z","steps":["trace[1129606437] 'agreement among raft nodes before linearized reading' (duration: 201.055266ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:46.648749Z","caller":"traceutil/trace.go:171","msg":"trace[567144338] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"180.118833ms","start":"2026-06-15T20:52:46.468609Z","end":"2026-06-15T20:52:46.648728Z","steps":["trace[567144338] 'read index received' (duration: 180.00349ms)","trace[567144338] 'applied index is now lower than readState.Index' (duration: 114.253µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:52:46.648864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.253127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:52:46.648897Z","caller":"traceutil/trace.go:171","msg":"trace[1153312111] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1197; }","duration":"180.34632ms","start":"2026-06-15T20:52:46.468542Z","end":"2026-06-15T20:52:46.648888Z","steps":["trace[1153312111] 'agreement among raft nodes before linearized reading' (duration: 180.270908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:46.649094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:52:46.240604Z","time spent":"408.460273ms","remote":"127.0.0.1:40460","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-15T20:52:50.82062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.560461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:52:50.820693Z","caller":"traceutil/trace.go:171","msg":"trace[943851283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1231; }","duration":"167.706156ms","start":"2026-06-15T20:52:50.652971Z","end":"2026-06-15T20:52:50.820678Z","steps":["trace[943851283] 'range keys from in-memory index tree' (duration: 167.48511ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:52:50.821001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.08551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:2 size:213827"} {"level":"info","ts":"2026-06-15T20:52:50.821052Z","caller":"traceutil/trace.go:171","msg":"trace[1074633921] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:2; response_revision:1231; }","duration":"226.164662ms","start":"2026-06-15T20:52:50.594875Z","end":"2026-06-15T20:52:50.82104Z","steps":["trace[1074633921] 'range keys from in-memory index tree' (duration: 225.670138ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:52:51.995116Z","caller":"traceutil/trace.go:171","msg":"trace[1976384240] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"115.394194ms","start":"2026-06-15T20:52:51.879693Z","end":"2026-06-15T20:52:51.995087Z","steps":["trace[1976384240] 'process raft request' (duration: 115.24032ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:08.623558Z","caller":"traceutil/trace.go:171","msg":"trace[443465188] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"104.6357ms","start":"2026-06-15T20:53:08.518884Z","end":"2026-06-15T20:53:08.62352Z","steps":["trace[443465188] 'process raft request' (duration: 104.348013ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:08.915725Z","caller":"traceutil/trace.go:171","msg":"trace[1847878858] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"158.017177ms","start":"2026-06-15T20:53:08.757677Z","end":"2026-06-15T20:53:08.915694Z","steps":["trace[1847878858] 'process raft request' (duration: 108.027848ms)","trace[1847878858] 'compare' (duration: 49.760943ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:53:08.915767Z","caller":"traceutil/trace.go:171","msg":"trace[1828303651] linearizableReadLoop","detail":"{readStateIndex:1440; appliedIndex:1437; }","duration":"105.79224ms","start":"2026-06-15T20:53:08.809953Z","end":"2026-06-15T20:53:08.915745Z","steps":["trace[1828303651] 'read index received' (duration: 55.703739ms)","trace[1828303651] 'applied index is now lower than readState.Index' (duration: 50.087441ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:53:08.915911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.932414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:53:08.915975Z","caller":"traceutil/trace.go:171","msg":"trace[1632817561] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"154.025922ms","start":"2026-06-15T20:53:08.761928Z","end":"2026-06-15T20:53:08.915954Z","steps":["trace[1632817561] 'process raft request' (duration: 153.765895ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:08.916033Z","caller":"traceutil/trace.go:171","msg":"trace[350795796] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1391; }","duration":"106.109829ms","start":"2026-06-15T20:53:08.809906Z","end":"2026-06-15T20:53:08.916016Z","steps":["trace[350795796] 'agreement among raft nodes before linearized reading' (duration: 105.914443ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:08.915989Z","caller":"traceutil/trace.go:171","msg":"trace[1743327822] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"154.356512ms","start":"2026-06-15T20:53:08.761577Z","end":"2026-06-15T20:53:08.915934Z","steps":["trace[1743327822] 'process raft request' (duration: 154.066763ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:09.476091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.689281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" ","response":"range_response_count:1 size:1041"} {"level":"info","ts":"2026-06-15T20:53:09.476177Z","caller":"traceutil/trace.go:171","msg":"trace[1227366156] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend; range_end:; response_count:1; response_revision:1434; }","duration":"106.831405ms","start":"2026-06-15T20:53:09.369324Z","end":"2026-06-15T20:53:09.476155Z","steps":["trace[1227366156] 'range keys from in-memory index tree' (duration: 106.485926ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:09.476595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.492069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" ","response":"range_response_count:1 size:1020"} {"level":"info","ts":"2026-06-15T20:53:09.476679Z","caller":"traceutil/trace.go:171","msg":"trace[443350149] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx; range_end:; response_count:1; response_revision:1434; }","duration":"105.768288ms","start":"2026-06-15T20:53:09.370891Z","end":"2026-06-15T20:53:09.476659Z","steps":["trace[443350149] 'range keys from in-memory index tree' (duration: 105.162731ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:12.082379Z","caller":"traceutil/trace.go:171","msg":"trace[2032096628] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"318.355302ms","start":"2026-06-15T20:53:11.763988Z","end":"2026-06-15T20:53:12.082344Z","steps":["trace[2032096628] 'process raft request' (duration: 318.124767ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:12.082606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:11.763972Z","time spent":"318.518797ms","remote":"127.0.0.1:40646","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-06-15T20:53:12.086954Z","caller":"traceutil/trace.go:171","msg":"trace[40003066] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"321.930844ms","start":"2026-06-15T20:53:11.764904Z","end":"2026-06-15T20:53:12.086835Z","steps":["trace[40003066] 'process raft request' (duration: 321.695348ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:12.087048Z","caller":"traceutil/trace.go:171","msg":"trace[1149252376] transaction","detail":"{read_only:false; response_revision:1471; number_of_response:1; }","duration":"321.574104ms","start":"2026-06-15T20:53:11.765439Z","end":"2026-06-15T20:53:12.087013Z","steps":["trace[1149252376] 'process raft request' (duration: 321.346449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:12.087177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:11.764897Z","time spent":"322.175379ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:53:12.087206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:11.765415Z","time spent":"321.709708ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:53:27.462829Z","caller":"traceutil/trace.go:171","msg":"trace[1776367966] transaction","detail":"{read_only:false; response_revision:1736; number_of_response:1; }","duration":"107.185696ms","start":"2026-06-15T20:53:27.355584Z","end":"2026-06-15T20:53:27.46277Z","steps":["trace[1776367966] 'process raft request' (duration: 106.899609ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:27.665897Z","caller":"traceutil/trace.go:171","msg":"trace[1378341326] transaction","detail":"{read_only:false; response_revision:1737; number_of_response:1; }","duration":"194.022279ms","start":"2026-06-15T20:53:27.471855Z","end":"2026-06-15T20:53:27.665877Z","steps":["trace[1378341326] 'process raft request' (duration: 189.588377ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:27.671203Z","caller":"traceutil/trace.go:171","msg":"trace[833255661] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"124.484829ms","start":"2026-06-15T20:53:27.546651Z","end":"2026-06-15T20:53:27.671135Z","steps":["trace[833255661] 'process raft request' (duration: 124.025589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:28.08703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.171216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:53:28.087323Z","caller":"traceutil/trace.go:171","msg":"trace[2083653030] transaction","detail":"{read_only:false; response_revision:1741; number_of_response:1; }","duration":"213.467409ms","start":"2026-06-15T20:53:27.873831Z","end":"2026-06-15T20:53:28.087298Z","steps":["trace[2083653030] 'process raft request' (duration: 213.367366ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:28.087628Z","caller":"traceutil/trace.go:171","msg":"trace[1358373333] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"416.656435ms","start":"2026-06-15T20:53:27.670949Z","end":"2026-06-15T20:53:28.087605Z","steps":["trace[1358373333] 'process raft request' (duration: 160.813854ms)","trace[1358373333] 'compare' (duration: 254.87426ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:53:28.087726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:27.670934Z","time spent":"416.733548ms","remote":"127.0.0.1:40660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1692,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:53:28.08789Z","caller":"traceutil/trace.go:171","msg":"trace[1738868042] linearizableReadLoop","detail":"{readStateIndex:1814; appliedIndex:1812; }","duration":"416.838ms","start":"2026-06-15T20:53:27.671025Z","end":"2026-06-15T20:53:28.087863Z","steps":["trace[1738868042] 'read index received' (duration: 7.02881ms)","trace[1738868042] 'applied index is now lower than readState.Index' (duration: 409.80779ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:53:28.088185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.180825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:53:28.088199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.52756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/pxc-operator\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:53:28.08823Z","caller":"traceutil/trace.go:171","msg":"trace[1129136619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1741; }","duration":"416.247817ms","start":"2026-06-15T20:53:27.671969Z","end":"2026-06-15T20:53:28.088217Z","steps":["trace[1129136619] 'agreement among raft nodes before linearized reading' (duration: 416.177336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:28.088098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.320748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-15T20:53:28.088267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:27.67196Z","time spent":"416.299848ms","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T20:53:28.088281Z","caller":"traceutil/trace.go:171","msg":"trace[1239898928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1741; }","duration":"427.537983ms","start":"2026-06-15T20:53:27.660732Z","end":"2026-06-15T20:53:28.08827Z","steps":["trace[1239898928] 'agreement among raft nodes before linearized reading' (duration: 427.257467ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:28.088288Z","caller":"traceutil/trace.go:171","msg":"trace[1936992060] range","detail":"{range_begin:/registry/serviceaccounts/openstack/pxc-operator; range_end:; response_count:0; response_revision:1741; }","duration":"162.599932ms","start":"2026-06-15T20:53:27.925652Z","end":"2026-06-15T20:53:28.088252Z","steps":["trace[1936992060] 'agreement among raft nodes before linearized reading' (duration: 162.49648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:28.088366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:27.660707Z","time spent":"427.648195ms","remote":"127.0.0.1:40646","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-06-15T20:53:28.441612Z","caller":"traceutil/trace.go:171","msg":"trace[276594963] linearizableReadLoop","detail":"{readStateIndex:1817; appliedIndex:1816; }","duration":"279.563431ms","start":"2026-06-15T20:53:28.162019Z","end":"2026-06-15T20:53:28.441583Z","steps":["trace[276594963] 'read index received' (duration: 278.508496ms)","trace[276594963] 'applied index is now lower than readState.Index' (duration: 1.053495ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:53:28.441865Z","caller":"traceutil/trace.go:171","msg":"trace[462304257] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1742; }","duration":"320.252117ms","start":"2026-06-15T20:53:28.121592Z","end":"2026-06-15T20:53:28.441844Z","steps":["trace[462304257] 'process raft request' (duration: 318.971918ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:28.442006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:28.121564Z","time spent":"320.38362ms","remote":"127.0.0.1:40514","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T20:53:28.442021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.939725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-15T20:53:28.441889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.55647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-15T20:53:28.442099Z","caller":"traceutil/trace.go:171","msg":"trace[1566424562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1742; }","duration":"275.055038ms","start":"2026-06-15T20:53:28.16701Z","end":"2026-06-15T20:53:28.442065Z","steps":["trace[1566424562] 'agreement among raft nodes before linearized reading' (duration: 274.933675ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:28.442204Z","caller":"traceutil/trace.go:171","msg":"trace[637919611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1742; }","duration":"279.900477ms","start":"2026-06-15T20:53:28.162269Z","end":"2026-06-15T20:53:28.442169Z","steps":["trace[637919611] 'agreement among raft nodes before linearized reading' (duration: 279.483897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:28.44189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.861707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-15T20:53:28.442425Z","caller":"traceutil/trace.go:171","msg":"trace[731910598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1742; }","duration":"280.44061ms","start":"2026-06-15T20:53:28.161964Z","end":"2026-06-15T20:53:28.442404Z","steps":["trace[731910598] 'agreement among raft nodes before linearized reading' (duration: 279.773745ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:28.996938Z","caller":"traceutil/trace.go:171","msg":"trace[921592732] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"482.447638ms","start":"2026-06-15T20:53:28.51447Z","end":"2026-06-15T20:53:28.996918Z","steps":["trace[921592732] 'process raft request' (duration: 476.707839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:28.997063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:28.514441Z","time spent":"482.572341ms","remote":"127.0.0.1:40496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":75430,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-15T20:53:29.152945Z","caller":"traceutil/trace.go:171","msg":"trace[768132058] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"112.961923ms","start":"2026-06-15T20:53:29.039953Z","end":"2026-06-15T20:53:29.152915Z","steps":["trace[768132058] 'process raft request' (duration: 90.08997ms)","trace[768132058] 'compare' (duration: 22.720359ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:53:39.567955Z","caller":"traceutil/trace.go:171","msg":"trace[597602486] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1971; }","duration":"275.146373ms","start":"2026-06-15T20:53:39.292783Z","end":"2026-06-15T20:53:39.567929Z","steps":["trace[597602486] 'read index received' (duration: 275.029501ms)","trace[597602486] 'applied index is now lower than readState.Index' (duration: 115.562µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:53:39.568201Z","caller":"traceutil/trace.go:171","msg":"trace[213377064] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"295.494681ms","start":"2026-06-15T20:53:39.272694Z","end":"2026-06-15T20:53:39.568189Z","steps":["trace[213377064] 'process raft request' (duration: 294.885098ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:39.568501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.670515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-15T20:53:39.568583Z","caller":"traceutil/trace.go:171","msg":"trace[839794273] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1895; }","duration":"275.823728ms","start":"2026-06-15T20:53:39.292738Z","end":"2026-06-15T20:53:39.568561Z","steps":["trace[839794273] 'agreement among raft nodes before linearized reading' (duration: 275.561612ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:40.622755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.948423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:53:40.623154Z","caller":"traceutil/trace.go:171","msg":"trace[1093883355] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"900.345273ms","start":"2026-06-15T20:53:39.722787Z","end":"2026-06-15T20:53:40.623132Z","steps":["trace[1093883355] 'process raft request' (duration: 900.285652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:40.623395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:39.722765Z","time spent":"900.575388ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:53:40.623756Z","caller":"traceutil/trace.go:171","msg":"trace[1827595620] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"1.25799373s","start":"2026-06-15T20:53:39.365728Z","end":"2026-06-15T20:53:40.623722Z","steps":["trace[1827595620] 'process raft request' (duration: 1.257235664s)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:40.623767Z","caller":"traceutil/trace.go:171","msg":"trace[2088636398] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"1.311826952s","start":"2026-06-15T20:53:39.311903Z","end":"2026-06-15T20:53:40.62373Z","steps":["trace[2088636398] 'process raft request' (duration: 511.801058ms)","trace[2088636398] 'compare' (duration: 798.815899ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:53:40.623885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109234984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-15T20:53:40.623903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135094288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10667"} {"level":"info","ts":"2026-06-15T20:53:40.623962Z","caller":"traceutil/trace.go:171","msg":"trace[2115365322] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1898; }","duration":"1.109353087s","start":"2026-06-15T20:53:39.514592Z","end":"2026-06-15T20:53:40.623945Z","steps":["trace[2115365322] 'agreement among raft nodes before linearized reading' (duration: 1.109155723s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:40.623957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:39.365708Z","time spent":"1.258137334s","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:53:40.62397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158275745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a88c0c50-6309-4c26-b23a-927428955add\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-15T20:53:40.624059Z","caller":"traceutil/trace.go:171","msg":"trace[2014565221] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a88c0c50-6309-4c26-b23a-927428955add; range_end:; response_count:1; response_revision:1898; }","duration":"1.158381407s","start":"2026-06-15T20:53:39.465661Z","end":"2026-06-15T20:53:40.624043Z","steps":["trace[2014565221] 'agreement among raft nodes before linearized reading' (duration: 1.158153833s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:40.624111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:39.465648Z","time spent":"1.158449659s","remote":"127.0.0.1:40586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a88c0c50-6309-4c26-b23a-927428955add\" "} {"level":"warn","ts":"2026-06-15T20:53:40.623968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:39.311883Z","time spent":"1.311977815s","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:53:40.623761Z","caller":"traceutil/trace.go:171","msg":"trace[515655067] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1972; }","duration":"1.055708749s","start":"2026-06-15T20:53:39.568028Z","end":"2026-06-15T20:53:40.623737Z","steps":["trace[515655067] 'read index received' (duration: 255.698834ms)","trace[515655067] 'applied index is now lower than readState.Index' (duration: 800.007144ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:53:40.623973Z","caller":"traceutil/trace.go:171","msg":"trace[302733296] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1898; }","duration":"1.13519905s","start":"2026-06-15T20:53:39.488757Z","end":"2026-06-15T20:53:40.623956Z","steps":["trace[302733296] 'agreement among raft nodes before linearized reading' (duration: 1.134995356s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:40.624476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:39.488723Z","time spent":"1.135713541s","remote":"127.0.0.1:40586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10690,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-06-15T20:53:40.624005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:39.514574Z","time spent":"1.109423468s","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-15T20:53:40.624612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.196419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-15T20:53:40.624651Z","caller":"traceutil/trace.go:171","msg":"trace[179455642] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1898; }","duration":"986.27841ms","start":"2026-06-15T20:53:39.638362Z","end":"2026-06-15T20:53:40.62464Z","steps":["trace[179455642] 'agreement among raft nodes before linearized reading' (duration: 986.082386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:40.624681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:39.63834Z","time spent":"986.333921ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":248,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:53:40.624731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.220887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-15T20:53:40.624794Z","caller":"traceutil/trace.go:171","msg":"trace[1355592983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1898; }","duration":"104.3499ms","start":"2026-06-15T20:53:40.520429Z","end":"2026-06-15T20:53:40.624779Z","steps":["trace[1355592983] 'agreement among raft nodes before linearized reading' (duration: 104.198007ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:40.624927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.109693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:53:40.62494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.433929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-15T20:53:40.624975Z","caller":"traceutil/trace.go:171","msg":"trace[515714342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1898; }","duration":"472.49294ms","start":"2026-06-15T20:53:40.152474Z","end":"2026-06-15T20:53:40.624967Z","steps":["trace[515714342] 'agreement among raft nodes before linearized reading' (duration: 472.368208ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:40.624972Z","caller":"traceutil/trace.go:171","msg":"trace[2138780345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1898; }","duration":"973.186194ms","start":"2026-06-15T20:53:39.651773Z","end":"2026-06-15T20:53:40.624959Z","steps":["trace[2138780345] 'agreement among raft nodes before linearized reading' (duration: 973.125523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:40.625004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:40.152443Z","time spent":"472.554622ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:53:40.625028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:39.651751Z","time spent":"973.267926ms","remote":"127.0.0.1:40422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:53:40.625074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.03248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-15T20:53:40.625146Z","caller":"traceutil/trace.go:171","msg":"trace[589363856] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1898; }","duration":"913.134202ms","start":"2026-06-15T20:53:39.711994Z","end":"2026-06-15T20:53:40.625128Z","steps":["trace[589363856] 'agreement among raft nodes before linearized reading' (duration: 912.979869ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:40.625201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:39.711973Z","time spent":"913.214374ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-06-15T20:53:41.200341Z","caller":"traceutil/trace.go:171","msg":"trace[1004353991] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"568.634484ms","start":"2026-06-15T20:53:40.63168Z","end":"2026-06-15T20:53:41.200315Z","steps":["trace[1004353991] 'process raft request' (duration: 568.371138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:41.200518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:40.631665Z","time spent":"568.747335ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:53:41.200759Z","caller":"traceutil/trace.go:171","msg":"trace[715858643] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"568.967581ms","start":"2026-06-15T20:53:40.631756Z","end":"2026-06-15T20:53:41.200723Z","steps":["trace[715858643] 'process raft request' (duration: 568.517122ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:41.200791Z","caller":"traceutil/trace.go:171","msg":"trace[196985530] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"568.414759ms","start":"2026-06-15T20:53:40.632351Z","end":"2026-06-15T20:53:41.200765Z","steps":["trace[196985530] 'process raft request' (duration: 568.183654ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:41.200843Z","caller":"traceutil/trace.go:171","msg":"trace[632216968] linearizableReadLoop","detail":"{readStateIndex:1982; appliedIndex:1975; }","duration":"548.666047ms","start":"2026-06-15T20:53:40.652153Z","end":"2026-06-15T20:53:41.200819Z","steps":["trace[632216968] 'read index received' (duration: 10.53855ms)","trace[632216968] 'applied index is now lower than readState.Index' (duration: 538.126527ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:53:41.200857Z","caller":"traceutil/trace.go:171","msg":"trace[711152748] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"568.275946ms","start":"2026-06-15T20:53:40.632544Z","end":"2026-06-15T20:53:41.20082Z","steps":["trace[711152748] 'process raft request' (duration: 568.087083ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:41.200898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:40.631747Z","time spent":"569.076573ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:53:41.200935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:40.632338Z","time spent":"568.522351ms","remote":"127.0.0.1:40646","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-06-15T20:53:41.200985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:40.632539Z","time spent":"568.378408ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:53:41.201028Z","caller":"traceutil/trace.go:171","msg":"trace[382022666] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"565.09848ms","start":"2026-06-15T20:53:40.635918Z","end":"2026-06-15T20:53:41.201011Z","steps":["trace[382022666] 'process raft request' (duration: 564.794354ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:41.201089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:40.635913Z","time spent":"565.156671ms","remote":"127.0.0.1:40586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10966,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:53:41.201155Z","caller":"traceutil/trace.go:171","msg":"trace[1734002107] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"568.123723ms","start":"2026-06-15T20:53:40.63302Z","end":"2026-06-15T20:53:41.201144Z","steps":["trace[1734002107] 'process raft request' (duration: 567.643923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:41.201234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:40.633015Z","time spent":"568.179284ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:53:41.201303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.149637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:53:41.201331Z","caller":"traceutil/trace.go:171","msg":"trace[1091048887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1905; }","duration":"549.200348ms","start":"2026-06-15T20:53:40.652124Z","end":"2026-06-15T20:53:41.201324Z","steps":["trace[1091048887] 'agreement among raft nodes before linearized reading' (duration: 549.150546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:41.201346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:40.652115Z","time spent":"549.228549ms","remote":"127.0.0.1:40422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T20:53:41.206859Z","caller":"traceutil/trace.go:171","msg":"trace[521072663] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"426.575126ms","start":"2026-06-15T20:53:40.780269Z","end":"2026-06-15T20:53:41.206844Z","steps":["trace[521072663] 'process raft request' (duration: 426.383562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:41.206948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:53:40.780235Z","time spent":"426.666957ms","remote":"127.0.0.1:40646","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-06-15T20:53:46.799497Z","caller":"traceutil/trace.go:171","msg":"trace[722286464] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"153.876223ms","start":"2026-06-15T20:53:46.645595Z","end":"2026-06-15T20:53:46.799472Z","steps":["trace[722286464] 'process raft request' (duration: 153.660479ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:46.847075Z","caller":"traceutil/trace.go:171","msg":"trace[550980295] linearizableReadLoop","detail":"{readStateIndex:2038; appliedIndex:2036; }","duration":"180.237144ms","start":"2026-06-15T20:53:46.666808Z","end":"2026-06-15T20:53:46.847046Z","steps":["trace[550980295] 'read index received' (duration: 132.564193ms)","trace[550980295] 'applied index is now lower than readState.Index' (duration: 47.671901ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:53:46.847121Z","caller":"traceutil/trace.go:171","msg":"trace[732424091] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"199.468122ms","start":"2026-06-15T20:53:46.647633Z","end":"2026-06-15T20:53:46.847101Z","steps":["trace[732424091] 'process raft request' (duration: 199.310109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:46.847379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.821622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-15T20:53:46.847365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.529831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-15T20:53:46.847419Z","caller":"traceutil/trace.go:171","msg":"trace[1595261737] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1958; }","duration":"160.905184ms","start":"2026-06-15T20:53:46.686502Z","end":"2026-06-15T20:53:46.847407Z","steps":["trace[1595261737] 'agreement among raft nodes before linearized reading' (duration: 160.67742ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:46.847446Z","caller":"traceutil/trace.go:171","msg":"trace[129032103] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1958; }","duration":"180.653522ms","start":"2026-06-15T20:53:46.666767Z","end":"2026-06-15T20:53:46.847421Z","steps":["trace[129032103] 'agreement among raft nodes before linearized reading' (duration: 180.386057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:47.86167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.490755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11664"} {"level":"info","ts":"2026-06-15T20:53:47.861761Z","caller":"traceutil/trace.go:171","msg":"trace[967828550] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1965; }","duration":"120.624677ms","start":"2026-06-15T20:53:47.741115Z","end":"2026-06-15T20:53:47.86174Z","steps":["trace[967828550] 'range keys from in-memory index tree' (duration: 120.321662ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:49.033197Z","caller":"traceutil/trace.go:171","msg":"trace[1333698547] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"222.522231ms","start":"2026-06-15T20:53:48.810652Z","end":"2026-06-15T20:53:49.033174Z","steps":["trace[1333698547] 'process raft request' (duration: 222.338108ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:49.035575Z","caller":"traceutil/trace.go:171","msg":"trace[72241991] linearizableReadLoop","detail":"{readStateIndex:2049; appliedIndex:2047; }","duration":"168.284121ms","start":"2026-06-15T20:53:48.86724Z","end":"2026-06-15T20:53:49.035524Z","steps":["trace[72241991] 'read index received' (duration: 165.73354ms)","trace[72241991] 'applied index is now lower than readState.Index' (duration: 2.549501ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:53:49.035678Z","caller":"traceutil/trace.go:171","msg":"trace[988202969] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"182.051675ms","start":"2026-06-15T20:53:48.853577Z","end":"2026-06-15T20:53:49.035629Z","steps":["trace[988202969] 'process raft request' (duration: 181.78728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:49.03588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.626568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-15T20:53:49.035953Z","caller":"traceutil/trace.go:171","msg":"trace[258788054] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1969; }","duration":"168.73213ms","start":"2026-06-15T20:53:48.867205Z","end":"2026-06-15T20:53:49.035937Z","steps":["trace[258788054] 'agreement among raft nodes before linearized reading' (duration: 168.489065ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:51.126307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.649068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:53:51.126388Z","caller":"traceutil/trace.go:171","msg":"trace[1378685989] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1977; }","duration":"144.780089ms","start":"2026-06-15T20:53:50.98159Z","end":"2026-06-15T20:53:51.12637Z","steps":["trace[1378685989] 'count revisions from in-memory index tree' (duration: 144.521685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:53:51.578526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.160432ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-15T20:53:51.578621Z","caller":"traceutil/trace.go:171","msg":"trace[143415474] linearizableReadLoop","detail":"{readStateIndex:2063; appliedIndex:2062; }","duration":"148.109433ms","start":"2026-06-15T20:53:51.430488Z","end":"2026-06-15T20:53:51.578597Z","steps":["trace[143415474] 'read index received' (duration: 36.73µs)","trace[143415474] 'applied index is now lower than readState.Index' (duration: 148.071663ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:53:51.579117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.622164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-15T20:53:51.579151Z","caller":"traceutil/trace.go:171","msg":"trace[1623526541] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1982; }","duration":"148.696005ms","start":"2026-06-15T20:53:51.430447Z","end":"2026-06-15T20:53:51.579143Z","steps":["trace[1623526541] 'agreement among raft nodes before linearized reading' (duration: 148.554952ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:55.993076Z","caller":"traceutil/trace.go:171","msg":"trace[486845455] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"293.31215ms","start":"2026-06-15T20:53:55.69974Z","end":"2026-06-15T20:53:55.993052Z","steps":["trace[486845455] 'process raft request' (duration: 293.262939ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:55.993333Z","caller":"traceutil/trace.go:171","msg":"trace[2050156561] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"293.435732ms","start":"2026-06-15T20:53:55.699733Z","end":"2026-06-15T20:53:55.993169Z","steps":["trace[2050156561] 'process raft request' (duration: 293.113876ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:55.999547Z","caller":"traceutil/trace.go:171","msg":"trace[597306395] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"298.636641ms","start":"2026-06-15T20:53:55.700886Z","end":"2026-06-15T20:53:55.999522Z","steps":["trace[597306395] 'process raft request' (duration: 298.483918ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:53:55.999781Z","caller":"traceutil/trace.go:171","msg":"trace[662179166] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"288.67036ms","start":"2026-06-15T20:53:55.711098Z","end":"2026-06-15T20:53:55.999768Z","steps":["trace[662179166] 'process raft request' (duration: 288.377715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:00.5099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955415720641102,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T20:54:00.913938Z","caller":"traceutil/trace.go:171","msg":"trace[1476254798] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"906.479188ms","start":"2026-06-15T20:54:00.007431Z","end":"2026-06-15T20:54:00.91391Z","steps":["trace[1476254798] 'process raft request' (duration: 906.320836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:00.914085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:54:00.007413Z","time spent":"906.591541ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:54:00.915263Z","caller":"traceutil/trace.go:171","msg":"trace[1449608002] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"906.605081ms","start":"2026-06-15T20:54:00.008626Z","end":"2026-06-15T20:54:00.915231Z","steps":["trace[1449608002] 'process raft request' (duration: 905.877108ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:00.915451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:54:00.008609Z","time spent":"906.739093ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:54:00.915478Z","caller":"traceutil/trace.go:171","msg":"trace[1681618852] linearizableReadLoop","detail":"{readStateIndex:2137; appliedIndex:2134; }","duration":"906.60931ms","start":"2026-06-15T20:54:00.008818Z","end":"2026-06-15T20:54:00.915427Z","steps":["trace[1681618852] 'read index received' (duration: 904.915108ms)","trace[1681618852] 'applied index is now lower than readState.Index' (duration: 1.692282ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:54:00.915651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.289694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:54:00.915691Z","caller":"traceutil/trace.go:171","msg":"trace[296925439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2055; }","duration":"264.363655ms","start":"2026-06-15T20:54:00.651316Z","end":"2026-06-15T20:54:00.915679Z","steps":["trace[296925439] 'agreement among raft nodes before linearized reading' (duration: 264.295974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:00.91572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.886946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-15T20:54:00.915775Z","caller":"traceutil/trace.go:171","msg":"trace[1152703450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2055; }","duration":"906.978997ms","start":"2026-06-15T20:54:00.008766Z","end":"2026-06-15T20:54:00.915745Z","steps":["trace[1152703450] 'agreement among raft nodes before linearized reading' (duration: 906.813654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:00.915776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.799043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-15T20:54:00.91581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:54:00.008737Z","time spent":"907.065599ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-15T20:54:00.915851Z","caller":"traceutil/trace.go:171","msg":"trace[2071472903] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2055; }","duration":"729.918324ms","start":"2026-06-15T20:54:00.185913Z","end":"2026-06-15T20:54:00.915831Z","steps":["trace[2071472903] 'agreement among raft nodes before linearized reading' (duration: 729.689991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:00.915902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:54:00.185894Z","time spent":"729.993867ms","remote":"127.0.0.1:36090","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":"info","ts":"2026-06-15T20:54:00.915598Z","caller":"traceutil/trace.go:171","msg":"trace[1645358971] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"905.790576ms","start":"2026-06-15T20:54:00.009766Z","end":"2026-06-15T20:54:00.915556Z","steps":["trace[1645358971] 'process raft request' (duration: 905.401079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:00.916575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:54:00.009751Z","time spent":"906.746543ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:54:12.36181Z","caller":"traceutil/trace.go:171","msg":"trace[59022382] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"105.914385ms","start":"2026-06-15T20:54:12.255876Z","end":"2026-06-15T20:54:12.36179Z","steps":["trace[59022382] 'process raft request' (duration: 105.786573ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:54:12.751308Z","caller":"traceutil/trace.go:171","msg":"trace[1070729692] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"119.470154ms","start":"2026-06-15T20:54:12.631814Z","end":"2026-06-15T20:54:12.751284Z","steps":["trace[1070729692] 'process raft request' (duration: 119.312501ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:54:17.153295Z","caller":"traceutil/trace.go:171","msg":"trace[653432321] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"134.197835ms","start":"2026-06-15T20:54:17.019076Z","end":"2026-06-15T20:54:17.153274Z","steps":["trace[653432321] 'process raft request' (duration: 134.054173ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:54:17.153916Z","caller":"traceutil/trace.go:171","msg":"trace[664063717] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"133.195557ms","start":"2026-06-15T20:54:17.020705Z","end":"2026-06-15T20:54:17.153901Z","steps":["trace[664063717] 'process raft request' (duration: 133.077665ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:54:17.15401Z","caller":"traceutil/trace.go:171","msg":"trace[623268185] linearizableReadLoop","detail":"{readStateIndex:2234; appliedIndex:2231; }","duration":"101.821964ms","start":"2026-06-15T20:54:17.052177Z","end":"2026-06-15T20:54:17.153999Z","steps":["trace[623268185] 'read index received' (duration: 100.9736ms)","trace[623268185] 'applied index is now lower than readState.Index' (duration: 847.184µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:54:17.154141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.958236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-15T20:54:17.154168Z","caller":"traceutil/trace.go:171","msg":"trace[1259579366] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2148; }","duration":"102.029318ms","start":"2026-06-15T20:54:17.052132Z","end":"2026-06-15T20:54:17.154161Z","steps":["trace[1259579366] 'agreement among raft nodes before linearized reading' (duration: 101.903366ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:54:17.154171Z","caller":"traceutil/trace.go:171","msg":"trace[1968883116] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"131.447257ms","start":"2026-06-15T20:54:17.022672Z","end":"2026-06-15T20:54:17.154119Z","steps":["trace[1968883116] 'process raft request' (duration: 131.192513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:23.549573Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955415720641409,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T20:54:23.582579Z","caller":"traceutil/trace.go:171","msg":"trace[665156872] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"546.975484ms","start":"2026-06-15T20:54:23.035575Z","end":"2026-06-15T20:54:23.58255Z","steps":["trace[665156872] 'process raft request' (duration: 546.67839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:23.582741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:54:23.035559Z","time spent":"547.099067ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:54:23.608913Z","caller":"traceutil/trace.go:171","msg":"trace[1340696663] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2265; }","duration":"559.623518ms","start":"2026-06-15T20:54:23.049264Z","end":"2026-06-15T20:54:23.608888Z","steps":["trace[1340696663] 'read index received' (duration: 533.021228ms)","trace[1340696663] 'applied index is now lower than readState.Index' (duration: 26.60113ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:54:23.6092Z","caller":"traceutil/trace.go:171","msg":"trace[1804639350] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"361.026668ms","start":"2026-06-15T20:54:23.248164Z","end":"2026-06-15T20:54:23.609191Z","steps":["trace[1804639350] 'process raft request' (duration: 360.5168ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:23.609286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:54:23.248136Z","time spent":"361.09287ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:54:23.609311Z","caller":"traceutil/trace.go:171","msg":"trace[1466087360] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"349.064307ms","start":"2026-06-15T20:54:23.260222Z","end":"2026-06-15T20:54:23.609287Z","steps":["trace[1466087360] 'process raft request' (duration: 348.598118ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:54:23.60936Z","caller":"traceutil/trace.go:171","msg":"trace[525499453] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"124.822152ms","start":"2026-06-15T20:54:23.484514Z","end":"2026-06-15T20:54:23.609336Z","steps":["trace[525499453] 'process raft request' (duration: 124.335904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:23.60941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:54:23.260187Z","time spent":"349.172318ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:54:23.60951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.136105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-15T20:54:23.609542Z","caller":"traceutil/trace.go:171","msg":"trace[1263512228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2181; }","duration":"434.223727ms","start":"2026-06-15T20:54:23.175311Z","end":"2026-06-15T20:54:23.609535Z","steps":["trace[1263512228] 'agreement among raft nodes before linearized reading' (duration: 434.110785ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:23.609564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:54:23.17527Z","time spent":"434.288258ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T20:54:23.609654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.019644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-15T20:54:23.609623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.30534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12382"} {"level":"warn","ts":"2026-06-15T20:54:23.609739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.494606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-15T20:54:23.609738Z","caller":"traceutil/trace.go:171","msg":"trace[50012853] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2181; }","duration":"560.484262ms","start":"2026-06-15T20:54:23.049228Z","end":"2026-06-15T20:54:23.609712Z","steps":["trace[50012853] 'agreement among raft nodes before linearized reading' (duration: 560.048506ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:54:23.609772Z","caller":"traceutil/trace.go:171","msg":"trace[1983555132] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2181; }","duration":"423.551696ms","start":"2026-06-15T20:54:23.18621Z","end":"2026-06-15T20:54:23.609762Z","steps":["trace[1983555132] 'agreement among raft nodes before linearized reading' (duration: 423.480235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:23.609793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:54:23.186192Z","time spent":"423.595857ms","remote":"127.0.0.1:36090","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-06-15T20:54:23.609804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:54:23.049209Z","time spent":"560.584724ms","remote":"127.0.0.1:40586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12405,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-15T20:54:23.609987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.063703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-15T20:54:23.610054Z","caller":"traceutil/trace.go:171","msg":"trace[1959535901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2181; }","duration":"431.173885ms","start":"2026-06-15T20:54:23.178864Z","end":"2026-06-15T20:54:23.610038Z","steps":["trace[1959535901] 'agreement among raft nodes before linearized reading' (duration: 431.015262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:23.610073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.007863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-15T20:54:23.610097Z","caller":"traceutil/trace.go:171","msg":"trace[1194798898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2181; }","duration":"431.034613ms","start":"2026-06-15T20:54:23.179057Z","end":"2026-06-15T20:54:23.610091Z","steps":["trace[1194798898] 'agreement among raft nodes before linearized reading' (duration: 430.970502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:23.610105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:54:23.178838Z","time spent":"431.254837ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:54:23.610116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:54:23.179031Z","time spent":"431.080234ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-15T20:54:23.60968Z","caller":"traceutil/trace.go:171","msg":"trace[1447597103] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2181; }","duration":"320.081806ms","start":"2026-06-15T20:54:23.289592Z","end":"2026-06-15T20:54:23.609674Z","steps":["trace[1447597103] 'agreement among raft nodes before linearized reading' (duration: 320.022575ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:23.610214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:54:23.289546Z","time spent":"320.657455ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-15T20:54:23.766808Z","caller":"traceutil/trace.go:171","msg":"trace[778526758] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"151.27915ms","start":"2026-06-15T20:54:23.615469Z","end":"2026-06-15T20:54:23.766748Z","steps":["trace[778526758] 'process raft request' (duration: 76.971572ms)","trace[778526758] 'compare' (duration: 74.059563ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:54:23.766913Z","caller":"traceutil/trace.go:171","msg":"trace[1013417047] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"151.35546ms","start":"2026-06-15T20:54:23.615543Z","end":"2026-06-15T20:54:23.766899Z","steps":["trace[1013417047] 'process raft request' (duration: 151.139446ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:54:23.767084Z","caller":"traceutil/trace.go:171","msg":"trace[221056121] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"151.471812ms","start":"2026-06-15T20:54:23.615596Z","end":"2026-06-15T20:54:23.767068Z","steps":["trace[221056121] 'process raft request' (duration: 151.238748ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:54:23.767185Z","caller":"traceutil/trace.go:171","msg":"trace[1229266059] linearizableReadLoop","detail":"{readStateIndex:2274; appliedIndex:2269; }","duration":"145.755106ms","start":"2026-06-15T20:54:23.621421Z","end":"2026-06-15T20:54:23.767176Z","steps":["trace[1229266059] 'read index received' (duration: 11.890231ms)","trace[1229266059] 'applied index is now lower than readState.Index' (duration: 133.863875ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:54:23.767266Z","caller":"traceutil/trace.go:171","msg":"trace[84107013] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"150.113909ms","start":"2026-06-15T20:54:23.617145Z","end":"2026-06-15T20:54:23.767259Z","steps":["trace[84107013] 'process raft request' (duration: 149.871435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:23.768493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.770382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:54:23.768676Z","caller":"traceutil/trace.go:171","msg":"trace[8764832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2186; }","duration":"118.003376ms","start":"2026-06-15T20:54:23.650592Z","end":"2026-06-15T20:54:23.768596Z","steps":["trace[8764832] 'agreement among raft nodes before linearized reading' (duration: 117.704121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:23.768814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.362912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:54:23.768874Z","caller":"traceutil/trace.go:171","msg":"trace[2092875033] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2186; }","duration":"147.464165ms","start":"2026-06-15T20:54:23.621384Z","end":"2026-06-15T20:54:23.768848Z","steps":["trace[2092875033] 'agreement among raft nodes before linearized reading' (duration: 147.361062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:54:24.188701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.513119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:54:24.188807Z","caller":"traceutil/trace.go:171","msg":"trace[1673699638] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2187; }","duration":"175.711932ms","start":"2026-06-15T20:54:24.013073Z","end":"2026-06-15T20:54:24.188785Z","steps":["trace[1673699638] 'count revisions from in-memory index tree' (duration: 175.361136ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:56:24.051Z","caller":"traceutil/trace.go:171","msg":"trace[1887396893] transaction","detail":"{read_only:false; response_revision:2925; number_of_response:1; }","duration":"134.254934ms","start":"2026-06-15T20:56:23.916699Z","end":"2026-06-15T20:56:24.050954Z","steps":["trace[1887396893] 'process raft request' (duration: 133.404221ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:56:29.392562Z","caller":"traceutil/trace.go:171","msg":"trace[532325645] linearizableReadLoop","detail":"{readStateIndex:3074; appliedIndex:3073; }","duration":"124.978256ms","start":"2026-06-15T20:56:29.267555Z","end":"2026-06-15T20:56:29.392533Z","steps":["trace[532325645] 'read index received' (duration: 124.677855ms)","trace[532325645] 'applied index is now lower than readState.Index' (duration: 299.181µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:56:29.39264Z","caller":"traceutil/trace.go:171","msg":"trace[1301475743] transaction","detail":"{read_only:false; response_revision:2959; number_of_response:1; }","duration":"267.596874ms","start":"2026-06-15T20:56:29.124994Z","end":"2026-06-15T20:56:29.392591Z","steps":["trace[1301475743] 'process raft request' (duration: 267.322533ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:56:29.392819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.206965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:56:29.392897Z","caller":"traceutil/trace.go:171","msg":"trace[1663331704] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2959; }","duration":"125.395491ms","start":"2026-06-15T20:56:29.267482Z","end":"2026-06-15T20:56:29.392878Z","steps":["trace[1663331704] 'agreement among raft nodes before linearized reading' (duration: 125.143912ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:58:23.361616Z","caller":"traceutil/trace.go:171","msg":"trace[1406577630] transaction","detail":"{read_only:false; response_revision:3633; number_of_response:1; }","duration":"140.638762ms","start":"2026-06-15T20:58:23.220953Z","end":"2026-06-15T20:58:23.361592Z","steps":["trace[1406577630] 'process raft request' (duration: 140.505049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:58:49.057494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.681417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:58:49.05759Z","caller":"traceutil/trace.go:171","msg":"trace[618299292] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:3779; }","duration":"227.793249ms","start":"2026-06-15T20:58:48.829774Z","end":"2026-06-15T20:58:49.057567Z","steps":["trace[618299292] 'count revisions from in-memory index tree' (duration: 227.604906ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:58:49.057579Z","caller":"traceutil/trace.go:171","msg":"trace[375250541] transaction","detail":"{read_only:false; response_revision:3780; number_of_response:1; }","duration":"141.536102ms","start":"2026-06-15T20:58:48.916001Z","end":"2026-06-15T20:58:49.057537Z","steps":["trace[375250541] 'process raft request' (duration: 129.028131ms)","trace[375250541] 'compare' (duration: 11.979899ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:58:53.01132Z","caller":"traceutil/trace.go:171","msg":"trace[664393129] transaction","detail":"{read_only:false; response_revision:3796; number_of_response:1; }","duration":"200.532792ms","start":"2026-06-15T20:58:52.810732Z","end":"2026-06-15T20:58:53.011264Z","steps":["trace[664393129] 'process raft request' (duration: 105.617115ms)","trace[664393129] 'compare' (duration: 94.60801ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:58:53.013455Z","caller":"traceutil/trace.go:171","msg":"trace[1093080069] transaction","detail":"{read_only:false; response_revision:3797; number_of_response:1; }","duration":"196.213362ms","start":"2026-06-15T20:58:52.817209Z","end":"2026-06-15T20:58:53.013422Z","steps":["trace[1093080069] 'process raft request' (duration: 196.063239ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:58:53.688246Z","caller":"traceutil/trace.go:171","msg":"trace[830121743] transaction","detail":"{read_only:false; response_revision:3803; number_of_response:1; }","duration":"259.681502ms","start":"2026-06-15T20:58:53.428536Z","end":"2026-06-15T20:58:53.688217Z","steps":["trace[830121743] 'process raft request' (duration: 191.251878ms)","trace[830121743] 'compare' (duration: 67.918912ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:58:56.933662Z","caller":"traceutil/trace.go:171","msg":"trace[1378100358] transaction","detail":"{read_only:false; response_revision:3818; number_of_response:1; }","duration":"161.22049ms","start":"2026-06-15T20:58:56.772402Z","end":"2026-06-15T20:58:56.933623Z","steps":["trace[1378100358] 'process raft request' (duration: 160.994185ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:59:05.653716Z","caller":"traceutil/trace.go:171","msg":"trace[2035521948] transaction","detail":"{read_only:false; response_revision:3867; number_of_response:1; }","duration":"124.297518ms","start":"2026-06-15T20:59:05.529382Z","end":"2026-06-15T20:59:05.65368Z","steps":["trace[2035521948] 'process raft request' (duration: 50.802786ms)","trace[2035521948] 'compare' (duration: 73.155464ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:59:05.654227Z","caller":"traceutil/trace.go:171","msg":"trace[1010843216] transaction","detail":"{read_only:false; response_revision:3868; number_of_response:1; }","duration":"123.819367ms","start":"2026-06-15T20:59:05.530377Z","end":"2026-06-15T20:59:05.654196Z","steps":["trace[1010843216] 'process raft request' (duration: 123.218774ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:59:25.397092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.791783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:59:25.397188Z","caller":"traceutil/trace.go:171","msg":"trace[1829319897] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3975; }","duration":"111.972626ms","start":"2026-06-15T20:59:25.285193Z","end":"2026-06-15T20:59:25.397165Z","steps":["trace[1829319897] 'range keys from in-memory index tree' (duration: 111.647808ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:59:27.233881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.337809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:59:27.234255Z","caller":"traceutil/trace.go:171","msg":"trace[1049170869] transaction","detail":"{read_only:false; response_revision:3987; number_of_response:1; }","duration":"227.677772ms","start":"2026-06-15T20:59:27.00654Z","end":"2026-06-15T20:59:27.234218Z","steps":["trace[1049170869] 'process raft request' (duration: 118.918605ms)","trace[1049170869] 'compare' (duration: 108.121823ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:59:27.23452Z","caller":"traceutil/trace.go:171","msg":"trace[1241676504] transaction","detail":"{read_only:false; response_revision:3988; number_of_response:1; }","duration":"223.963292ms","start":"2026-06-15T20:59:27.010526Z","end":"2026-06-15T20:59:27.23449Z","steps":["trace[1241676504] 'process raft request' (duration: 223.771428ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:59:27.234574Z","caller":"traceutil/trace.go:171","msg":"trace[1909399002] transaction","detail":"{read_only:false; response_revision:3989; number_of_response:1; }","duration":"159.826207ms","start":"2026-06-15T20:59:27.074707Z","end":"2026-06-15T20:59:27.234533Z","steps":["trace[1909399002] 'process raft request' (duration: 159.678734ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:59:33.994002Z","caller":"traceutil/trace.go:171","msg":"trace[809589095] transaction","detail":"{read_only:false; response_revision:4086; number_of_response:1; }","duration":"127.212659ms","start":"2026-06-15T20:59:33.866745Z","end":"2026-06-15T20:59:33.993957Z","steps":["trace[809589095] 'process raft request' (duration: 100.080077ms)","trace[809589095] 'compare' (duration: 26.772934ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:59:40.880179Z","caller":"traceutil/trace.go:171","msg":"trace[121845431] transaction","detail":"{read_only:false; response_revision:4121; number_of_response:1; }","duration":"131.799258ms","start":"2026-06-15T20:59:40.748322Z","end":"2026-06-15T20:59:40.880121Z","steps":["trace[121845431] 'process raft request' (duration: 131.343958ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:02.354896Z","caller":"traceutil/trace.go:171","msg":"trace[1377639445] transaction","detail":"{read_only:false; response_revision:4242; number_of_response:1; }","duration":"111.877706ms","start":"2026-06-15T21:00:02.242983Z","end":"2026-06-15T21:00:02.354861Z","steps":["trace[1377639445] 'process raft request' (duration: 111.631032ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:24.499577Z","caller":"traceutil/trace.go:171","msg":"trace[1351680432] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"172.759228ms","start":"2026-06-15T21:00:24.326787Z","end":"2026-06-15T21:00:24.499546Z","steps":["trace[1351680432] 'process raft request' (duration: 172.543484ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:24.525193Z","caller":"traceutil/trace.go:171","msg":"trace[1800623526] transaction","detail":"{read_only:false; response_revision:4812; number_of_response:1; }","duration":"171.614946ms","start":"2026-06-15T21:00:24.353539Z","end":"2026-06-15T21:00:24.525154Z","steps":["trace[1800623526] 'process raft request' (duration: 171.407473ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:26.187679Z","caller":"traceutil/trace.go:171","msg":"trace[1442010574] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"102.252521ms","start":"2026-06-15T21:00:26.085365Z","end":"2026-06-15T21:00:26.187617Z","steps":["trace[1442010574] 'process raft request' (duration: 102.015006ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:28.318797Z","caller":"traceutil/trace.go:171","msg":"trace[1187741784] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"123.082406ms","start":"2026-06-15T21:00:28.19568Z","end":"2026-06-15T21:00:28.318762Z","steps":["trace[1187741784] 'process raft request' (duration: 122.747049ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:29.019521Z","caller":"traceutil/trace.go:171","msg":"trace[1712638724] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"132.014946ms","start":"2026-06-15T21:00:28.887477Z","end":"2026-06-15T21:00:29.019492Z","steps":["trace[1712638724] 'process raft request' (duration: 131.384224ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:29.30361Z","caller":"traceutil/trace.go:171","msg":"trace[1153792908] linearizableReadLoop","detail":"{readStateIndex:5076; appliedIndex:5075; }","duration":"112.623741ms","start":"2026-06-15T21:00:29.190954Z","end":"2026-06-15T21:00:29.303578Z","steps":["trace[1153792908] 'read index received' (duration: 112.340896ms)","trace[1153792908] 'applied index is now lower than readState.Index' (duration: 281.705µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T21:00:29.303957Z","caller":"traceutil/trace.go:171","msg":"trace[595019107] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"150.525129ms","start":"2026-06-15T21:00:29.153389Z","end":"2026-06-15T21:00:29.303914Z","steps":["trace[595019107] 'process raft request' (duration: 149.953757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:29.303996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.006998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-15T21:00:29.304083Z","caller":"traceutil/trace.go:171","msg":"trace[1276669895] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4886; }","duration":"113.133971ms","start":"2026-06-15T21:00:29.190928Z","end":"2026-06-15T21:00:29.304062Z","steps":["trace[1276669895] 'agreement among raft nodes before linearized reading' (duration: 112.866856ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:29.536495Z","caller":"traceutil/trace.go:171","msg":"trace[2015028417] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"139.754312ms","start":"2026-06-15T21:00:29.396707Z","end":"2026-06-15T21:00:29.536461Z","steps":["trace[2015028417] 'process raft request' (duration: 139.481537ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:29.73995Z","caller":"traceutil/trace.go:171","msg":"trace[1166055416] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"323.172931ms","start":"2026-06-15T21:00:29.416752Z","end":"2026-06-15T21:00:29.739925Z","steps":["trace[1166055416] 'process raft request' (duration: 322.993637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:29.740128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:00:29.416732Z","time spent":"323.296294ms","remote":"127.0.0.1:40586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11439,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T21:00:29.740226Z","caller":"traceutil/trace.go:171","msg":"trace[127725878] linearizableReadLoop","detail":"{readStateIndex:5081; appliedIndex:5081; }","duration":"157.437822ms","start":"2026-06-15T21:00:29.582718Z","end":"2026-06-15T21:00:29.740156Z","steps":["trace[127725878] 'read index received' (duration: 157.417041ms)","trace[127725878] 'applied index is now lower than readState.Index' (duration: 17.441µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T21:00:29.740354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.643315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T21:00:29.740799Z","caller":"traceutil/trace.go:171","msg":"trace[322973785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4891; }","duration":"158.077504ms","start":"2026-06-15T21:00:29.582655Z","end":"2026-06-15T21:00:29.740732Z","steps":["trace[322973785] 'agreement among raft nodes before linearized reading' (duration: 157.664015ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:29.839295Z","caller":"traceutil/trace.go:171","msg":"trace[1899577620] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"151.383705ms","start":"2026-06-15T21:00:29.687841Z","end":"2026-06-15T21:00:29.839225Z","steps":["trace[1899577620] 'process raft request' (duration: 150.399187ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:29.839594Z","caller":"traceutil/trace.go:171","msg":"trace[1926288494] transaction","detail":"{read_only:false; response_revision:4893; number_of_response:1; }","duration":"141.208331ms","start":"2026-06-15T21:00:29.698374Z","end":"2026-06-15T21:00:29.839582Z","steps":["trace[1926288494] 'process raft request' (duration: 140.906335ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:29.859403Z","caller":"traceutil/trace.go:171","msg":"trace[1843297497] linearizableReadLoop","detail":"{readStateIndex:5084; appliedIndex:5081; }","duration":"118.897632ms","start":"2026-06-15T21:00:29.740476Z","end":"2026-06-15T21:00:29.859374Z","steps":["trace[1843297497] 'read index received' (duration: 98.046842ms)","trace[1843297497] 'applied index is now lower than readState.Index' (duration: 20.84969ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T21:00:29.859468Z","caller":"traceutil/trace.go:171","msg":"trace[1308847699] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"150.719691ms","start":"2026-06-15T21:00:29.708627Z","end":"2026-06-15T21:00:29.859347Z","steps":["trace[1308847699] 'process raft request' (duration: 130.736449ms)","trace[1308847699] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; req_size:538; } (duration: 19.800429ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T21:00:29.859711Z","caller":"traceutil/trace.go:171","msg":"trace[1087958361] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"113.405926ms","start":"2026-06-15T21:00:29.746272Z","end":"2026-06-15T21:00:29.859678Z","steps":["trace[1087958361] 'process raft request' (duration: 113.047779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:29.85979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.333787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11454"} {"level":"info","ts":"2026-06-15T21:00:29.859826Z","caller":"traceutil/trace.go:171","msg":"trace[1639488136] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4895; }","duration":"109.393488ms","start":"2026-06-15T21:00:29.750422Z","end":"2026-06-15T21:00:29.859815Z","steps":["trace[1639488136] 'agreement among raft nodes before linearized reading' (duration: 109.198695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:29.860173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.247745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T21:00:29.860216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.013547ms","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-06-15T21:00:29.860244Z","caller":"traceutil/trace.go:171","msg":"trace[954586125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4895; }","duration":"208.389178ms","start":"2026-06-15T21:00:29.651841Z","end":"2026-06-15T21:00:29.86023Z","steps":["trace[954586125] 'agreement among raft nodes before linearized reading' (duration: 207.661174ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:29.860273Z","caller":"traceutil/trace.go:171","msg":"trace[1946247958] 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:4895; }","duration":"102.089959ms","start":"2026-06-15T21:00:29.758165Z","end":"2026-06-15T21:00:29.860255Z","steps":["trace[1946247958] 'agreement among raft nodes before linearized reading' (duration: 101.872414ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:32.026983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.749522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-06-15T21:00:32.02708Z","caller":"traceutil/trace.go:171","msg":"trace[128552087] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4945; }","duration":"221.919636ms","start":"2026-06-15T21:00:31.805138Z","end":"2026-06-15T21:00:32.027058Z","steps":["trace[128552087] 'range keys from in-memory index tree' (duration: 221.528888ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:32.030712Z","caller":"traceutil/trace.go:171","msg":"trace[726393330] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"184.608862ms","start":"2026-06-15T21:00:31.846087Z","end":"2026-06-15T21:00:32.030696Z","steps":["trace[726393330] 'process raft request' (duration: 184.47068ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:32.031324Z","caller":"traceutil/trace.go:171","msg":"trace[450496181] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"184.860586ms","start":"2026-06-15T21:00:31.846451Z","end":"2026-06-15T21:00:32.031312Z","steps":["trace[450496181] 'process raft request' (duration: 184.672982ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:32.031427Z","caller":"traceutil/trace.go:171","msg":"trace[1512378373] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"164.065109ms","start":"2026-06-15T21:00:31.86735Z","end":"2026-06-15T21:00:32.031415Z","steps":["trace[1512378373] 'process raft request' (duration: 163.852094ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:32.031683Z","caller":"traceutil/trace.go:171","msg":"trace[1922305327] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"115.972139ms","start":"2026-06-15T21:00:31.91568Z","end":"2026-06-15T21:00:32.031652Z","steps":["trace[1922305327] 'process raft request' (duration: 115.562112ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:36.334442Z","caller":"traceutil/trace.go:171","msg":"trace[553188214] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"166.242959ms","start":"2026-06-15T21:00:36.168179Z","end":"2026-06-15T21:00:36.334422Z","steps":["trace[553188214] 'process raft request' (duration: 166.093946ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:36.334856Z","caller":"traceutil/trace.go:171","msg":"trace[1103819085] linearizableReadLoop","detail":"{readStateIndex:5219; appliedIndex:5219; }","duration":"159.598843ms","start":"2026-06-15T21:00:36.175235Z","end":"2026-06-15T21:00:36.334833Z","steps":["trace[1103819085] 'read index received' (duration: 158.956011ms)","trace[1103819085] 'applied index is now lower than readState.Index' (duration: 642.182µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T21:00:36.334993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.746566ms","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-06-15T21:00:36.335236Z","caller":"traceutil/trace.go:171","msg":"trace[109688667] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:5025; }","duration":"160.055051ms","start":"2026-06-15T21:00:36.175172Z","end":"2026-06-15T21:00:36.335227Z","steps":["trace[109688667] 'agreement among raft nodes before linearized reading' (duration: 159.733146ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:37.90631Z","caller":"traceutil/trace.go:171","msg":"trace[547839860] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"232.504364ms","start":"2026-06-15T21:00:37.673759Z","end":"2026-06-15T21:00:37.906263Z","steps":["trace[547839860] 'process raft request' (duration: 232.2628ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:38.62474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.396753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-15T21:00:38.624812Z","caller":"traceutil/trace.go:171","msg":"trace[1436349564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5053; }","duration":"234.520426ms","start":"2026-06-15T21:00:38.390275Z","end":"2026-06-15T21:00:38.624796Z","steps":["trace[1436349564] 'range keys from in-memory index tree' (duration: 234.239959ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:38.625306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.05661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-15T21:00:38.625386Z","caller":"traceutil/trace.go:171","msg":"trace[598103859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5053; }","duration":"230.203323ms","start":"2026-06-15T21:00:38.395165Z","end":"2026-06-15T21:00:38.625368Z","steps":["trace[598103859] 'range keys from in-memory index tree' (duration: 229.790216ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:40.922091Z","caller":"traceutil/trace.go:171","msg":"trace[1907159274] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"167.338994ms","start":"2026-06-15T21:00:40.754714Z","end":"2026-06-15T21:00:40.922053Z","steps":["trace[1907159274] 'process raft request' (duration: 166.759093ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:42.311148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.372067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-abdea758-01c0-44b9-b772-107b2e405d12\" ","response":"range_response_count:1 size:3950"} {"level":"info","ts":"2026-06-15T21:00:42.311468Z","caller":"traceutil/trace.go:171","msg":"trace[1284432686] transaction","detail":"{read_only:false; response_revision:5101; number_of_response:1; }","duration":"235.010743ms","start":"2026-06-15T21:00:42.076437Z","end":"2026-06-15T21:00:42.311447Z","steps":["trace[1284432686] 'process raft request' (duration: 232.535316ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:42.311637Z","caller":"traceutil/trace.go:171","msg":"trace[2007395907] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"200.634615ms","start":"2026-06-15T21:00:42.110821Z","end":"2026-06-15T21:00:42.311456Z","steps":["trace[2007395907] 'process raft request' (duration: 200.453651ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:42.311549Z","caller":"traceutil/trace.go:171","msg":"trace[784944624] transaction","detail":"{read_only:false; response_revision:5103; number_of_response:1; }","duration":"199.421253ms","start":"2026-06-15T21:00:42.112047Z","end":"2026-06-15T21:00:42.311468Z","steps":["trace[784944624] 'process raft request' (duration: 199.344821ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:42.311845Z","caller":"traceutil/trace.go:171","msg":"trace[115959461] transaction","detail":"{read_only:false; response_revision:5104; number_of_response:1; }","duration":"159.217213ms","start":"2026-06-15T21:00:42.15256Z","end":"2026-06-15T21:00:42.311777Z","steps":["trace[115959461] 'process raft request' (duration: 158.868047ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:42.311337Z","caller":"traceutil/trace.go:171","msg":"trace[1419697487] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-abdea758-01c0-44b9-b772-107b2e405d12; range_end:; response_count:1; response_revision:5100; }","duration":"281.605502ms","start":"2026-06-15T21:00:42.029703Z","end":"2026-06-15T21:00:42.311309Z","steps":["trace[1419697487] 'range keys from in-memory index tree' (duration: 280.681234ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:48.239873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.111836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T21:00:48.240009Z","caller":"traceutil/trace.go:171","msg":"trace[1390983114] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"377.464239ms","start":"2026-06-15T21:00:47.86252Z","end":"2026-06-15T21:00:48.239984Z","steps":["trace[1390983114] 'process raft request' (duration: 82.139599ms)","trace[1390983114] 'compare' (duration: 294.663337ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T21:00:48.240076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:00:47.862498Z","time spent":"377.546591ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12012,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T21:00:53.537522Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955415720648786,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T21:00:53.560849Z","caller":"traceutil/trace.go:171","msg":"trace[1581527011] linearizableReadLoop","detail":"{readStateIndex:5442; appliedIndex:5441; }","duration":"524.294505ms","start":"2026-06-15T21:00:53.036525Z","end":"2026-06-15T21:00:53.56082Z","steps":["trace[1581527011] 'read index received' (duration: 523.521841ms)","trace[1581527011] 'applied index is now lower than readState.Index' (duration: 771.434µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T21:00:53.560992Z","caller":"traceutil/trace.go:171","msg":"trace[1655815501] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"526.422216ms","start":"2026-06-15T21:00:53.034552Z","end":"2026-06-15T21:00:53.560975Z","steps":["trace[1655815501] 'process raft request' (duration: 525.55942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:53.561112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:00:53.034522Z","time spent":"526.499977ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T21:00:53.561351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.007461ms","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-06-15T21:00:53.561416Z","caller":"traceutil/trace.go:171","msg":"trace[1404947994] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5242; }","duration":"524.132973ms","start":"2026-06-15T21:00:53.037267Z","end":"2026-06-15T21:00:53.5614Z","steps":["trace[1404947994] 'agreement among raft nodes before linearized reading' (duration: 523.93925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:53.561448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:00:53.037244Z","time spent":"524.197824ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9662,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-06-15T21:00:53.561703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.368933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T21:00:53.56174Z","caller":"traceutil/trace.go:171","msg":"trace[1384315765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5242; }","duration":"505.433105ms","start":"2026-06-15T21:00:53.056297Z","end":"2026-06-15T21:00:53.56173Z","steps":["trace[1384315765] 'agreement among raft nodes before linearized reading' (duration: 505.297772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:53.561772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:00:53.056284Z","time spent":"505.481585ms","remote":"127.0.0.1:40646","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-06-15T21:00:53.561892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.325556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-15T21:00:53.561989Z","caller":"traceutil/trace.go:171","msg":"trace[1250515335] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5242; }","duration":"525.467248ms","start":"2026-06-15T21:00:53.036503Z","end":"2026-06-15T21:00:53.56197Z","steps":["trace[1250515335] 'agreement among raft nodes before linearized reading' (duration: 525.181502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:53.562039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:00:53.036489Z","time spent":"525.538019ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-15T21:00:54.102557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.817041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T21:00:54.102819Z","caller":"traceutil/trace.go:171","msg":"trace[789883207] linearizableReadLoop","detail":"{readStateIndex:5445; appliedIndex:5442; }","duration":"522.779805ms","start":"2026-06-15T21:00:53.580019Z","end":"2026-06-15T21:00:54.102799Z","steps":["trace[789883207] 'read index received' (duration: 160.477856ms)","trace[789883207] 'applied index is now lower than readState.Index' (duration: 362.300669ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T21:00:54.102927Z","caller":"traceutil/trace.go:171","msg":"trace[1571060187] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"533.962103ms","start":"2026-06-15T21:00:53.568954Z","end":"2026-06-15T21:00:54.102916Z","steps":["trace[1571060187] 'process raft request' (duration: 533.726529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:54.103003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:00:53.568946Z","time spent":"534.017465ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":725,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T21:00:54.103244Z","caller":"traceutil/trace.go:171","msg":"trace[204908501] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"534.8316ms","start":"2026-06-15T21:00:53.568384Z","end":"2026-06-15T21:00:54.103216Z","steps":["trace[204908501] 'process raft request' (duration: 172.231025ms)","trace[204908501] 'compare' (duration: 361.653518ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T21:00:54.103295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.712331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T21:00:54.103333Z","caller":"traceutil/trace.go:171","msg":"trace[2075923111] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:5245; }","duration":"323.795672ms","start":"2026-06-15T21:00:53.779526Z","end":"2026-06-15T21:00:54.103322Z","steps":["trace[2075923111] 'agreement among raft nodes before linearized reading' (duration: 323.719981ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:54.103375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:00:53.7795Z","time spent":"323.866345ms","remote":"127.0.0.1:40796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-15T21:00:54.103404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:00:53.568362Z","time spent":"534.953012ms","remote":"127.0.0.1:40646","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-06-15T21:00:54.103402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.351756ms","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-06-15T21:00:54.103437Z","caller":"traceutil/trace.go:171","msg":"trace[1188333550] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"534.33176ms","start":"2026-06-15T21:00:53.569063Z","end":"2026-06-15T21:00:54.103395Z","steps":["trace[1188333550] 'process raft request' (duration: 533.692498ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:54.103509Z","caller":"traceutil/trace.go:171","msg":"trace[367232308] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5245; }","duration":"523.51377ms","start":"2026-06-15T21:00:53.579971Z","end":"2026-06-15T21:00:54.103485Z","steps":["trace[367232308] 'agreement among raft nodes before linearized reading' (duration: 523.204834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:54.103562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:00:53.57995Z","time spent":"523.601401ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9662,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-06-15T21:00:54.103651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:00:53.569045Z","time spent":"534.505294ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T21:00:54.10375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.026675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-06-15T21:00:54.103802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.702981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17150"} {"level":"info","ts":"2026-06-15T21:00:54.103805Z","caller":"traceutil/trace.go:171","msg":"trace[1843455328] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5245; }","duration":"139.132857ms","start":"2026-06-15T21:00:53.964654Z","end":"2026-06-15T21:00:54.103787Z","steps":["trace[1843455328] 'agreement among raft nodes before linearized reading' (duration: 138.870401ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:54.103846Z","caller":"traceutil/trace.go:171","msg":"trace[1220881613] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5245; }","duration":"237.782343ms","start":"2026-06-15T21:00:53.866046Z","end":"2026-06-15T21:00:54.103828Z","steps":["trace[1220881613] 'agreement among raft nodes before linearized reading' (duration: 237.60892ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:54.245394Z","caller":"traceutil/trace.go:171","msg":"trace[1886400866] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"119.728575ms","start":"2026-06-15T21:00:54.125483Z","end":"2026-06-15T21:00:54.245211Z","steps":["trace[1886400866] 'process raft request' (duration: 112.9762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:54.962044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.991458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T21:00:54.962857Z","caller":"traceutil/trace.go:171","msg":"trace[1707867952] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"589.996456ms","start":"2026-06-15T21:00:54.372828Z","end":"2026-06-15T21:00:54.962825Z","steps":["trace[1707867952] 'process raft request' (duration: 305.119371ms)","trace[1707867952] 'compare' (duration: 283.735284ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T21:00:54.963046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:00:54.372806Z","time spent":"590.138038ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T21:00:54.963219Z","caller":"traceutil/trace.go:171","msg":"trace[471468553] linearizableReadLoop","detail":"{readStateIndex:5451; appliedIndex:5449; }","duration":"585.732847ms","start":"2026-06-15T21:00:54.377469Z","end":"2026-06-15T21:00:54.963202Z","steps":["trace[471468553] 'read index received' (duration: 8.789573ms)","trace[471468553] 'applied index is now lower than readState.Index' (duration: 576.942574ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T21:00:54.96351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.593542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T21:00:54.96354Z","caller":"traceutil/trace.go:171","msg":"trace[324836446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5252; }","duration":"311.670053ms","start":"2026-06-15T21:00:54.651861Z","end":"2026-06-15T21:00:54.963531Z","steps":["trace[324836446] 'agreement among raft nodes before linearized reading' (duration: 311.612192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:54.963567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:00:54.651837Z","time spent":"311.723354ms","remote":"127.0.0.1:40422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T21:00:54.963581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.105384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-15T21:00:54.963645Z","caller":"traceutil/trace.go:171","msg":"trace[1880894937] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5252; }","duration":"586.169715ms","start":"2026-06-15T21:00:54.377461Z","end":"2026-06-15T21:00:54.963631Z","steps":["trace[1880894937] 'agreement among raft nodes before linearized reading' (duration: 585.963971ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:54.963688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:00:54.377416Z","time spent":"586.263837ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-15T21:00:54.963823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.964633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-15T21:00:54.963853Z","caller":"traceutil/trace.go:171","msg":"trace[709705323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5252; }","duration":"238.005625ms","start":"2026-06-15T21:00:54.72584Z","end":"2026-06-15T21:00:54.963845Z","steps":["trace[709705323] 'agreement among raft nodes before linearized reading' (duration: 237.941344ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:54.963929Z","caller":"traceutil/trace.go:171","msg":"trace[1336093177] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"585.240688ms","start":"2026-06-15T21:00:54.378677Z","end":"2026-06-15T21:00:54.963917Z","steps":["trace[1336093177] 'process raft request' (duration: 584.466084ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:54.963993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:00:54.378643Z","time spent":"585.31657ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T21:00:54.963766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.088313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-15T21:00:54.964046Z","caller":"traceutil/trace.go:171","msg":"trace[1866668026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5252; }","duration":"204.380078ms","start":"2026-06-15T21:00:54.759649Z","end":"2026-06-15T21:00:54.964029Z","steps":["trace[1866668026] 'agreement among raft nodes before linearized reading' (duration: 204.067262ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:54.96409Z","caller":"traceutil/trace.go:171","msg":"trace[866747180] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"587.126894ms","start":"2026-06-15T21:00:54.376954Z","end":"2026-06-15T21:00:54.964081Z","steps":["trace[866747180] 'process raft request' (duration: 585.190757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:54.964151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:00:54.376939Z","time spent":"587.195375ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T21:00:55.75031Z","caller":"traceutil/trace.go:171","msg":"trace[1304046694] linearizableReadLoop","detail":"{readStateIndex:5456; appliedIndex:5455; }","duration":"170.635798ms","start":"2026-06-15T21:00:55.579636Z","end":"2026-06-15T21:00:55.750271Z","steps":["trace[1304046694] 'read index received' (duration: 170.434744ms)","trace[1304046694] 'applied index is now lower than readState.Index' (duration: 199.674µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T21:00:55.750643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.002495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T21:00:55.750743Z","caller":"traceutil/trace.go:171","msg":"trace[587332668] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:5256; }","duration":"171.155947ms","start":"2026-06-15T21:00:55.579564Z","end":"2026-06-15T21:00:55.75072Z","steps":["trace[587332668] 'agreement among raft nodes before linearized reading' (duration: 170.955873ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:55.750761Z","caller":"traceutil/trace.go:171","msg":"trace[281409537] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"179.4269ms","start":"2026-06-15T21:00:55.571318Z","end":"2026-06-15T21:00:55.750745Z","steps":["trace[281409537] 'process raft request' (duration: 178.709747ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:56.255631Z","caller":"traceutil/trace.go:171","msg":"trace[1911694729] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"139.959255ms","start":"2026-06-15T21:00:56.115626Z","end":"2026-06-15T21:00:56.255585Z","steps":["trace[1911694729] 'process raft request' (duration: 139.728221ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:00:56.260796Z","caller":"traceutil/trace.go:171","msg":"trace[1245774424] linearizableReadLoop","detail":"{readStateIndex:5458; appliedIndex:5456; }","duration":"124.237276ms","start":"2026-06-15T21:00:56.136529Z","end":"2026-06-15T21:00:56.260766Z","steps":["trace[1245774424] 'read index received' (duration: 118.882886ms)","trace[1245774424] 'applied index is now lower than readState.Index' (duration: 5.35308ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T21:00:56.260922Z","caller":"traceutil/trace.go:171","msg":"trace[2076837183] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"144.793555ms","start":"2026-06-15T21:00:56.116049Z","end":"2026-06-15T21:00:56.260842Z","steps":["trace[2076837183] 'process raft request' (duration: 144.514819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:00:56.260967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.416099ms","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-06-15T21:00:56.261068Z","caller":"traceutil/trace.go:171","msg":"trace[749564416] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5259; }","duration":"124.565271ms","start":"2026-06-15T21:00:56.136483Z","end":"2026-06-15T21:00:56.261048Z","steps":["trace[749564416] 'agreement among raft nodes before linearized reading' (duration: 124.406358ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:01:13.216675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2873} {"level":"info","ts":"2026-06-15T21:01:13.297014Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2873,"took":"78.286735ms","hash":4104421816,"current-db-size-bytes":52269056,"current-db-size":"52 MB","current-db-size-in-use-bytes":27791360,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-15T21:01:13.297097Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4104421816,"revision":2873,"compact-revision":-1} {"level":"info","ts":"2026-06-15T21:01:21.071128Z","caller":"traceutil/trace.go:171","msg":"trace[1219347068] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"157.523385ms","start":"2026-06-15T21:01:20.913566Z","end":"2026-06-15T21:01:21.07109Z","steps":["trace[1219347068] 'process raft request' (duration: 157.331961ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:01:22.502272Z","caller":"traceutil/trace.go:171","msg":"trace[1318612970] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"100.198667ms","start":"2026-06-15T21:01:22.402026Z","end":"2026-06-15T21:01:22.502225Z","steps":["trace[1318612970] 'process raft request' (duration: 99.930642ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:01:22.503798Z","caller":"traceutil/trace.go:171","msg":"trace[475605979] transaction","detail":"{read_only:false; response_revision:5427; number_of_response:1; }","duration":"100.081596ms","start":"2026-06-15T21:01:22.40367Z","end":"2026-06-15T21:01:22.503752Z","steps":["trace[475605979] 'process raft request' (duration: 99.625287ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:01:41.367275Z","caller":"traceutil/trace.go:171","msg":"trace[53768919] transaction","detail":"{read_only:false; response_revision:5626; number_of_response:1; }","duration":"156.931942ms","start":"2026-06-15T21:01:41.210315Z","end":"2026-06-15T21:01:41.367247Z","steps":["trace[53768919] 'process raft request' (duration: 156.82248ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:01:41.367418Z","caller":"traceutil/trace.go:171","msg":"trace[1616331117] linearizableReadLoop","detail":"{readStateIndex:5838; appliedIndex:5835; }","duration":"141.122802ms","start":"2026-06-15T21:01:41.226285Z","end":"2026-06-15T21:01:41.367408Z","steps":["trace[1616331117] 'read index received' (duration: 52.205245ms)","trace[1616331117] 'applied index is now lower than readState.Index' (duration: 88.916187ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T21:01:41.367487Z","caller":"traceutil/trace.go:171","msg":"trace[733085491] transaction","detail":"{read_only:false; response_revision:5627; number_of_response:1; }","duration":"136.966989ms","start":"2026-06-15T21:01:41.23051Z","end":"2026-06-15T21:01:41.367477Z","steps":["trace[733085491] 'process raft request' (duration: 136.674893ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:01:41.36759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.281155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T21:01:41.367301Z","caller":"traceutil/trace.go:171","msg":"trace[536695179] transaction","detail":"{read_only:false; response_revision:5625; number_of_response:1; }","duration":"157.219656ms","start":"2026-06-15T21:01:41.210046Z","end":"2026-06-15T21:01:41.367266Z","steps":["trace[536695179] 'process raft request' (duration: 156.895431ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:01:41.367674Z","caller":"traceutil/trace.go:171","msg":"trace[475777955] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5627; }","duration":"141.428077ms","start":"2026-06-15T21:01:41.22623Z","end":"2026-06-15T21:01:41.367658Z","steps":["trace[475777955] 'agreement among raft nodes before linearized reading' (duration: 141.286434ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:01:41.655701Z","caller":"traceutil/trace.go:171","msg":"trace[1244533273] linearizableReadLoop","detail":"{readStateIndex:5840; appliedIndex:5839; }","duration":"288.228849ms","start":"2026-06-15T21:01:41.367449Z","end":"2026-06-15T21:01:41.655677Z","steps":["trace[1244533273] 'read index received' (duration: 236.383619ms)","trace[1244533273] 'applied index is now lower than readState.Index' (duration: 51.84427ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T21:01:41.655764Z","caller":"traceutil/trace.go:171","msg":"trace[1600293543] transaction","detail":"{read_only:false; response_revision:5628; number_of_response:1; }","duration":"347.436839ms","start":"2026-06-15T21:01:41.308299Z","end":"2026-06-15T21:01:41.655736Z","steps":["trace[1600293543] 'process raft request' (duration: 295.503318ms)","trace[1600293543] 'compare' (duration: 51.763538ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T21:01:41.655896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:01:41.308266Z","time spent":"347.555151ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T21:01:41.65595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.923357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-15T21:01:41.655989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.592547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-15T21:01:41.655992Z","caller":"traceutil/trace.go:171","msg":"trace[109651802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5628; }","duration":"313.022079ms","start":"2026-06-15T21:01:41.342958Z","end":"2026-06-15T21:01:41.65598Z","steps":["trace[109651802] 'agreement among raft nodes before linearized reading' (duration: 312.774854ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:01:41.656018Z","caller":"traceutil/trace.go:171","msg":"trace[164654950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5628; }","duration":"314.699209ms","start":"2026-06-15T21:01:41.34131Z","end":"2026-06-15T21:01:41.65601Z","steps":["trace[164654950] 'agreement among raft nodes before linearized reading' (duration: 314.473525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:01:41.656024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:01:41.342932Z","time spent":"313.086879ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T21:01:41.656046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:01:41.341283Z","time spent":"314.75599ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T21:01:41.656055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.043849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-15T21:01:41.65614Z","caller":"traceutil/trace.go:171","msg":"trace[1921884831] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5628; }","duration":"313.201102ms","start":"2026-06-15T21:01:41.342919Z","end":"2026-06-15T21:01:41.65612Z","steps":["trace[1921884831] 'agreement among raft nodes before linearized reading' (duration: 312.836095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:01:41.656192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T21:01:41.342893Z","time spent":"313.289623ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-15T21:01:41.68087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.683815ms","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-06-15T21:01:41.680941Z","caller":"traceutil/trace.go:171","msg":"trace[211213523] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:5628; }","duration":"245.835037ms","start":"2026-06-15T21:01:41.435091Z","end":"2026-06-15T21:01:41.680926Z","steps":["trace[211213523] 'agreement among raft nodes before linearized reading' (duration: 245.697375ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:01:42.327625Z","caller":"traceutil/trace.go:171","msg":"trace[1174459227] transaction","detail":"{read_only:false; response_revision:5634; number_of_response:1; }","duration":"129.106847ms","start":"2026-06-15T21:01:42.198487Z","end":"2026-06-15T21:01:42.327594Z","steps":["trace[1174459227] 'process raft request' (duration: 128.640758ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:01:42.567284Z","caller":"traceutil/trace.go:171","msg":"trace[17051241] transaction","detail":"{read_only:false; response_revision:5638; number_of_response:1; }","duration":"110.549247ms","start":"2026-06-15T21:01:42.456693Z","end":"2026-06-15T21:01:42.567243Z","steps":["trace[17051241] 'process raft request' (duration: 75.310214ms)","trace[17051241] 'compare' (duration: 35.081931ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T21:02:02.372133Z","caller":"traceutil/trace.go:171","msg":"trace[299132325] transaction","detail":"{read_only:false; response_revision:5790; number_of_response:1; }","duration":"122.968933ms","start":"2026-06-15T21:02:02.249106Z","end":"2026-06-15T21:02:02.372075Z","steps":["trace[299132325] 'process raft request' (duration: 122.481216ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:02:02.535583Z","caller":"traceutil/trace.go:171","msg":"trace[1318193479] transaction","detail":"{read_only:false; response_revision:5792; number_of_response:1; }","duration":"153.854983ms","start":"2026-06-15T21:02:02.381695Z","end":"2026-06-15T21:02:02.53555Z","steps":["trace[1318193479] 'process raft request' (duration: 153.630689ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:02:12.983195Z","caller":"traceutil/trace.go:171","msg":"trace[235449881] transaction","detail":"{read_only:false; response_revision:5874; number_of_response:1; }","duration":"110.93551ms","start":"2026-06-15T21:02:12.872224Z","end":"2026-06-15T21:02:12.983159Z","steps":["trace[235449881] 'process raft request' (duration: 67.901385ms)","trace[235449881] 'compare' (duration: 42.910183ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T21:03:03.125149Z","caller":"traceutil/trace.go:171","msg":"trace[634286403] transaction","detail":"{read_only:false; response_revision:6175; number_of_response:1; }","duration":"137.061324ms","start":"2026-06-15T21:03:02.98806Z","end":"2026-06-15T21:03:03.125122Z","steps":["trace[634286403] 'process raft request' (duration: 136.87018ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:03:17.391469Z","caller":"traceutil/trace.go:171","msg":"trace[249765482] transaction","detail":"{read_only:false; response_revision:6248; number_of_response:1; }","duration":"102.504862ms","start":"2026-06-15T21:03:17.288914Z","end":"2026-06-15T21:03:17.391419Z","steps":["trace[249765482] 'process raft request' (duration: 40.425055ms)","trace[249765482] 'compare' (duration: 61.825513ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T21:04:02.924775Z","caller":"traceutil/trace.go:171","msg":"trace[1277090566] linearizableReadLoop","detail":"{readStateIndex:6761; appliedIndex:6760; }","duration":"146.307035ms","start":"2026-06-15T21:04:02.778441Z","end":"2026-06-15T21:04:02.924748Z","steps":["trace[1277090566] 'read index received' (duration: 50.221466ms)","trace[1277090566] 'applied index is now lower than readState.Index' (duration: 96.084469ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T21:04:02.925034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.570589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-15T21:04:02.925017Z","caller":"traceutil/trace.go:171","msg":"trace[529147146] transaction","detail":"{read_only:false; response_revision:6518; number_of_response:1; }","duration":"163.00569ms","start":"2026-06-15T21:04:02.761967Z","end":"2026-06-15T21:04:02.924972Z","steps":["trace[529147146] 'process raft request' (duration: 66.654856ms)","trace[529147146] 'compare' (duration: 95.974328ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T21:04:02.925098Z","caller":"traceutil/trace.go:171","msg":"trace[2006052568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6518; }","duration":"146.694941ms","start":"2026-06-15T21:04:02.778391Z","end":"2026-06-15T21:04:02.925085Z","steps":["trace[2006052568] 'agreement among raft nodes before linearized reading' (duration: 146.473248ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:04:33.678605Z","caller":"traceutil/trace.go:171","msg":"trace[434542597] transaction","detail":"{read_only:false; response_revision:6834; number_of_response:1; }","duration":"135.448968ms","start":"2026-06-15T21:04:33.543065Z","end":"2026-06-15T21:04:33.678514Z","steps":["trace[434542597] 'process raft request' (duration: 135.152653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:04:38.217806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.734803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T21:04:38.217918Z","caller":"traceutil/trace.go:171","msg":"trace[1717645243] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:6857; }","duration":"134.920446ms","start":"2026-06-15T21:04:38.082974Z","end":"2026-06-15T21:04:38.217895Z","steps":["trace[1717645243] 'count revisions from in-memory index tree' (duration: 134.631612ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:04:39.12573Z","caller":"traceutil/trace.go:171","msg":"trace[1350874717] transaction","detail":"{read_only:false; response_revision:6861; number_of_response:1; }","duration":"163.703725ms","start":"2026-06-15T21:04:38.961997Z","end":"2026-06-15T21:04:39.125701Z","steps":["trace[1350874717] 'process raft request' (duration: 163.303929ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:05:06.555458Z","caller":"traceutil/trace.go:171","msg":"trace[1048721389] transaction","detail":"{read_only:false; response_revision:7061; number_of_response:1; }","duration":"122.823803ms","start":"2026-06-15T21:05:06.43258Z","end":"2026-06-15T21:05:06.555404Z","steps":["trace[1048721389] 'process raft request' (duration: 122.613406ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:05:06.594241Z","caller":"traceutil/trace.go:171","msg":"trace[1433119078] transaction","detail":"{read_only:false; response_revision:7062; number_of_response:1; }","duration":"160.592274ms","start":"2026-06-15T21:05:06.433628Z","end":"2026-06-15T21:05:06.59422Z","steps":["trace[1433119078] 'process raft request' (duration: 160.17739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:05:06.764668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.96265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T21:05:06.764804Z","caller":"traceutil/trace.go:171","msg":"trace[343258361] linearizableReadLoop","detail":"{readStateIndex:7326; appliedIndex:7325; }","duration":"119.772591ms","start":"2026-06-15T21:05:06.645018Z","end":"2026-06-15T21:05:06.764791Z","steps":["trace[343258361] 'read index received' (duration: 37.711µs)","trace[343258361] 'applied index is now lower than readState.Index' (duration: 119.73404ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T21:05:06.76492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.903436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T21:05:06.764943Z","caller":"traceutil/trace.go:171","msg":"trace[2006536557] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7063; }","duration":"119.966768ms","start":"2026-06-15T21:05:06.644968Z","end":"2026-06-15T21:05:06.764934Z","steps":["trace[2006536557] 'agreement among raft nodes before linearized reading' (duration: 119.861664ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:05:06.764994Z","caller":"traceutil/trace.go:171","msg":"trace[1281640649] transaction","detail":"{read_only:false; response_revision:7063; number_of_response:1; }","duration":"170.865404ms","start":"2026-06-15T21:05:06.594115Z","end":"2026-06-15T21:05:06.76498Z","steps":["trace[1281640649] 'process raft request' (duration: 41.50964ms)","trace[1281640649] 'compare' (duration: 128.622307ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T21:06:13.223999Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5369} {"level":"info","ts":"2026-06-15T21:06:13.310406Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5369,"took":"84.950636ms","hash":1212976285,"current-db-size-bytes":52269056,"current-db-size":"52 MB","current-db-size-in-use-bytes":18763776,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-06-15T21:06:13.310471Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1212976285,"revision":5369,"compact-revision":2873} {"level":"info","ts":"2026-06-15T21:06:34.926513Z","caller":"traceutil/trace.go:171","msg":"trace[774345157] transaction","detail":"{read_only:false; response_revision:7535; number_of_response:1; }","duration":"125.09557ms","start":"2026-06-15T21:06:34.801379Z","end":"2026-06-15T21:06:34.926475Z","steps":["trace[774345157] 'process raft request' (duration: 92.515067ms)","trace[774345157] 'compare' (duration: 32.325695ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T21:06:34.926439Z","caller":"traceutil/trace.go:171","msg":"trace[1952922446] linearizableReadLoop","detail":"{readStateIndex:7816; appliedIndex:7815; }","duration":"122.791558ms","start":"2026-06-15T21:06:34.803609Z","end":"2026-06-15T21:06:34.926401Z","steps":["trace[1952922446] 'read index received' (duration: 90.260176ms)","trace[1952922446] 'applied index is now lower than readState.Index' (duration: 32.529922ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T21:06:34.926688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.043756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-15T21:06:34.926781Z","caller":"traceutil/trace.go:171","msg":"trace[828552303] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7535; }","duration":"123.196251ms","start":"2026-06-15T21:06:34.80357Z","end":"2026-06-15T21:06:34.926766Z","steps":["trace[828552303] 'agreement among raft nodes before linearized reading' (duration: 122.906191ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:08:11.524918Z","caller":"traceutil/trace.go:171","msg":"trace[753323152] transaction","detail":"{read_only:false; response_revision:8022; number_of_response:1; }","duration":"101.198538ms","start":"2026-06-15T21:08:11.423683Z","end":"2026-06-15T21:08:11.524882Z","steps":["trace[753323152] 'process raft request' (duration: 100.909881ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T21:08:11.625465Z","caller":"traceutil/trace.go:171","msg":"trace[1064124591] linearizableReadLoop","detail":"{readStateIndex:8323; appliedIndex:8322; }","duration":"100.645714ms","start":"2026-06-15T21:08:11.524789Z","end":"2026-06-15T21:08:11.625435Z","steps":["trace[1064124591] 'read index received' (duration: 90.119577ms)","trace[1064124591] 'applied index is now lower than readState.Index' (duration: 10.523757ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T21:08:11.625652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.639934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-06-15T21:08:11.625737Z","caller":"traceutil/trace.go:171","msg":"trace[1280159934] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8022; }","duration":"144.761867ms","start":"2026-06-15T21:08:11.480944Z","end":"2026-06-15T21:08:11.625706Z","steps":["trace[1280159934] 'agreement among raft nodes before linearized reading' (duration: 144.539131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T21:08:11.625749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.879675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-node-cluster-rsrc-use\" ","response":"range_response_count:1 size:14912"} {"level":"info","ts":"2026-06-15T21:08:11.62649Z","caller":"traceutil/trace.go:171","msg":"trace[1967612662] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-node-cluster-rsrc-use; range_end:; response_count:1; response_revision:8022; }","duration":"154.038609ms","start":"2026-06-15T21:08:11.471823Z","end":"2026-06-15T21:08:11.625861Z","steps":["trace[1967612662] 'agreement among raft nodes before linearized reading' (duration: 153.71307ms)"],"step_count":1}