{"level":"warn","ts":"2026-07-30T23:04:22.426562Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T23:04:22.426679Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-07-30T23:04:22.426755Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T23:04:22.426771Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-07-30T23:04:22.426793Z","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-07-30T23:04:22.427829Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-07-30T23:04:22.428014Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-07-30T23:04:22.432382Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.62573ms"} {"level":"info","ts":"2026-07-30T23:04:22.438625Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-07-30T23:04:22.438755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T23:04:22.438799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-07-30T23:04:22.439115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T23:04:22.439147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-07-30T23:04:22.439271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-07-30T23:04:22.444926Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T23:04:22.446951Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T23:04:22.45075Z","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-07-30T23:04:22.453602Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T23:04:22.453921Z","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-07-30T23:04:22.454098Z","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-07-30T23:04:22.454145Z","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-07-30T23:04:22.454185Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T23:04:22.45548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-07-30T23:04:22.455899Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-07-30T23:04:22.458411Z","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-07-30T23:04:22.458597Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T23:04:22.458627Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T23:04:22.458685Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-07-30T23:04:22.458699Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-07-30T23:04:23.339904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T23:04:23.340008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T23:04:23.340053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-07-30T23:04:23.340077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-07-30T23:04:23.340088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-07-30T23:04:23.340107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-07-30T23:04:23.340123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-07-30T23:04:23.341413Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T23:04:23.341448Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:04:23.341648Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T23:04:23.341713Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T23:04:23.342425Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:04:23.342624Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:04:23.342691Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:04:23.344989Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-07-30T23:04:23.345189Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T23:04:23.345218Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T23:04:23.345405Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T23:04:43.502441Z","caller":"traceutil/trace.go:171","msg":"trace[149101920] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"207.204039ms","start":"2026-07-30T23:04:43.295212Z","end":"2026-07-30T23:04:43.502416Z","steps":["trace[149101920] 'process raft request' (duration: 207.056925ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:04:48.937574Z","caller":"traceutil/trace.go:171","msg":"trace[702958037] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"128.053087ms","start":"2026-07-30T23:04:48.809495Z","end":"2026-07-30T23:04:48.937548Z","steps":["trace[702958037] 'process raft request' (duration: 127.826941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:04:49.148993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.013309ms","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-07-30T23:04:49.149015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.082073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:04:49.149135Z","caller":"traceutil/trace.go:171","msg":"trace[1654905504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:463; }","duration":"172.209435ms","start":"2026-07-30T23:04:48.9769Z","end":"2026-07-30T23:04:49.149109Z","steps":["trace[1654905504] 'range keys from in-memory index tree' (duration: 171.886765ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:04:49.149151Z","caller":"traceutil/trace.go:171","msg":"trace[313583521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:463; }","duration":"110.379531ms","start":"2026-07-30T23:04:49.038743Z","end":"2026-07-30T23:04:49.149123Z","steps":["trace[313583521] 'range keys from in-memory index tree' (duration: 109.98482ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:04:53.29909Z","caller":"traceutil/trace.go:171","msg":"trace[659384724] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"126.122105ms","start":"2026-07-30T23:04:53.172931Z","end":"2026-07-30T23:04:53.299054Z","steps":["trace[659384724] 'process raft request' (duration: 125.847987ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:00.733781Z","caller":"traceutil/trace.go:171","msg":"trace[1924657353] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"344.756908ms","start":"2026-07-30T23:05:00.389Z","end":"2026-07-30T23:05:00.733756Z","steps":["trace[1924657353] 'process raft request' (duration: 338.937348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:00.734228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:00.388964Z","time spent":"344.888932ms","remote":"127.0.0.1:46024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:05:00.735304Z","caller":"traceutil/trace.go:171","msg":"trace[657243339] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"339.598385ms","start":"2026-07-30T23:05:00.395683Z","end":"2026-07-30T23:05:00.735282Z","steps":["trace[657243339] 'process raft request' (duration: 339.355389ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:00.735389Z","caller":"traceutil/trace.go:171","msg":"trace[215211963] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:591; }","duration":"176.608396ms","start":"2026-07-30T23:05:00.558728Z","end":"2026-07-30T23:05:00.735337Z","steps":["trace[215211963] 'read index received' (duration: 169.224482ms)","trace[215211963] 'applied index is now lower than readState.Index' (duration: 7.383374ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:05:00.735408Z","caller":"traceutil/trace.go:171","msg":"trace[275329632] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"321.98143ms","start":"2026-07-30T23:05:00.413409Z","end":"2026-07-30T23:05:00.73539Z","steps":["trace[275329632] 'process raft request' (duration: 321.782465ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:00.735453Z","caller":"traceutil/trace.go:171","msg":"trace[547675815] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"315.689567ms","start":"2026-07-30T23:05:00.419758Z","end":"2026-07-30T23:05:00.735447Z","steps":["trace[547675815] 'process raft request' (duration: 315.468271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:00.735486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:00.419734Z","time spent":"315.737868ms","remote":"127.0.0.1:46024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:05:00.735498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:00.41339Z","time spent":"322.066223ms","remote":"127.0.0.1:46024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:05:00.735416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:00.395655Z","time spent":"339.700788ms","remote":"127.0.0.1:46024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:05:00.735635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.105157ms","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-07-30T23:05:00.735667Z","caller":"traceutil/trace.go:171","msg":"trace[475448016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"170.152088ms","start":"2026-07-30T23:05:00.565502Z","end":"2026-07-30T23:05:00.735654Z","steps":["trace[475448016] 'agreement among raft nodes before linearized reading' (duration: 170.067385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:00.735643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.904324ms","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-07-30T23:05:00.735713Z","caller":"traceutil/trace.go:171","msg":"trace[1572710812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:570; }","duration":"177.023527ms","start":"2026-07-30T23:05:00.55868Z","end":"2026-07-30T23:05:00.735704Z","steps":["trace[1572710812] 'agreement among raft nodes before linearized reading' (duration: 176.812441ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:01.430829Z","caller":"traceutil/trace.go:171","msg":"trace[1302773801] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"606.324084ms","start":"2026-07-30T23:05:00.824473Z","end":"2026-07-30T23:05:01.430797Z","steps":["trace[1302773801] 'process raft request' (duration: 532.934042ms)","trace[1302773801] 'compare' (duration: 73.236008ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:05:01.431064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:00.824445Z","time spent":"606.505929ms","remote":"127.0.0.1:46024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18032,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:05:01.487072Z","caller":"traceutil/trace.go:171","msg":"trace[467246117] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:599; }","duration":"449.150794ms","start":"2026-07-30T23:05:01.037885Z","end":"2026-07-30T23:05:01.487036Z","steps":["trace[467246117] 'read index received' (duration: 319.177673ms)","trace[467246117] 'applied index is now lower than readState.Index' (duration: 129.971561ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:05:01.487188Z","caller":"traceutil/trace.go:171","msg":"trace[841772027] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"658.266865ms","start":"2026-07-30T23:05:00.828884Z","end":"2026-07-30T23:05:01.487151Z","steps":["trace[841772027] 'process raft request' (duration: 657.884885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:01.487306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:00.828814Z","time spent":"658.4336ms","remote":"127.0.0.1:46024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:05:01.487351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.432342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:05:01.487435Z","caller":"traceutil/trace.go:171","msg":"trace[11734634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"449.578345ms","start":"2026-07-30T23:05:01.037836Z","end":"2026-07-30T23:05:01.487414Z","steps":["trace[11734634] 'agreement among raft nodes before linearized reading' (duration: 449.36361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:01.487495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:01.037811Z","time spent":"449.670268ms","remote":"127.0.0.1:45966","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-07-30T23:05:01.601943Z","caller":"traceutil/trace.go:171","msg":"trace[701348672] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"114.756241ms","start":"2026-07-30T23:05:01.487168Z","end":"2026-07-30T23:05:01.601925Z","steps":["trace[701348672] 'read index received' (duration: 114.601687ms)","trace[701348672] 'applied index is now lower than readState.Index' (duration: 153.354µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:05:01.602603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.572919ms","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-07-30T23:05:01.60269Z","caller":"traceutil/trace.go:171","msg":"trace[369416705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"174.651382ms","start":"2026-07-30T23:05:01.427999Z","end":"2026-07-30T23:05:01.60265Z","steps":["trace[369416705] 'agreement among raft nodes before linearized reading' (duration: 174.030335ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:01.60273Z","caller":"traceutil/trace.go:171","msg":"trace[651005573] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"157.294054ms","start":"2026-07-30T23:05:01.444725Z","end":"2026-07-30T23:05:01.602019Z","steps":["trace[651005573] 'process raft request' (duration: 157.059137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:01.627978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.137773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:05:01.628078Z","caller":"traceutil/trace.go:171","msg":"trace[1205503030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"139.290387ms","start":"2026-07-30T23:05:01.488765Z","end":"2026-07-30T23:05:01.628056Z","steps":["trace[1205503030] 'agreement among raft nodes before linearized reading' (duration: 139.121032ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:01.628397Z","caller":"traceutil/trace.go:171","msg":"trace[1823860429] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"124.486539ms","start":"2026-07-30T23:05:01.503896Z","end":"2026-07-30T23:05:01.628383Z","steps":["trace[1823860429] 'process raft request' (duration: 123.217214ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:07.640039Z","caller":"traceutil/trace.go:171","msg":"trace[546885809] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"225.962185ms","start":"2026-07-30T23:05:07.41405Z","end":"2026-07-30T23:05:07.640013Z","steps":["trace[546885809] 'read index received' (duration: 225.76891ms)","trace[546885809] 'applied index is now lower than readState.Index' (duration: 192.455µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:05:07.640145Z","caller":"traceutil/trace.go:171","msg":"trace[33325260] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"278.548774ms","start":"2026-07-30T23:05:07.361566Z","end":"2026-07-30T23:05:07.640115Z","steps":["trace[33325260] 'process raft request' (duration: 278.305368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:07.640434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.342116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v5c2x\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-30T23:05:07.640489Z","caller":"traceutil/trace.go:171","msg":"trace[1271676583] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v5c2x; range_end:; response_count:1; response_revision:632; }","duration":"226.47389ms","start":"2026-07-30T23:05:07.413998Z","end":"2026-07-30T23:05:07.640472Z","steps":["trace[1271676583] 'agreement among raft nodes before linearized reading' (duration: 226.12073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:07.854323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.441787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:05:07.854443Z","caller":"traceutil/trace.go:171","msg":"trace[1468963756] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:632; }","duration":"202.635503ms","start":"2026-07-30T23:05:07.651776Z","end":"2026-07-30T23:05:07.854411Z","steps":["trace[1468963756] 'range keys from in-memory index tree' (duration: 202.360156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:07.85488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.657025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:05:07.855347Z","caller":"traceutil/trace.go:171","msg":"trace[457407437] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"125.97222ms","start":"2026-07-30T23:05:07.729352Z","end":"2026-07-30T23:05:07.855325Z","steps":["trace[457407437] 'read index received' (duration: 27.040805ms)","trace[457407437] 'applied index is now lower than readState.Index' (duration: 98.929785ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:05:07.855381Z","caller":"traceutil/trace.go:171","msg":"trace[2132979196] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"203.370033ms","start":"2026-07-30T23:05:07.651976Z","end":"2026-07-30T23:05:07.855346Z","steps":["trace[2132979196] 'process raft request' (duration: 57.973557ms)","trace[2132979196] 'compare' (duration: 144.45916ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:05:07.855468Z","caller":"traceutil/trace.go:171","msg":"trace[446782149] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"200.400821ms","start":"2026-07-30T23:05:07.655025Z","end":"2026-07-30T23:05:07.855426Z","steps":["trace[446782149] 'process raft request' (duration: 200.207456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:07.855594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.248168ms","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-07-30T23:05:07.855649Z","caller":"traceutil/trace.go:171","msg":"trace[894535969] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"126.368422ms","start":"2026-07-30T23:05:07.729266Z","end":"2026-07-30T23:05:07.855635Z","steps":["trace[894535969] 'agreement among raft nodes before linearized reading' (duration: 126.137215ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:08.469197Z","caller":"traceutil/trace.go:171","msg":"trace[1624936538] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"191.942408ms","start":"2026-07-30T23:05:08.27723Z","end":"2026-07-30T23:05:08.469172Z","steps":["trace[1624936538] 'process raft request' (duration: 191.442184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:10.401485Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.096015755s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-30T23:05:10.408189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587008952s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:05:10.408524Z","caller":"traceutil/trace.go:171","msg":"trace[770062990] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.203604999s","start":"2026-07-30T23:05:09.204898Z","end":"2026-07-30T23:05:10.408503Z","steps":["trace[770062990] 'process raft request' (duration: 1.203404833s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:10.408642Z","caller":"traceutil/trace.go:171","msg":"trace[230326955] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"1.613707967s","start":"2026-07-30T23:05:08.79487Z","end":"2026-07-30T23:05:10.408578Z","steps":["trace[230326955] 'read index received' (duration: 26.219382ms)","trace[230326955] 'applied index is now lower than readState.Index' (duration: 1.587481915s)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:05:10.408711Z","caller":"traceutil/trace.go:171","msg":"trace[681131947] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.867915081s","start":"2026-07-30T23:05:08.540771Z","end":"2026-07-30T23:05:10.408686Z","steps":["trace[681131947] 'process raft request' (duration: 280.339094ms)","trace[681131947] 'compare' (duration: 1.586728644s)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:05:10.408689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:09.204872Z","time spent":"1.203721572s","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:05:10.408793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:08.540746Z","time spent":"1.868008293s","remote":"127.0.0.1:46192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:05:10.409042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.61415339s","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-07-30T23:05:10.409093Z","caller":"traceutil/trace.go:171","msg":"trace[322217291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"1.614260753s","start":"2026-07-30T23:05:08.794816Z","end":"2026-07-30T23:05:10.409077Z","steps":["trace[322217291] 'agreement among raft nodes before linearized reading' (duration: 1.613994405s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:10.409131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:08.794793Z","time spent":"1.614328365s","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T23:05:10.409319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.610516879s","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-07-30T23:05:10.409352Z","caller":"traceutil/trace.go:171","msg":"trace[1574961765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"1.610571491s","start":"2026-07-30T23:05:08.798772Z","end":"2026-07-30T23:05:10.409344Z","steps":["trace[1574961765] 'agreement among raft nodes before linearized reading' (duration: 1.610482719s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:10.409378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:08.798761Z","time spent":"1.610611742s","remote":"127.0.0.1:46206","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-07-30T23:05:10.409561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369938992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:05:10.409574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.898932ms","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-07-30T23:05:10.409623Z","caller":"traceutil/trace.go:171","msg":"trace[48187253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"1.369997613s","start":"2026-07-30T23:05:09.039607Z","end":"2026-07-30T23:05:10.409604Z","steps":["trace[48187253] 'agreement among raft nodes before linearized reading' (duration: 1.36989449s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:10.409565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.038087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-30T23:05:10.409675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:09.039532Z","time spent":"1.370136017s","remote":"127.0.0.1:45966","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-07-30T23:05:10.409692Z","caller":"traceutil/trace.go:171","msg":"trace[379317968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"549.059686ms","start":"2026-07-30T23:05:09.860605Z","end":"2026-07-30T23:05:10.409665Z","steps":["trace[379317968] 'agreement among raft nodes before linearized reading' (duration: 548.767088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:10.40975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:09.860582Z","time spent":"549.158449ms","remote":"127.0.0.1:46206","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-07-30T23:05:10.409858Z","caller":"traceutil/trace.go:171","msg":"trace[34915475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"528.300494ms","start":"2026-07-30T23:05:09.881453Z","end":"2026-07-30T23:05:10.409754Z","steps":["trace[34915475] 'agreement among raft nodes before linearized reading' (duration: 527.823331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:10.409948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:09.88143Z","time spent":"528.503119ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T23:05:10.716567Z","caller":"traceutil/trace.go:171","msg":"trace[419838754] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"302.302097ms","start":"2026-07-30T23:05:10.414225Z","end":"2026-07-30T23:05:10.716527Z","steps":["trace[419838754] 'process raft request' (duration: 257.459123ms)","trace[419838754] 'compare' (duration: 44.664709ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:05:10.716806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:10.414211Z","time spent":"302.470561ms","remote":"127.0.0.1:46206","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-07-30T23:05:10.718385Z","caller":"traceutil/trace.go:171","msg":"trace[992113254] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"303.645544ms","start":"2026-07-30T23:05:10.414707Z","end":"2026-07-30T23:05:10.718353Z","steps":["trace[992113254] 'process raft request' (duration: 303.510811ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:10.718421Z","caller":"traceutil/trace.go:171","msg":"trace[1900272730] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"303.88427ms","start":"2026-07-30T23:05:10.414499Z","end":"2026-07-30T23:05:10.718384Z","steps":["trace[1900272730] 'process raft request' (duration: 303.564482ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:10.718574Z","caller":"traceutil/trace.go:171","msg":"trace[1794988411] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:665; }","duration":"302.637827ms","start":"2026-07-30T23:05:10.415908Z","end":"2026-07-30T23:05:10.718545Z","steps":["trace[1794988411] 'read index received' (duration: 255.927591ms)","trace[1794988411] 'applied index is now lower than readState.Index' (duration: 46.708146ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:05:10.718624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:10.414666Z","time spent":"303.816519ms","remote":"127.0.0.1:46206","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-07-30T23:05:10.71865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:10.414481Z","time spent":"304.046325ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:05:10.718662Z","caller":"traceutil/trace.go:171","msg":"trace[1404581765] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"303.409048ms","start":"2026-07-30T23:05:10.415216Z","end":"2026-07-30T23:05:10.718625Z","steps":["trace[1404581765] 'process raft request' (duration: 303.076379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:10.718751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.828302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-30T23:05:10.718817Z","caller":"traceutil/trace.go:171","msg":"trace[87371085] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:642; }","duration":"302.911864ms","start":"2026-07-30T23:05:10.415888Z","end":"2026-07-30T23:05:10.7188Z","steps":["trace[87371085] 'agreement among raft nodes before linearized reading' (duration: 302.795461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:10.718834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:10.415193Z","time spent":"303.553961ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:05:10.718869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:10.41588Z","time spent":"302.976355ms","remote":"127.0.0.1:46168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-07-30T23:05:10.719113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.742642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:05:10.719245Z","caller":"traceutil/trace.go:171","msg":"trace[1081558310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"301.950017ms","start":"2026-07-30T23:05:10.417268Z","end":"2026-07-30T23:05:10.719218Z","steps":["trace[1081558310] 'agreement among raft nodes before linearized reading' (duration: 301.619118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:10.719363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:10.417255Z","time spent":"302.090562ms","remote":"127.0.0.1:45966","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-07-30T23:05:16.179598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.377653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:05:16.179739Z","caller":"traceutil/trace.go:171","msg":"trace[404515774] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"305.762104ms","start":"2026-07-30T23:05:15.87395Z","end":"2026-07-30T23:05:16.179712Z","steps":["trace[404515774] 'process raft request' (duration: 139.164194ms)","trace[404515774] 'compare' (duration: 166.229749ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:05:16.179817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:15.873925Z","time spent":"305.863567ms","remote":"127.0.0.1:46064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:05:16.181064Z","caller":"traceutil/trace.go:171","msg":"trace[1614742389] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"272.990361ms","start":"2026-07-30T23:05:15.908039Z","end":"2026-07-30T23:05:16.181029Z","steps":["trace[1614742389] 'process raft request' (duration: 272.635621ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:16.181112Z","caller":"traceutil/trace.go:171","msg":"trace[1078502297] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:744; }","duration":"142.458565ms","start":"2026-07-30T23:05:16.03863Z","end":"2026-07-30T23:05:16.181089Z","steps":["trace[1078502297] 'read index received' (duration: 141.983821ms)","trace[1078502297] 'applied index is now lower than readState.Index' (duration: 473.743µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:05:16.181203Z","caller":"traceutil/trace.go:171","msg":"trace[1092602993] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"197.997344ms","start":"2026-07-30T23:05:15.98312Z","end":"2026-07-30T23:05:16.181117Z","steps":["trace[1092602993] 'process raft request' (duration: 197.764858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:16.181394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.749023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:05:16.181526Z","caller":"traceutil/trace.go:171","msg":"trace[1140501359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"142.933658ms","start":"2026-07-30T23:05:16.038573Z","end":"2026-07-30T23:05:16.181506Z","steps":["trace[1140501359] 'agreement among raft nodes before linearized reading' (duration: 142.65886ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:25.730952Z","caller":"traceutil/trace.go:171","msg":"trace[933286322] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"190.99451ms","start":"2026-07-30T23:05:25.539897Z","end":"2026-07-30T23:05:25.730891Z","steps":["trace[933286322] 'process raft request' (duration: 190.805715ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:04.628204Z","caller":"traceutil/trace.go:171","msg":"trace[360483182] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"161.871363ms","start":"2026-07-30T23:06:04.466305Z","end":"2026-07-30T23:06:04.628176Z","steps":["trace[360483182] 'process raft request' (duration: 161.704508ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:06.667287Z","caller":"traceutil/trace.go:171","msg":"trace[1161115976] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1120; }","duration":"161.888333ms","start":"2026-07-30T23:06:06.505369Z","end":"2026-07-30T23:06:06.667257Z","steps":["trace[1161115976] 'read index received' (duration: 161.814341ms)","trace[1161115976] 'applied index is now lower than readState.Index' (duration: 72.202µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:06:06.667512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.096809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T23:06:06.667635Z","caller":"traceutil/trace.go:171","msg":"trace[1509582375] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1079; }","duration":"162.257884ms","start":"2026-07-30T23:06:06.505351Z","end":"2026-07-30T23:06:06.667608Z","steps":["trace[1509582375] 'agreement among raft nodes before linearized reading' (duration: 162.015167ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:07.113547Z","caller":"traceutil/trace.go:171","msg":"trace[1307535144] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1121; }","duration":"446.155168ms","start":"2026-07-30T23:06:06.667361Z","end":"2026-07-30T23:06:07.113517Z","steps":["trace[1307535144] 'read index received' (duration: 445.979283ms)","trace[1307535144] 'applied index is now lower than readState.Index' (duration: 174.155µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:07.113587Z","caller":"traceutil/trace.go:171","msg":"trace[1821453160] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"466.810414ms","start":"2026-07-30T23:06:06.646742Z","end":"2026-07-30T23:06:07.113553Z","steps":["trace[1821453160] 'process raft request' (duration: 466.587768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:07.11369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.070961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:06:07.113782Z","caller":"traceutil/trace.go:171","msg":"trace[1425937994] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:1080; }","duration":"478.197295ms","start":"2026-07-30T23:06:06.635557Z","end":"2026-07-30T23:06:07.113754Z","steps":["trace[1425937994] 'agreement among raft nodes before linearized reading' (duration: 478.043491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:07.113725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:06.646722Z","time spent":"466.937787ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:06:07.113793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.668772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-30T23:06:07.113833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:06.635545Z","time spent":"478.279407ms","remote":"127.0.0.1:46080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"info","ts":"2026-07-30T23:06:07.11385Z","caller":"traceutil/trace.go:171","msg":"trace[1342155856] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1080; }","duration":"480.787546ms","start":"2026-07-30T23:06:06.633047Z","end":"2026-07-30T23:06:07.113834Z","steps":["trace[1342155856] 'agreement among raft nodes before linearized reading' (duration: 480.623152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:07.113888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:06.633024Z","time spent":"480.854558ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-30T23:06:07.115207Z","caller":"traceutil/trace.go:171","msg":"trace[839911387] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"446.938329ms","start":"2026-07-30T23:06:06.668231Z","end":"2026-07-30T23:06:07.11517Z","steps":["trace[839911387] 'process raft request' (duration: 446.588519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:07.115549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:06.668218Z","time spent":"447.098783ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:06:24.977568Z","caller":"traceutil/trace.go:171","msg":"trace[1265735598] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"149.051152ms","start":"2026-07-30T23:06:24.828491Z","end":"2026-07-30T23:06:24.977542Z","steps":["trace[1265735598] 'process raft request' (duration: 148.921268ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:25.633266Z","caller":"traceutil/trace.go:171","msg":"trace[465738144] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"214.168995ms","start":"2026-07-30T23:06:25.419067Z","end":"2026-07-30T23:06:25.633236Z","steps":["trace[465738144] 'process raft request' (duration: 198.907287ms)","trace[465738144] 'compare' (duration: 15.135074ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:32.566787Z","caller":"traceutil/trace.go:171","msg":"trace[1661146528] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"101.601133ms","start":"2026-07-30T23:06:32.464753Z","end":"2026-07-30T23:06:32.566354Z","steps":["trace[1661146528] 'process raft request' (duration: 101.374417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:37.488689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.517241ms","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-07-30T23:06:37.488772Z","caller":"traceutil/trace.go:171","msg":"trace[1420741196] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1398; }","duration":"101.628865ms","start":"2026-07-30T23:06:37.387122Z","end":"2026-07-30T23:06:37.488751Z","steps":["trace[1420741196] 'range keys from in-memory index tree' (duration: 101.397868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:37.488769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.885658ms","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-07-30T23:06:37.488923Z","caller":"traceutil/trace.go:171","msg":"trace[1393251555] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1398; }","duration":"103.110154ms","start":"2026-07-30T23:06:37.385792Z","end":"2026-07-30T23:06:37.488902Z","steps":["trace[1393251555] 'count revisions from in-memory index tree' (duration: 102.752134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:37.489023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.423525ms","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-07-30T23:06:37.489083Z","caller":"traceutil/trace.go:171","msg":"trace[152684672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1398; }","duration":"102.528378ms","start":"2026-07-30T23:06:37.386538Z","end":"2026-07-30T23:06:37.489066Z","steps":["trace[152684672] 'range keys from in-memory index tree' (duration: 102.294923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:37.489544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.934436ms","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-07-30T23:06:37.489659Z","caller":"traceutil/trace.go:171","msg":"trace[1077154127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1398; }","duration":"101.686795ms","start":"2026-07-30T23:06:37.387953Z","end":"2026-07-30T23:06:37.48964Z","steps":["trace[1077154127] 'range keys from in-memory index tree' (duration: 100.818112ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:39.518719Z","caller":"traceutil/trace.go:171","msg":"trace[1812521906] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"228.722696ms","start":"2026-07-30T23:06:39.289957Z","end":"2026-07-30T23:06:39.51868Z","steps":["trace[1812521906] 'process raft request' (duration: 228.49837ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:40.13226Z","caller":"traceutil/trace.go:171","msg":"trace[1261948333] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"201.839569ms","start":"2026-07-30T23:06:39.930391Z","end":"2026-07-30T23:06:40.132231Z","steps":["trace[1261948333] 'process raft request' (duration: 133.521475ms)","trace[1261948333] 'compare' (duration: 68.14205ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:06:41.773044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.925306ms","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-07-30T23:06:41.773151Z","caller":"traceutil/trace.go:171","msg":"trace[85318616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1420; }","duration":"154.075791ms","start":"2026-07-30T23:06:41.619043Z","end":"2026-07-30T23:06:41.773119Z","steps":["trace[85318616] 'agreement among raft nodes before linearized reading' (duration: 85.991922ms)","trace[85318616] 'range keys from in-memory index tree' (duration: 67.844432ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:41.773346Z","caller":"traceutil/trace.go:171","msg":"trace[522418915] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"136.259699ms","start":"2026-07-30T23:06:41.637046Z","end":"2026-07-30T23:06:41.773306Z","steps":["trace[522418915] 'process raft request' (duration: 67.949464ms)","trace[522418915] 'compare' (duration: 67.789461ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:06:41.77342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.069008ms","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-07-30T23:06:41.773585Z","caller":"traceutil/trace.go:171","msg":"trace[562474441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1421; }","duration":"138.305835ms","start":"2026-07-30T23:06:41.635258Z","end":"2026-07-30T23:06:41.773564Z","steps":["trace[562474441] 'agreement among raft nodes before linearized reading' (duration: 137.950025ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:41.824302Z","caller":"traceutil/trace.go:171","msg":"trace[1087514444] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"115.859258ms","start":"2026-07-30T23:06:41.70842Z","end":"2026-07-30T23:06:41.824279Z","steps":["trace[1087514444] 'process raft request' (duration: 115.741665ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:41.960568Z","caller":"traceutil/trace.go:171","msg":"trace[249650037] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"110.857703ms","start":"2026-07-30T23:06:41.84967Z","end":"2026-07-30T23:06:41.960527Z","steps":["trace[249650037] 'process raft request' (duration: 38.318024ms)","trace[249650037] 'compare' (duration: 72.400385ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:42.899681Z","caller":"traceutil/trace.go:171","msg":"trace[1377422509] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"114.415829ms","start":"2026-07-30T23:06:42.785232Z","end":"2026-07-30T23:06:42.899648Z","steps":["trace[1377422509] 'process raft request' (duration: 16.179706ms)","trace[1377422509] 'compare' (duration: 98.045738ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:42.899923Z","caller":"traceutil/trace.go:171","msg":"trace[1010498857] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"106.395343ms","start":"2026-07-30T23:06:42.793501Z","end":"2026-07-30T23:06:42.899896Z","steps":["trace[1010498857] 'process raft request' (duration: 106.094804ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:43.173749Z","caller":"traceutil/trace.go:171","msg":"trace[529018683] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"270.013131ms","start":"2026-07-30T23:06:42.903702Z","end":"2026-07-30T23:06:43.173715Z","steps":["trace[529018683] 'process raft request' (duration: 213.167556ms)","trace[529018683] 'compare' (duration: 56.622759ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:43.247325Z","caller":"traceutil/trace.go:171","msg":"trace[1021657797] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"342.906779ms","start":"2026-07-30T23:06:42.904396Z","end":"2026-07-30T23:06:43.247303Z","steps":["trace[1021657797] 'process raft request' (duration: 342.778225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:43.24748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:42.904366Z","time spent":"343.032332ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:06:43.641727Z","caller":"traceutil/trace.go:171","msg":"trace[801971485] linearizableReadLoop","detail":"{readStateIndex:1484; appliedIndex:1483; }","duration":"366.581328ms","start":"2026-07-30T23:06:43.27512Z","end":"2026-07-30T23:06:43.641701Z","steps":["trace[801971485] 'read index received' (duration: 303.722161ms)","trace[801971485] 'applied index is now lower than readState.Index' (duration: 62.858047ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:43.641755Z","caller":"traceutil/trace.go:171","msg":"trace[1709685709] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"384.907652ms","start":"2026-07-30T23:06:43.256813Z","end":"2026-07-30T23:06:43.64172Z","steps":["trace[1709685709] 'process raft request' (duration: 322.170169ms)","trace[1709685709] 'compare' (duration: 62.61273ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:06:43.641945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:43.2568Z","time spent":"385.037237ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:06:43.641963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.830515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-07-30T23:06:43.642035Z","caller":"traceutil/trace.go:171","msg":"trace[860786718] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1434; }","duration":"366.945918ms","start":"2026-07-30T23:06:43.27507Z","end":"2026-07-30T23:06:43.642016Z","steps":["trace[860786718] 'agreement among raft nodes before linearized reading' (duration: 366.733043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:43.642083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:43.275049Z","time spent":"367.02535ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-30T23:06:43.704236Z","caller":"traceutil/trace.go:171","msg":"trace[1963779661] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"121.702526ms","start":"2026-07-30T23:06:43.582515Z","end":"2026-07-30T23:06:43.704218Z","steps":["trace[1963779661] 'process raft request' (duration: 121.575812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:44.474411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.077258ms","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-07-30T23:06:44.474494Z","caller":"traceutil/trace.go:171","msg":"trace[1099145276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1436; }","duration":"625.215492ms","start":"2026-07-30T23:06:43.84926Z","end":"2026-07-30T23:06:44.474476Z","steps":["trace[1099145276] 'range keys from in-memory index tree' (duration: 624.908153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:44.474538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:43.849236Z","time spent":"625.290373ms","remote":"127.0.0.1:46206","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-07-30T23:06:44.474582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.565163ms","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-07-30T23:06:44.474725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.668622ms","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-07-30T23:06:44.474761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.342873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:06:44.474795Z","caller":"traceutil/trace.go:171","msg":"trace[2078509266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1436; }","duration":"509.788845ms","start":"2026-07-30T23:06:43.96499Z","end":"2026-07-30T23:06:44.474779Z","steps":["trace[2078509266] 'range keys from in-memory index tree' (duration: 509.414425ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:44.474828Z","caller":"traceutil/trace.go:171","msg":"trace[104175483] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1436; }","duration":"712.447196ms","start":"2026-07-30T23:06:43.762364Z","end":"2026-07-30T23:06:44.474811Z","steps":["trace[104175483] 'count revisions from in-memory index tree' (duration: 712.23005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:44.474846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:43.964961Z","time spent":"509.872957ms","remote":"127.0.0.1:46206","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-07-30T23:06:44.474874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:43.762345Z","time spent":"712.514568ms","remote":"127.0.0.1:46390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-07-30T23:06:44.47494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.337427ms","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-07-30T23:06:44.475021Z","caller":"traceutil/trace.go:171","msg":"trace[875441055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1436; }","duration":"624.502312ms","start":"2026-07-30T23:06:43.850497Z","end":"2026-07-30T23:06:44.475Z","steps":["trace[875441055] 'range keys from in-memory index tree' (duration: 624.162663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:44.47506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:43.850477Z","time spent":"624.574434ms","remote":"127.0.0.1:46206","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-07-30T23:06:44.475101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.589589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:06:44.474734Z","caller":"traceutil/trace.go:171","msg":"trace[1193344341] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1436; }","duration":"624.753809ms","start":"2026-07-30T23:06:43.84996Z","end":"2026-07-30T23:06:44.474714Z","steps":["trace[1193344341] 'range keys from in-memory index tree' (duration: 624.42154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:44.475217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.158589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-30T23:06:44.475226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:43.849953Z","time spent":"625.256992ms","remote":"127.0.0.1:46206","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-07-30T23:06:44.475246Z","caller":"traceutil/trace.go:171","msg":"trace[737089982] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1436; }","duration":"625.223351ms","start":"2026-07-30T23:06:43.850014Z","end":"2026-07-30T23:06:44.475237Z","steps":["trace[737089982] 'range keys from in-memory index tree' (duration: 625.043416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:44.475277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:43.849995Z","time spent":"625.276423ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-30T23:06:44.475174Z","caller":"traceutil/trace.go:171","msg":"trace[322215769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1436; }","duration":"436.697771ms","start":"2026-07-30T23:06:44.038458Z","end":"2026-07-30T23:06:44.475156Z","steps":["trace[322215769] 'range keys from in-memory index tree' (duration: 436.445584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:44.475352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:44.038447Z","time spent":"436.893017ms","remote":"127.0.0.1:45966","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-07-30T23:06:45.539427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874443288687298,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:06:45.574947Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.057333869s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T23:06:45.575332Z","caller":"traceutil/trace.go:171","msg":"trace[671707252] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"1.095260953s","start":"2026-07-30T23:06:44.480045Z","end":"2026-07-30T23:06:45.575306Z","steps":["trace[671707252] 'process raft request' (duration: 1.095109339s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:45.575498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:44.480019Z","time spent":"1.095383686s","remote":"127.0.0.1:46206","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-07-30T23:06:45.642875Z","caller":"traceutil/trace.go:171","msg":"trace[1124565342] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"1.162483288s","start":"2026-07-30T23:06:44.480323Z","end":"2026-07-30T23:06:45.642807Z","steps":["trace[1124565342] 'process raft request' (duration: 1.162292633s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:45.643098Z","caller":"traceutil/trace.go:171","msg":"trace[706278897] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"1.162669793s","start":"2026-07-30T23:06:44.480389Z","end":"2026-07-30T23:06:45.643059Z","steps":["trace[706278897] 'process raft request' (duration: 1.162268212s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:45.64319Z","caller":"traceutil/trace.go:171","msg":"trace[1448931458] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"1.162493888s","start":"2026-07-30T23:06:44.480684Z","end":"2026-07-30T23:06:45.643178Z","steps":["trace[1448931458] 'process raft request' (duration: 1.162008575s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:45.643966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:44.480311Z","time spent":"1.163518156s","remote":"127.0.0.1:46206","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-07-30T23:06:45.643983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:44.480368Z","time spent":"1.163551587s","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:06:45.64322Z","caller":"traceutil/trace.go:171","msg":"trace[1300280760] linearizableReadLoop","detail":"{readStateIndex:1491; appliedIndex:1486; }","duration":"604.570984ms","start":"2026-07-30T23:06:45.03864Z","end":"2026-07-30T23:06:45.643211Z","steps":["trace[1300280760] 'read index received' (duration: 536.499666ms)","trace[1300280760] 'applied index is now lower than readState.Index' (duration: 68.070288ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:06:45.644095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:44.480662Z","time spent":"1.163358612s","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:06:45.643236Z","caller":"traceutil/trace.go:171","msg":"trace[233758093] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"1.163000012s","start":"2026-07-30T23:06:44.480214Z","end":"2026-07-30T23:06:45.643214Z","steps":["trace[233758093] 'process raft request' (duration: 1.160103274s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:45.644247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:44.480199Z","time spent":"1.164006679s","remote":"127.0.0.1:46206","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-07-30T23:06:45.643284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.633555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:06:45.644284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.803251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-30T23:06:45.644348Z","caller":"traceutil/trace.go:171","msg":"trace[1443888533] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1442; }","duration":"507.900894ms","start":"2026-07-30T23:06:45.136417Z","end":"2026-07-30T23:06:45.644318Z","steps":["trace[1443888533] 'agreement among raft nodes before linearized reading' (duration: 507.7527ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:45.644358Z","caller":"traceutil/trace.go:171","msg":"trace[1498268370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1442; }","duration":"605.724615ms","start":"2026-07-30T23:06:45.038592Z","end":"2026-07-30T23:06:45.644317Z","steps":["trace[1498268370] 'agreement among raft nodes before linearized reading' (duration: 604.646056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:45.644435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:45.1364Z","time spent":"508.027807ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T23:06:45.644782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:45.038569Z","time spent":"606.170547ms","remote":"127.0.0.1:45966","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-07-30T23:06:45.995588Z","caller":"traceutil/trace.go:171","msg":"trace[1406193262] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"345.997912ms","start":"2026-07-30T23:06:45.649235Z","end":"2026-07-30T23:06:45.995233Z","steps":["trace[1406193262] 'process raft request' (duration: 345.835618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:45.996215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:45.649214Z","time spent":"346.914346ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:06:45.996352Z","caller":"traceutil/trace.go:171","msg":"trace[624327478] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"346.167537ms","start":"2026-07-30T23:06:45.65016Z","end":"2026-07-30T23:06:45.996327Z","steps":["trace[624327478] 'process raft request' (duration: 345.983352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:45.996495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:45.650139Z","time spent":"346.285779ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:06:45.996598Z","caller":"traceutil/trace.go:171","msg":"trace[1130199587] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"342.198359ms","start":"2026-07-30T23:06:45.654378Z","end":"2026-07-30T23:06:45.996576Z","steps":["trace[1130199587] 'process raft request' (duration: 341.891191ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:45.996719Z","caller":"traceutil/trace.go:171","msg":"trace[575426931] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"287.147553ms","start":"2026-07-30T23:06:45.709559Z","end":"2026-07-30T23:06:45.996707Z","steps":["trace[575426931] 'process raft request' (duration: 286.942957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:45.996743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:45.654369Z","time spent":"342.302732ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:06:45.996729Z","caller":"traceutil/trace.go:171","msg":"trace[1405824920] linearizableReadLoop","detail":"{readStateIndex:1495; appliedIndex:1492; }","duration":"301.84533ms","start":"2026-07-30T23:06:45.694859Z","end":"2026-07-30T23:06:45.996704Z","steps":["trace[1405824920] 'read index received' (duration: 300.095633ms)","trace[1405824920] 'applied index is now lower than readState.Index' (duration: 1.748527ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:06:45.996855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.983693ms","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-07-30T23:06:45.99692Z","caller":"traceutil/trace.go:171","msg":"trace[2060063445] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1446; }","duration":"302.078296ms","start":"2026-07-30T23:06:45.694825Z","end":"2026-07-30T23:06:45.996903Z","steps":["trace[2060063445] 'agreement among raft nodes before linearized reading' (duration: 301.973353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:45.996975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:45.694813Z","time spent":"302.145188ms","remote":"127.0.0.1:49706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-07-30T23:07:01.271138Z","caller":"traceutil/trace.go:171","msg":"trace[1522580548] transaction","detail":"{read_only:false; response_revision:1599; number_of_response:1; }","duration":"180.035731ms","start":"2026-07-30T23:07:01.091068Z","end":"2026-07-30T23:07:01.271104Z","steps":["trace[1522580548] 'process raft request' (duration: 179.796644ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:06.946712Z","caller":"traceutil/trace.go:171","msg":"trace[1683352461] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"116.31005ms","start":"2026-07-30T23:07:06.830357Z","end":"2026-07-30T23:07:06.946667Z","steps":["trace[1683352461] 'process raft request' (duration: 116.099845ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:07.336874Z","caller":"traceutil/trace.go:171","msg":"trace[1099381812] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"203.443533ms","start":"2026-07-30T23:07:07.133399Z","end":"2026-07-30T23:07:07.336842Z","steps":["trace[1099381812] 'process raft request' (duration: 203.190646ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:14.042261Z","caller":"traceutil/trace.go:171","msg":"trace[405357010] linearizableReadLoop","detail":"{readStateIndex:1896; appliedIndex:1895; }","duration":"309.872726ms","start":"2026-07-30T23:07:13.732351Z","end":"2026-07-30T23:07:14.042224Z","steps":["trace[405357010] 'read index received' (duration: 306.111385ms)","trace[405357010] 'applied index is now lower than readState.Index' (duration: 3.759501ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:07:14.042338Z","caller":"traceutil/trace.go:171","msg":"trace[1969255738] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"498.117229ms","start":"2026-07-30T23:07:13.544182Z","end":"2026-07-30T23:07:14.042299Z","steps":["trace[1969255738] 'process raft request' (duration: 494.382448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:14.042539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:13.544154Z","time spent":"498.277724ms","remote":"127.0.0.1:46024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120300,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:07:14.042631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.797633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-30T23:07:14.042681Z","caller":"traceutil/trace.go:171","msg":"trace[51412781] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1834; }","duration":"263.923506ms","start":"2026-07-30T23:07:13.778743Z","end":"2026-07-30T23:07:14.042666Z","steps":["trace[51412781] 'agreement among raft nodes before linearized reading' (duration: 263.71241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:14.042689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.889682ms","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-07-30T23:07:14.042784Z","caller":"traceutil/trace.go:171","msg":"trace[1719749158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1834; }","duration":"231.993575ms","start":"2026-07-30T23:07:13.810767Z","end":"2026-07-30T23:07:14.042761Z","steps":["trace[1719749158] 'agreement among raft nodes before linearized reading' (duration: 231.741798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:14.042891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.730562ms","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-07-30T23:07:14.042963Z","caller":"traceutil/trace.go:171","msg":"trace[1687568299] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1834; }","duration":"236.817015ms","start":"2026-07-30T23:07:13.806132Z","end":"2026-07-30T23:07:14.042949Z","steps":["trace[1687568299] 'agreement among raft nodes before linearized reading' (duration: 236.585788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:14.042992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.272127ms","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-07-30T23:07:14.04303Z","caller":"traceutil/trace.go:171","msg":"trace[503149457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1834; }","duration":"237.331948ms","start":"2026-07-30T23:07:13.805686Z","end":"2026-07-30T23:07:14.043017Z","steps":["trace[503149457] 'agreement among raft nodes before linearized reading' (duration: 237.211315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:14.042941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.556605ms","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-07-30T23:07:14.043197Z","caller":"traceutil/trace.go:171","msg":"trace[759305796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1834; }","duration":"310.828712ms","start":"2026-07-30T23:07:13.732328Z","end":"2026-07-30T23:07:14.043156Z","steps":["trace[759305796] 'agreement among raft nodes before linearized reading' (duration: 310.127173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:14.043288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:13.732242Z","time spent":"311.032497ms","remote":"127.0.0.1:46206","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-07-30T23:07:29.61154Z","caller":"traceutil/trace.go:171","msg":"trace[1255083418] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"182.602531ms","start":"2026-07-30T23:07:29.428905Z","end":"2026-07-30T23:07:29.611507Z","steps":["trace[1255083418] 'process raft request' (duration: 182.417646ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:38.326118Z","caller":"traceutil/trace.go:171","msg":"trace[1839210448] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"111.997572ms","start":"2026-07-30T23:07:38.214087Z","end":"2026-07-30T23:07:38.326085Z","steps":["trace[1839210448] 'process raft request' (duration: 25.820802ms)","trace[1839210448] 'compare' (duration: 86.074928ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:07:39.289692Z","caller":"traceutil/trace.go:171","msg":"trace[2075968524] linearizableReadLoop","detail":"{readStateIndex:2305; appliedIndex:2304; }","duration":"347.871386ms","start":"2026-07-30T23:07:38.94179Z","end":"2026-07-30T23:07:39.289661Z","steps":["trace[2075968524] 'read index received' (duration: 347.592508ms)","trace[2075968524] 'applied index is now lower than readState.Index' (duration: 277.498µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:07:39.289787Z","caller":"traceutil/trace.go:171","msg":"trace[1319640063] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"694.967671ms","start":"2026-07-30T23:07:38.59478Z","end":"2026-07-30T23:07:39.289748Z","steps":["trace[1319640063] 'process raft request' (duration: 694.618922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:39.289994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:38.59475Z","time spent":"695.122535ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:07:39.290002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.181713ms","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-07-30T23:07:39.290075Z","caller":"traceutil/trace.go:171","msg":"trace[1255925527] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2217; }","duration":"348.326027ms","start":"2026-07-30T23:07:38.941727Z","end":"2026-07-30T23:07:39.290053Z","steps":["trace[1255925527] 'agreement among raft nodes before linearized reading' (duration: 348.088501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:39.290137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:38.941699Z","time spent":"348.42106ms","remote":"127.0.0.1:40174","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-07-30T23:07:39.303423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.345928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:07:39.303498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.295201ms","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-07-30T23:07:39.303532Z","caller":"traceutil/trace.go:171","msg":"trace[1792595042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2218; }","duration":"263.522703ms","start":"2026-07-30T23:07:39.039982Z","end":"2026-07-30T23:07:39.303505Z","steps":["trace[1792595042] 'agreement among raft nodes before linearized reading' (duration: 263.339088ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:39.30356Z","caller":"traceutil/trace.go:171","msg":"trace[1214959139] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2218; }","duration":"172.395303ms","start":"2026-07-30T23:07:39.131145Z","end":"2026-07-30T23:07:39.30354Z","steps":["trace[1214959139] 'agreement among raft nodes before linearized reading' (duration: 172.27684ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:39.303439Z","caller":"traceutil/trace.go:171","msg":"trace[999181770] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"101.647673ms","start":"2026-07-30T23:07:39.201754Z","end":"2026-07-30T23:07:39.303401Z","steps":["trace[999181770] 'process raft request' (duration: 101.390036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:39.304023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.911152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-07-30T23:07:39.304141Z","caller":"traceutil/trace.go:171","msg":"trace[241907096] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2218; }","duration":"204.080547ms","start":"2026-07-30T23:07:39.100034Z","end":"2026-07-30T23:07:39.304114Z","steps":["trace[241907096] 'agreement among raft nodes before linearized reading' (duration: 203.43686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:39.847611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874443288688859,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T23:07:40.046596Z","caller":"traceutil/trace.go:171","msg":"trace[1991009019] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"737.344433ms","start":"2026-07-30T23:07:39.309211Z","end":"2026-07-30T23:07:40.046555Z","steps":["trace[1991009019] 'process raft request' (duration: 651.151882ms)","trace[1991009019] 'compare' (duration: 85.168654ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:07:40.046629Z","caller":"traceutil/trace.go:171","msg":"trace[1972604359] linearizableReadLoop","detail":"{readStateIndex:2309; appliedIndex:2306; }","duration":"699.718985ms","start":"2026-07-30T23:07:39.346862Z","end":"2026-07-30T23:07:40.046581Z","steps":["trace[1972604359] 'read index received' (duration: 613.449731ms)","trace[1972604359] 'applied index is now lower than readState.Index' (duration: 86.267214ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:07:40.046858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:39.309184Z","time spent":"737.498257ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:07:40.04705Z","caller":"traceutil/trace.go:171","msg":"trace[187710691] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"400.72485ms","start":"2026-07-30T23:07:39.64625Z","end":"2026-07-30T23:07:40.046975Z","steps":["trace[187710691] 'process raft request' (duration: 400.272228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:40.047209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.133424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T23:07:40.047261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.975845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-30T23:07:40.047263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.954473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-30T23:07:40.047311Z","caller":"traceutil/trace.go:171","msg":"trace[1236179299] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"507.841706ms","start":"2026-07-30T23:07:39.538938Z","end":"2026-07-30T23:07:40.04678Z","steps":["trace[1236179299] 'process raft request' (duration: 507.436905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:40.047284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.60541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11912"} {"level":"info","ts":"2026-07-30T23:07:40.047327Z","caller":"traceutil/trace.go:171","msg":"trace[687810443] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2221; }","duration":"105.326368ms","start":"2026-07-30T23:07:39.941974Z","end":"2026-07-30T23:07:40.0473Z","steps":["trace[687810443] 'agreement among raft nodes before linearized reading' (duration: 105.105363ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:40.047338Z","caller":"traceutil/trace.go:171","msg":"trace[234973556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2221; }","duration":"689.112249ms","start":"2026-07-30T23:07:39.358206Z","end":"2026-07-30T23:07:40.047319Z","steps":["trace[234973556] 'agreement among raft nodes before linearized reading' (duration: 688.860132ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:40.04735Z","caller":"traceutil/trace.go:171","msg":"trace[2122804011] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2221; }","duration":"687.071485ms","start":"2026-07-30T23:07:39.360257Z","end":"2026-07-30T23:07:40.047329Z","steps":["trace[2122804011] 'agreement among raft nodes before linearized reading' (duration: 686.828319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:40.047309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.403322ms","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-07-30T23:07:40.047373Z","caller":"traceutil/trace.go:171","msg":"trace[1750190860] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2221; }","duration":"214.766495ms","start":"2026-07-30T23:07:39.832583Z","end":"2026-07-30T23:07:40.047349Z","steps":["trace[1750190860] 'agreement among raft nodes before linearized reading' (duration: 214.487277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:40.047399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:39.358178Z","time spent":"689.212512ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T23:07:40.047426Z","caller":"traceutil/trace.go:171","msg":"trace[759877526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2221; }","duration":"700.576177ms","start":"2026-07-30T23:07:39.346825Z","end":"2026-07-30T23:07:40.047401Z","steps":["trace[759877526] 'agreement among raft nodes before linearized reading' (duration: 700.202527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:40.047243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:39.64622Z","time spent":"400.921115ms","remote":"127.0.0.1:46206","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-07-30T23:07:40.047486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:39.346806Z","time spent":"700.670429ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T23:07:40.04737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:39.538877Z","time spent":"508.461481ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:07:40.047241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.480578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:07:40.047682Z","caller":"traceutil/trace.go:171","msg":"trace[692096915] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2221; }","duration":"634.979261ms","start":"2026-07-30T23:07:39.412687Z","end":"2026-07-30T23:07:40.047666Z","steps":["trace[692096915] 'agreement among raft nodes before linearized reading' (duration: 634.393796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:40.047726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:39.412673Z","time spent":"635.037143ms","remote":"127.0.0.1:49634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-30T23:07:40.0474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:39.360246Z","time spent":"687.144298ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-30T23:07:40.560697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874443288688864,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T23:07:40.949384Z","caller":"traceutil/trace.go:171","msg":"trace[1193794599] linearizableReadLoop","detail":"{readStateIndex:2314; appliedIndex:2309; }","duration":"889.233982ms","start":"2026-07-30T23:07:40.060124Z","end":"2026-07-30T23:07:40.949358Z","steps":["trace[1193794599] 'read index received' (duration: 885.032503ms)","trace[1193794599] 'applied index is now lower than readState.Index' (duration: 4.200079ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:07:40.949896Z","caller":"traceutil/trace.go:171","msg":"trace[827509085] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"784.973782ms","start":"2026-07-30T23:07:40.164904Z","end":"2026-07-30T23:07:40.949878Z","steps":["trace[827509085] 'process raft request' (duration: 784.378766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:40.950051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:40.164888Z","time spent":"785.054733ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:07:40.949917Z","caller":"traceutil/trace.go:171","msg":"trace[253365363] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"895.68903ms","start":"2026-07-30T23:07:40.054176Z","end":"2026-07-30T23:07:40.949865Z","steps":["trace[253365363] 'process raft request' (duration: 895.068994ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:40.950127Z","caller":"traceutil/trace.go:171","msg":"trace[407897042] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"897.20829ms","start":"2026-07-30T23:07:40.052877Z","end":"2026-07-30T23:07:40.950085Z","steps":["trace[407897042] 'process raft request' (duration: 892.181139ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:40.950147Z","caller":"traceutil/trace.go:171","msg":"trace[729929115] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"895.979698ms","start":"2026-07-30T23:07:40.054124Z","end":"2026-07-30T23:07:40.950103Z","steps":["trace[729929115] 'process raft request' (duration: 895.071834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:40.950333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.65733ms","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-07-30T23:07:40.950392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.748746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-30T23:07:40.950392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.385744ms","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-07-30T23:07:40.950447Z","caller":"traceutil/trace.go:171","msg":"trace[158963954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2226; }","duration":"619.829367ms","start":"2026-07-30T23:07:40.330606Z","end":"2026-07-30T23:07:40.950435Z","steps":["trace[158963954] 'agreement among raft nodes before linearized reading' (duration: 619.619812ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:40.950456Z","caller":"traceutil/trace.go:171","msg":"trace[898407073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2226; }","duration":"745.829984ms","start":"2026-07-30T23:07:40.204596Z","end":"2026-07-30T23:07:40.950426Z","steps":["trace[898407073] 'agreement among raft nodes before linearized reading' (duration: 745.544347ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:40.950475Z","caller":"traceutil/trace.go:171","msg":"trace[133046890] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2226; }","duration":"652.491187ms","start":"2026-07-30T23:07:40.29796Z","end":"2026-07-30T23:07:40.950451Z","steps":["trace[133046890] 'agreement among raft nodes before linearized reading' (duration: 652.311942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:40.950496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:40.330595Z","time spent":"619.893079ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T23:07:40.950503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.357783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-30T23:07:40.950393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:40.054156Z","time spent":"896.04311ms","remote":"127.0.0.1:46206","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-07-30T23:07:40.950361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:40.054104Z","time spent":"896.139732ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:07:40.950535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:40.297944Z","time spent":"652.578829ms","remote":"127.0.0.1:40174","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-07-30T23:07:40.950168Z","caller":"traceutil/trace.go:171","msg":"trace[1804233422] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"735.247168ms","start":"2026-07-30T23:07:40.214876Z","end":"2026-07-30T23:07:40.950123Z","steps":["trace[1804233422] 'process raft request' (duration: 734.445397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:40.950961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:40.214861Z","time spent":"735.990437ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:07:40.950369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.244709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:07:40.950362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:40.052846Z","time spent":"897.383745ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:07:40.951171Z","caller":"traceutil/trace.go:171","msg":"trace[490206653] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2226; }","duration":"891.029569ms","start":"2026-07-30T23:07:40.060088Z","end":"2026-07-30T23:07:40.951117Z","steps":["trace[490206653] 'agreement among raft nodes before linearized reading' (duration: 890.236898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:40.951231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:40.060072Z","time spent":"891.142913ms","remote":"127.0.0.1:46378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-30T23:07:40.950522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:40.204576Z","time spent":"745.927386ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-30T23:07:40.950544Z","caller":"traceutil/trace.go:171","msg":"trace[2076735514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2226; }","duration":"737.430545ms","start":"2026-07-30T23:07:40.2131Z","end":"2026-07-30T23:07:40.950531Z","steps":["trace[2076735514] 'agreement among raft nodes before linearized reading' (duration: 737.305211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:40.951407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:40.213075Z","time spent":"738.312908ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-30T23:07:46.136643Z","caller":"traceutil/trace.go:171","msg":"trace[1781663010] transaction","detail":"{read_only:false; response_revision:2277; number_of_response:1; }","duration":"108.488532ms","start":"2026-07-30T23:07:46.028119Z","end":"2026-07-30T23:07:46.136607Z","steps":["trace[1781663010] 'process raft request' (duration: 108.200894ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:47.595015Z","caller":"traceutil/trace.go:171","msg":"trace[1558829680] transaction","detail":"{read_only:false; response_revision:2291; number_of_response:1; }","duration":"278.092281ms","start":"2026-07-30T23:07:47.316892Z","end":"2026-07-30T23:07:47.594984Z","steps":["trace[1558829680] 'process raft request' (duration: 277.843564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:53.506473Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874443288689099,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T23:07:53.98479Z","caller":"traceutil/trace.go:171","msg":"trace[1390355311] linearizableReadLoop","detail":"{readStateIndex:2427; appliedIndex:2426; }","duration":"978.853863ms","start":"2026-07-30T23:07:53.005901Z","end":"2026-07-30T23:07:53.984755Z","steps":["trace[1390355311] 'read index received' (duration: 978.647618ms)","trace[1390355311] 'applied index is now lower than readState.Index' (duration: 204.425µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:07:53.984925Z","caller":"traceutil/trace.go:171","msg":"trace[142764037] transaction","detail":"{read_only:false; response_revision:2336; number_of_response:1; }","duration":"991.871021ms","start":"2026-07-30T23:07:52.993006Z","end":"2026-07-30T23:07:53.984877Z","steps":["trace[142764037] 'process raft request' (duration: 991.523062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:53.985072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.14431ms","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-07-30T23:07:53.985132Z","caller":"traceutil/trace.go:171","msg":"trace[497839234] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2336; }","duration":"979.266673ms","start":"2026-07-30T23:07:53.005848Z","end":"2026-07-30T23:07:53.985115Z","steps":["trace[497839234] 'agreement among raft nodes before linearized reading' (duration: 979.065178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:53.985132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:52.992975Z","time spent":"992.054666ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:07:53.98518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:53.005823Z","time spent":"979.345766ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T23:07:53.985923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.088845ms","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-07-30T23:07:53.985992Z","caller":"traceutil/trace.go:171","msg":"trace[645219729] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2337; }","duration":"950.175307ms","start":"2026-07-30T23:07:53.035798Z","end":"2026-07-30T23:07:53.985974Z","steps":["trace[645219729] 'agreement among raft nodes before linearized reading' (duration: 950.034373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:53.986187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.322475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-30T23:07:53.986235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:53.035785Z","time spent":"950.442594ms","remote":"127.0.0.1:40174","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-07-30T23:07:53.985996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.969932ms","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-07-30T23:07:53.986295Z","caller":"traceutil/trace.go:171","msg":"trace[985960101] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2337; }","duration":"964.475918ms","start":"2026-07-30T23:07:53.021792Z","end":"2026-07-30T23:07:53.986268Z","steps":["trace[985960101] 'agreement among raft nodes before linearized reading' (duration: 964.131409ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:53.986356Z","caller":"traceutil/trace.go:171","msg":"trace[1297437196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2337; }","duration":"970.330581ms","start":"2026-07-30T23:07:53.015993Z","end":"2026-07-30T23:07:53.986324Z","steps":["trace[1297437196] 'agreement among raft nodes before linearized reading' (duration: 969.857379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:53.985973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.870108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-30T23:07:53.986387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:53.021775Z","time spent":"964.592292ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-30T23:07:53.986104Z","caller":"traceutil/trace.go:171","msg":"trace[2085438850] transaction","detail":"{read_only:false; response_revision:2337; number_of_response:1; }","duration":"369.274332ms","start":"2026-07-30T23:07:53.616739Z","end":"2026-07-30T23:07:53.986014Z","steps":["trace[2085438850] 'process raft request' (duration: 368.80387ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:53.986455Z","caller":"traceutil/trace.go:171","msg":"trace[416286750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2337; }","duration":"978.418602ms","start":"2026-07-30T23:07:53.00801Z","end":"2026-07-30T23:07:53.986429Z","steps":["trace[416286750] 'agreement among raft nodes before linearized reading' (duration: 977.747594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:53.986442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:53.015982Z","time spent":"970.435904ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T23:07:53.986516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:53.007986Z","time spent":"978.513193ms","remote":"127.0.0.1:46206","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-07-30T23:07:53.98611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.767904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:07:53.986722Z","caller":"traceutil/trace.go:171","msg":"trace[1054399273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2337; }","duration":"948.417062ms","start":"2026-07-30T23:07:53.038287Z","end":"2026-07-30T23:07:53.986704Z","steps":["trace[1054399273] 'agreement among raft nodes before linearized reading' (duration: 947.745044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:53.986719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:53.616708Z","time spent":"369.789575ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:07:53.986764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:53.038279Z","time spent":"948.472563ms","remote":"127.0.0.1:45966","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-07-30T23:07:53.986152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.296628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-30T23:07:53.986817Z","caller":"traceutil/trace.go:171","msg":"trace[209016577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2337; }","duration":"973.993386ms","start":"2026-07-30T23:07:53.012806Z","end":"2026-07-30T23:07:53.986799Z","steps":["trace[209016577] 'agreement among raft nodes before linearized reading' (duration: 973.238037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:53.986867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:53.012791Z","time spent":"974.057678ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T23:07:53.986264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.651685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-30T23:07:53.986984Z","caller":"traceutil/trace.go:171","msg":"trace[602363147] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2337; }","duration":"972.416826ms","start":"2026-07-30T23:07:53.014557Z","end":"2026-07-30T23:07:53.986974Z","steps":["trace[602363147] 'agreement among raft nodes before linearized reading' (duration: 971.323267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:53.987015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:53.014547Z","time spent":"972.459047ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-30T23:08:14.568663Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874443288689421,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T23:08:14.624Z","caller":"traceutil/trace.go:171","msg":"trace[914258462] transaction","detail":"{read_only:false; response_revision:2479; number_of_response:1; }","duration":"568.304188ms","start":"2026-07-30T23:08:14.055668Z","end":"2026-07-30T23:08:14.623972Z","steps":["trace[914258462] 'process raft request' (duration: 568.066511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:14.624247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.055636Z","time spent":"568.499093ms","remote":"127.0.0.1:46206","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-07-30T23:08:14.652463Z","caller":"traceutil/trace.go:171","msg":"trace[536506618] transaction","detail":"{read_only:false; response_revision:2480; number_of_response:1; }","duration":"593.662126ms","start":"2026-07-30T23:08:14.058759Z","end":"2026-07-30T23:08:14.652421Z","steps":["trace[536506618] 'process raft request' (duration: 593.43132ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:14.652488Z","caller":"traceutil/trace.go:171","msg":"trace[922515084] transaction","detail":"{read_only:false; response_revision:2481; number_of_response:1; }","duration":"404.074307ms","start":"2026-07-30T23:08:14.248386Z","end":"2026-07-30T23:08:14.65246Z","steps":["trace[922515084] 'process raft request' (duration: 403.959824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:14.652653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.058739Z","time spent":"593.814781ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:08:14.652653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.24837Z","time spent":"404.1833ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:08:14.652875Z","caller":"traceutil/trace.go:171","msg":"trace[1112120361] transaction","detail":"{read_only:false; response_revision:2482; number_of_response:1; }","duration":"400.058283ms","start":"2026-07-30T23:08:14.252777Z","end":"2026-07-30T23:08:14.652836Z","steps":["trace[1112120361] 'process raft request' (duration: 399.621122ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:14.652936Z","caller":"traceutil/trace.go:171","msg":"trace[1394206123] linearizableReadLoop","detail":"{readStateIndex:2578; appliedIndex:2573; }","duration":"585.162976ms","start":"2026-07-30T23:08:14.067733Z","end":"2026-07-30T23:08:14.652896Z","steps":["trace[1394206123] 'read index received' (duration: 556.09754ms)","trace[1394206123] 'applied index is now lower than readState.Index' (duration: 29.064036ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:08:14.653098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.362692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T23:08:14.653134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.252771Z","time spent":"400.200127ms","remote":"127.0.0.1:46206","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-07-30T23:08:14.653157Z","caller":"traceutil/trace.go:171","msg":"trace[1866786381] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2483; }","duration":"585.451203ms","start":"2026-07-30T23:08:14.067691Z","end":"2026-07-30T23:08:14.653142Z","steps":["trace[1866786381] 'agreement among raft nodes before linearized reading' (duration: 585.32453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:14.653196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.067677Z","time spent":"585.510175ms","remote":"127.0.0.1:46242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":4,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-07-30T23:08:14.65328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.233712ms","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-07-30T23:08:14.65329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.326616ms","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-07-30T23:08:14.653323Z","caller":"traceutil/trace.go:171","msg":"trace[270093783] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2483; }","duration":"524.314014ms","start":"2026-07-30T23:08:14.128999Z","end":"2026-07-30T23:08:14.653313Z","steps":["trace[270093783] 'agreement among raft nodes before linearized reading' (duration: 524.17909ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:14.653336Z","caller":"traceutil/trace.go:171","msg":"trace[2129097295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2483; }","duration":"566.45669ms","start":"2026-07-30T23:08:14.086863Z","end":"2026-07-30T23:08:14.65332Z","steps":["trace[2129097295] 'agreement among raft nodes before linearized reading' (duration: 566.232974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:14.653378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.086839Z","time spent":"566.530501ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T23:08:14.653498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.72431ms","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-07-30T23:08:14.653348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.128984Z","time spent":"524.356004ms","remote":"127.0.0.1:40174","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-07-30T23:08:14.65352Z","caller":"traceutil/trace.go:171","msg":"trace[299431533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2483; }","duration":"540.783892ms","start":"2026-07-30T23:08:14.112731Z","end":"2026-07-30T23:08:14.653515Z","steps":["trace[299431533] 'agreement among raft nodes before linearized reading' (duration: 540.72779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:14.653538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.112713Z","time spent":"540.818863ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T23:08:14.653582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.070857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-30T23:08:14.6536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.325146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-07-30T23:08:14.653604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.269705ms","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-07-30T23:08:14.653646Z","caller":"traceutil/trace.go:171","msg":"trace[588227821] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2483; }","duration":"541.393458ms","start":"2026-07-30T23:08:14.112242Z","end":"2026-07-30T23:08:14.653636Z","steps":["trace[588227821] 'agreement among raft nodes before linearized reading' (duration: 541.278035ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:14.653649Z","caller":"traceutil/trace.go:171","msg":"trace[495240487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2483; }","duration":"564.16434ms","start":"2026-07-30T23:08:14.089469Z","end":"2026-07-30T23:08:14.653633Z","steps":["trace[495240487] 'agreement among raft nodes before linearized reading' (duration: 564.022956ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:14.65366Z","caller":"traceutil/trace.go:171","msg":"trace[252555884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2483; }","duration":"541.359607ms","start":"2026-07-30T23:08:14.112285Z","end":"2026-07-30T23:08:14.653645Z","steps":["trace[252555884] 'agreement among raft nodes before linearized reading' (duration: 541.118231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:14.653675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.112228Z","time spent":"541.440109ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-30T23:08:14.653684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.08945Z","time spent":"564.224882ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T23:08:14.653694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.112269Z","time spent":"541.417558ms","remote":"127.0.0.1:46206","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-07-30T23:08:14.814262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.019505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:08:14.814377Z","caller":"traceutil/trace.go:171","msg":"trace[17724376] transaction","detail":"{read_only:false; response_revision:2484; number_of_response:1; }","duration":"155.797241ms","start":"2026-07-30T23:08:14.658562Z","end":"2026-07-30T23:08:14.814359Z","steps":["trace[17724376] 'compare' (duration: 148.878681ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:14.878589Z","caller":"traceutil/trace.go:171","msg":"trace[561777050] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"219.450826ms","start":"2026-07-30T23:08:14.659089Z","end":"2026-07-30T23:08:14.87854Z","steps":["trace[561777050] 'process raft request' (duration: 219.181749ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:14.878638Z","caller":"traceutil/trace.go:171","msg":"trace[408940989] transaction","detail":"{read_only:false; response_revision:2486; number_of_response:1; }","duration":"219.162849ms","start":"2026-07-30T23:08:14.659448Z","end":"2026-07-30T23:08:14.878611Z","steps":["trace[408940989] 'process raft request' (duration: 219.005365ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:14.878637Z","caller":"traceutil/trace.go:171","msg":"trace[1738175460] transaction","detail":"{read_only:false; response_revision:2487; number_of_response:1; }","duration":"218.137532ms","start":"2026-07-30T23:08:14.660468Z","end":"2026-07-30T23:08:14.878605Z","steps":["trace[1738175460] 'process raft request' (duration: 218.041959ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:14.879047Z","caller":"traceutil/trace.go:171","msg":"trace[1602470632] transaction","detail":"{read_only:false; response_revision:2488; number_of_response:1; }","duration":"218.534242ms","start":"2026-07-30T23:08:14.660486Z","end":"2026-07-30T23:08:14.879021Z","steps":["trace[1602470632] 'process raft request' (duration: 218.06949ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:14.880222Z","caller":"traceutil/trace.go:171","msg":"trace[257361165] transaction","detail":"{read_only:false; response_revision:2489; number_of_response:1; }","duration":"219.684732ms","start":"2026-07-30T23:08:14.660519Z","end":"2026-07-30T23:08:14.880204Z","steps":["trace[257361165] 'process raft request' (duration: 218.415729ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:14.880336Z","caller":"traceutil/trace.go:171","msg":"trace[410921665] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"219.703033ms","start":"2026-07-30T23:08:14.660612Z","end":"2026-07-30T23:08:14.880315Z","steps":["trace[410921665] 'process raft request' (duration: 219.529689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:15.602969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.319987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"warn","ts":"2026-07-30T23:08:15.602988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.059437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:08:15.603071Z","caller":"traceutil/trace.go:171","msg":"trace[1984717203] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2490; }","duration":"468.516303ms","start":"2026-07-30T23:08:15.134532Z","end":"2026-07-30T23:08:15.603048Z","steps":["trace[1984717203] 'range keys from in-memory index tree' (duration: 468.099372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:15.603121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:15.134503Z","time spent":"468.607646ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-30T23:08:15.603527Z","caller":"traceutil/trace.go:171","msg":"trace[1729701820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2490; }","duration":"564.233511ms","start":"2026-07-30T23:08:15.038828Z","end":"2026-07-30T23:08:15.603061Z","steps":["trace[1729701820] 'range keys from in-memory index tree' (duration: 563.76996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:15.603657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:15.038797Z","time spent":"564.844118ms","remote":"127.0.0.1:45966","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-07-30T23:08:17.192246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.186899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:08:17.192417Z","caller":"traceutil/trace.go:171","msg":"trace[124440509] transaction","detail":"{read_only:false; response_revision:2494; number_of_response:1; }","duration":"612.604498ms","start":"2026-07-30T23:08:16.579793Z","end":"2026-07-30T23:08:17.192398Z","steps":["trace[124440509] 'process raft request' (duration: 407.992008ms)","trace[124440509] 'compare' (duration: 203.308326ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:08:17.192475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:16.57977Z","time spent":"612.67929ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:08:17.238525Z","caller":"traceutil/trace.go:171","msg":"trace[2068275121] transaction","detail":"{read_only:false; response_revision:2495; number_of_response:1; }","duration":"607.768093ms","start":"2026-07-30T23:08:16.630729Z","end":"2026-07-30T23:08:17.238497Z","steps":["trace[2068275121] 'process raft request' (duration: 607.426274ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:17.238545Z","caller":"traceutil/trace.go:171","msg":"trace[1847774662] linearizableReadLoop","detail":"{readStateIndex:2594; appliedIndex:2589; }","duration":"571.12778ms","start":"2026-07-30T23:08:16.667384Z","end":"2026-07-30T23:08:17.238511Z","steps":["trace[1847774662] 'read index received' (duration: 320.444522ms)","trace[1847774662] 'applied index is now lower than readState.Index' (duration: 250.681808ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:08:17.238661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:16.630704Z","time spent":"607.886415ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:08:17.238722Z","caller":"traceutil/trace.go:171","msg":"trace[68817600] transaction","detail":"{read_only:false; response_revision:2496; number_of_response:1; }","duration":"579.669462ms","start":"2026-07-30T23:08:16.659039Z","end":"2026-07-30T23:08:17.238709Z","steps":["trace[68817600] 'process raft request' (duration: 579.335684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:17.238871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:16.659022Z","time spent":"579.730513ms","remote":"127.0.0.1:46206","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-07-30T23:08:17.238991Z","caller":"traceutil/trace.go:171","msg":"trace[2012109544] transaction","detail":"{read_only:false; response_revision:2498; number_of_response:1; }","duration":"579.455947ms","start":"2026-07-30T23:08:16.659506Z","end":"2026-07-30T23:08:17.238962Z","steps":["trace[2012109544] 'process raft request' (duration: 578.956604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:17.239193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:16.659496Z","time spent":"579.58215ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:08:17.239192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.775617ms","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-07-30T23:08:17.239248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.13913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-07-30T23:08:17.239299Z","caller":"traceutil/trace.go:171","msg":"trace[218744105] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2498; }","duration":"571.937811ms","start":"2026-07-30T23:08:16.667336Z","end":"2026-07-30T23:08:17.239274Z","steps":["trace[218744105] 'agreement among raft nodes before linearized reading' (duration: 571.482669ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:17.238968Z","caller":"traceutil/trace.go:171","msg":"trace[640498409] transaction","detail":"{read_only:false; response_revision:2497; number_of_response:1; }","duration":"579.667902ms","start":"2026-07-30T23:08:16.659261Z","end":"2026-07-30T23:08:17.238929Z","steps":["trace[640498409] 'process raft request' (duration: 579.158439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:17.239261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.667018ms","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-07-30T23:08:17.239387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:16.667322Z","time spent":"572.053774ms","remote":"127.0.0.1:40174","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-07-30T23:08:17.2394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.32935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-07-30T23:08:17.239413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:16.659228Z","time spent":"580.148415ms","remote":"127.0.0.1:46206","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-07-30T23:08:17.239411Z","caller":"traceutil/trace.go:171","msg":"trace[128455462] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2498; }","duration":"216.286904ms","start":"2026-07-30T23:08:17.023037Z","end":"2026-07-30T23:08:17.239324Z","steps":["trace[128455462] 'agreement among raft nodes before linearized reading' (duration: 216.031968ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:17.239419Z","caller":"traceutil/trace.go:171","msg":"trace[1877748762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2498; }","duration":"420.884123ms","start":"2026-07-30T23:08:16.818511Z","end":"2026-07-30T23:08:17.239395Z","steps":["trace[1877748762] 'agreement among raft nodes before linearized reading' (duration: 420.511203ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:17.23945Z","caller":"traceutil/trace.go:171","msg":"trace[1465865027] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2498; }","duration":"355.416232ms","start":"2026-07-30T23:08:16.884022Z","end":"2026-07-30T23:08:17.239438Z","steps":["trace[1465865027] 'agreement among raft nodes before linearized reading' (duration: 355.239108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:17.239472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:16.818486Z","time spent":"420.973386ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T23:08:17.239485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:16.884004Z","time spent":"355.473183ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-30T23:08:17.239544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.241368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-30T23:08:17.239602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.299118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:08:17.239632Z","caller":"traceutil/trace.go:171","msg":"trace[2077937857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2498; }","duration":"200.36704ms","start":"2026-07-30T23:08:17.039255Z","end":"2026-07-30T23:08:17.239622Z","steps":["trace[2077937857] 'agreement among raft nodes before linearized reading' (duration: 200.320979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:17.239633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.520412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-30T23:08:17.239641Z","caller":"traceutil/trace.go:171","msg":"trace[1903649515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2498; }","duration":"355.412492ms","start":"2026-07-30T23:08:16.884205Z","end":"2026-07-30T23:08:17.239617Z","steps":["trace[1903649515] 'agreement among raft nodes before linearized reading' (duration: 355.030632ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:17.239664Z","caller":"traceutil/trace.go:171","msg":"trace[17541791] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2498; }","duration":"138.575053ms","start":"2026-07-30T23:08:17.101079Z","end":"2026-07-30T23:08:17.239654Z","steps":["trace[17541791] 'agreement among raft nodes before linearized reading' (duration: 138.47422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:17.239693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:16.884161Z","time spent":"355.521845ms","remote":"127.0.0.1:46206","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-07-30T23:08:17.239741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.941662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-30T23:08:17.239755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.249807ms","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-07-30T23:08:17.239796Z","caller":"traceutil/trace.go:171","msg":"trace[1132759828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2498; }","duration":"355.318139ms","start":"2026-07-30T23:08:16.884467Z","end":"2026-07-30T23:08:17.239785Z","steps":["trace[1132759828] 'agreement among raft nodes before linearized reading' (duration: 355.197296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:17.239797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.36444ms","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-07-30T23:08:17.239793Z","caller":"traceutil/trace.go:171","msg":"trace[893438433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2498; }","duration":"357.040334ms","start":"2026-07-30T23:08:16.882738Z","end":"2026-07-30T23:08:17.239779Z","steps":["trace[893438433] 'agreement among raft nodes before linearized reading' (duration: 356.866049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:17.239828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:16.884446Z","time spent":"355.375261ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-30T23:08:17.239836Z","caller":"traceutil/trace.go:171","msg":"trace[163852374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2498; }","duration":"355.433962ms","start":"2026-07-30T23:08:16.884394Z","end":"2026-07-30T23:08:17.239828Z","steps":["trace[163852374] 'agreement among raft nodes before linearized reading' (duration: 355.34557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:17.239848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:16.882711Z","time spent":"357.129647ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T23:08:17.239865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:16.884384Z","time spent":"355.472683ms","remote":"127.0.0.1:46206","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-07-30T23:08:17.386488Z","caller":"traceutil/trace.go:171","msg":"trace[1653559599] transaction","detail":"{read_only:false; response_revision:2500; number_of_response:1; }","duration":"140.251787ms","start":"2026-07-30T23:08:17.246197Z","end":"2026-07-30T23:08:17.386449Z","steps":["trace[1653559599] 'process raft request' (duration: 61.815597ms)","trace[1653559599] 'compare' (duration: 78.211424ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:08:17.386629Z","caller":"traceutil/trace.go:171","msg":"trace[994188303] transaction","detail":"{read_only:false; response_revision:2501; number_of_response:1; }","duration":"140.324549ms","start":"2026-07-30T23:08:17.246287Z","end":"2026-07-30T23:08:17.386612Z","steps":["trace[994188303] 'process raft request' (duration: 140.096993ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:17.386838Z","caller":"traceutil/trace.go:171","msg":"trace[1670554819] transaction","detail":"{read_only:false; response_revision:2502; number_of_response:1; }","duration":"140.421812ms","start":"2026-07-30T23:08:17.246405Z","end":"2026-07-30T23:08:17.386827Z","steps":["trace[1670554819] 'process raft request' (duration: 140.156245ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:17.387113Z","caller":"traceutil/trace.go:171","msg":"trace[223428228] transaction","detail":"{read_only:false; response_revision:2503; number_of_response:1; }","duration":"140.662758ms","start":"2026-07-30T23:08:17.24644Z","end":"2026-07-30T23:08:17.387103Z","steps":["trace[223428228] 'process raft request' (duration: 140.34651ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:17.387465Z","caller":"traceutil/trace.go:171","msg":"trace[797054459] transaction","detail":"{read_only:false; response_revision:2504; number_of_response:1; }","duration":"140.679189ms","start":"2026-07-30T23:08:17.246756Z","end":"2026-07-30T23:08:17.387435Z","steps":["trace[797054459] 'process raft request' (duration: 140.308289ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:17.387737Z","caller":"traceutil/trace.go:171","msg":"trace[1964917596] transaction","detail":"{read_only:false; response_revision:2506; number_of_response:1; }","duration":"139.752535ms","start":"2026-07-30T23:08:17.247953Z","end":"2026-07-30T23:08:17.387705Z","steps":["trace[1964917596] 'process raft request' (duration: 139.604211ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:17.387823Z","caller":"traceutil/trace.go:171","msg":"trace[257953395] transaction","detail":"{read_only:false; response_revision:2505; number_of_response:1; }","duration":"140.665738ms","start":"2026-07-30T23:08:17.247139Z","end":"2026-07-30T23:08:17.387805Z","steps":["trace[257953395] 'process raft request' (duration: 140.142155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:19.891839Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874443288689483,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T23:08:20.086443Z","caller":"traceutil/trace.go:171","msg":"trace[1102413680] linearizableReadLoop","detail":"{readStateIndex:2607; appliedIndex:2606; }","duration":"695.174387ms","start":"2026-07-30T23:08:19.391233Z","end":"2026-07-30T23:08:20.086407Z","steps":["trace[1102413680] 'read index received' (duration: 695.040433ms)","trace[1102413680] 'applied index is now lower than readState.Index' (duration: 132.244µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:08:20.086583Z","caller":"traceutil/trace.go:171","msg":"trace[823528948] transaction","detail":"{read_only:false; response_revision:2511; number_of_response:1; }","duration":"764.624042ms","start":"2026-07-30T23:08:19.321911Z","end":"2026-07-30T23:08:20.086535Z","steps":["trace[823528948] 'process raft request' (duration: 764.250872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:20.086775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.475304ms","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-07-30T23:08:20.086792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:19.32189Z","time spent":"764.794897ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:08:20.086822Z","caller":"traceutil/trace.go:171","msg":"trace[1028599863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2511; }","duration":"695.641679ms","start":"2026-07-30T23:08:19.391168Z","end":"2026-07-30T23:08:20.086809Z","steps":["trace[1028599863] 'agreement among raft nodes before linearized reading' (duration: 695.402933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:20.08689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:19.39113Z","time spent":"695.748332ms","remote":"127.0.0.1:46206","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-07-30T23:08:20.791558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.915203ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:08:20.791746Z","caller":"traceutil/trace.go:171","msg":"trace[585026049] linearizableReadLoop","detail":"{readStateIndex:2608; appliedIndex:2607; }","duration":"705.189206ms","start":"2026-07-30T23:08:20.086535Z","end":"2026-07-30T23:08:20.791724Z","steps":["trace[585026049] 'read index received' (duration: 11.028426ms)","trace[585026049] 'applied index is now lower than readState.Index' (duration: 694.15982ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:08:20.791854Z","caller":"traceutil/trace.go:171","msg":"trace[880601138] transaction","detail":"{read_only:false; response_revision:2512; number_of_response:1; }","duration":"848.524163ms","start":"2026-07-30T23:08:19.943317Z","end":"2026-07-30T23:08:20.791841Z","steps":["trace[880601138] 'process raft request' (duration: 154.22965ms)","trace[880601138] 'compare' (duration: 693.771289ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:08:20.791927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:19.943284Z","time spent":"848.605925ms","remote":"127.0.0.1:46206","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-07-30T23:08:20.792075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40068169s","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-07-30T23:08:20.792112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400839605s","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-07-30T23:08:20.792139Z","caller":"traceutil/trace.go:171","msg":"trace[1832838910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2513; }","duration":"1.400778833s","start":"2026-07-30T23:08:19.391341Z","end":"2026-07-30T23:08:20.79212Z","steps":["trace[1832838910] 'agreement among raft nodes before linearized reading' (duration: 1.400578698s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:20.792188Z","caller":"traceutil/trace.go:171","msg":"trace[1271554903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2513; }","duration":"1.400963878s","start":"2026-07-30T23:08:19.391204Z","end":"2026-07-30T23:08:20.792168Z","steps":["trace[1271554903] 'agreement among raft nodes before linearized reading' (duration: 1.400742892s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:20.792173Z","caller":"traceutil/trace.go:171","msg":"trace[1478005027] transaction","detail":"{read_only:false; response_revision:2513; number_of_response:1; }","duration":"699.935029ms","start":"2026-07-30T23:08:20.092197Z","end":"2026-07-30T23:08:20.792132Z","steps":["trace[1478005027] 'process raft request' (duration: 699.484947ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:20.792198Z","caller":"traceutil/trace.go:171","msg":"trace[1570888177] transaction","detail":"{read_only:false; response_revision:2514; number_of_response:1; }","duration":"649.299123ms","start":"2026-07-30T23:08:20.142858Z","end":"2026-07-30T23:08:20.792157Z","steps":["trace[1570888177] 'process raft request' (duration: 649.129268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:20.792242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400411073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-30T23:08:20.792194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:19.391288Z","time spent":"1.400893895s","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T23:08:20.792335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.291478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"warn","ts":"2026-07-30T23:08:20.79232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.963541ms","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-07-30T23:08:20.79236Z","caller":"traceutil/trace.go:171","msg":"trace[1929318422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2513; }","duration":"1.400547576s","start":"2026-07-30T23:08:19.391787Z","end":"2026-07-30T23:08:20.792335Z","steps":["trace[1929318422] 'agreement among raft nodes before linearized reading' (duration: 1.400191377s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:20.792397Z","caller":"traceutil/trace.go:171","msg":"trace[748743939] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2514; }","duration":"170.414802ms","start":"2026-07-30T23:08:20.621965Z","end":"2026-07-30T23:08:20.792379Z","steps":["trace[748743939] 'agreement among raft nodes before linearized reading' (duration: 170.255327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:20.7924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:20.092135Z","time spent":"700.147205ms","remote":"127.0.0.1:46206","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-07-30T23:08:20.792401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:20.142812Z","time spent":"649.482637ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:08:20.792434Z","caller":"traceutil/trace.go:171","msg":"trace[291818615] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2514; }","duration":"467.136785ms","start":"2026-07-30T23:08:20.325271Z","end":"2026-07-30T23:08:20.792408Z","steps":["trace[291818615] 'agreement among raft nodes before linearized reading' (duration: 466.852838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:20.792244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:19.391175Z","time spent":"1.40106014s","remote":"127.0.0.1:46206","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-07-30T23:08:20.792112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384388807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c733f1745841b9\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-30T23:08:20.792689Z","caller":"traceutil/trace.go:171","msg":"trace[1470923060] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c733f1745841b9; range_end:; response_count:1; response_revision:2513; }","duration":"1.384988142s","start":"2026-07-30T23:08:19.407674Z","end":"2026-07-30T23:08:20.792662Z","steps":["trace[1470923060] 'agreement among raft nodes before linearized reading' (duration: 1.384278964s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:20.792798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:19.407658Z","time spent":"1.385121696s","remote":"127.0.0.1:46064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c733f1745841b9\" "} {"level":"warn","ts":"2026-07-30T23:08:20.792443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:19.391762Z","time spent":"1.40066971s","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T23:08:20.792351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39990017s","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-07-30T23:08:20.793037Z","caller":"traceutil/trace.go:171","msg":"trace[80384673] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2513; }","duration":"1.400620609s","start":"2026-07-30T23:08:19.392392Z","end":"2026-07-30T23:08:20.793013Z","steps":["trace[80384673] 'agreement among raft nodes before linearized reading' (duration: 1.399551471s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:20.793159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:19.392381Z","time spent":"1.400737702s","remote":"127.0.0.1:46206","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-07-30T23:08:20.7925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:20.325245Z","time spent":"467.244128ms","remote":"127.0.0.1:40174","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-07-30T23:09:51.604475Z","caller":"traceutil/trace.go:171","msg":"trace[241094636] transaction","detail":"{read_only:false; response_revision:3163; number_of_response:1; }","duration":"260.775367ms","start":"2026-07-30T23:09:51.343286Z","end":"2026-07-30T23:09:51.604062Z","steps":["trace[241094636] 'process raft request' (duration: 260.204101ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:09:51.631338Z","caller":"traceutil/trace.go:171","msg":"trace[515364101] transaction","detail":"{read_only:false; response_revision:3164; number_of_response:1; }","duration":"286.663073ms","start":"2026-07-30T23:09:51.344658Z","end":"2026-07-30T23:09:51.631321Z","steps":["trace[515364101] 'process raft request' (duration: 286.446257ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:09:51.631326Z","caller":"traceutil/trace.go:171","msg":"trace[824268430] linearizableReadLoop","detail":"{readStateIndex:3281; appliedIndex:3278; }","duration":"283.787915ms","start":"2026-07-30T23:09:51.347515Z","end":"2026-07-30T23:09:51.631303Z","steps":["trace[824268430] 'read index received' (duration: 255.830823ms)","trace[824268430] 'applied index is now lower than readState.Index' (duration: 27.953292ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:09:51.63151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.97271ms","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-07-30T23:09:51.63154Z","caller":"traceutil/trace.go:171","msg":"trace[986026071] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3165; }","duration":"284.028751ms","start":"2026-07-30T23:09:51.347502Z","end":"2026-07-30T23:09:51.631531Z","steps":["trace[986026071] 'agreement among raft nodes before linearized reading' (duration: 283.868067ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:09:51.631691Z","caller":"traceutil/trace.go:171","msg":"trace[951707306] transaction","detail":"{read_only:false; response_revision:3165; number_of_response:1; }","duration":"285.111321ms","start":"2026-07-30T23:09:51.346551Z","end":"2026-07-30T23:09:51.631662Z","steps":["trace[951707306] 'process raft request' (duration: 284.70916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:09:51.631915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.388586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-30T23:09:51.631972Z","caller":"traceutil/trace.go:171","msg":"trace[1921366076] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:3166; }","duration":"179.50518ms","start":"2026-07-30T23:09:51.452449Z","end":"2026-07-30T23:09:51.631954Z","steps":["trace[1921366076] 'agreement among raft nodes before linearized reading' (duration: 179.316305ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:10:23.958306Z","caller":"traceutil/trace.go:171","msg":"trace[568746619] transaction","detail":"{read_only:false; response_revision:3383; number_of_response:1; }","duration":"214.028148ms","start":"2026-07-30T23:10:23.744251Z","end":"2026-07-30T23:10:23.958279Z","steps":["trace[568746619] 'process raft request' (duration: 213.842033ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:10:23.978887Z","caller":"traceutil/trace.go:171","msg":"trace[1716082885] transaction","detail":"{read_only:false; response_revision:3384; number_of_response:1; }","duration":"228.094877ms","start":"2026-07-30T23:10:23.750775Z","end":"2026-07-30T23:10:23.97887Z","steps":["trace[1716082885] 'process raft request' (duration: 227.930542ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:10:23.979174Z","caller":"traceutil/trace.go:171","msg":"trace[2020305590] transaction","detail":"{read_only:false; response_revision:3385; number_of_response:1; }","duration":"221.264883ms","start":"2026-07-30T23:10:23.757875Z","end":"2026-07-30T23:10:23.97914Z","steps":["trace[2020305590] 'process raft request' (duration: 220.951155ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:10:23.979245Z","caller":"traceutil/trace.go:171","msg":"trace[379229166] linearizableReadLoop","detail":"{readStateIndex:3507; appliedIndex:3505; }","duration":"222.679001ms","start":"2026-07-30T23:10:23.756554Z","end":"2026-07-30T23:10:23.979233Z","steps":["trace[379229166] 'read index received' (duration: 201.610124ms)","trace[379229166] 'applied index is now lower than readState.Index' (duration: 21.067747ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:10:23.97944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.878776ms","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-07-30T23:10:23.97948Z","caller":"traceutil/trace.go:171","msg":"trace[1306744643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3386; }","duration":"222.984229ms","start":"2026-07-30T23:10:23.756486Z","end":"2026-07-30T23:10:23.97947Z","steps":["trace[1306744643] 'agreement among raft nodes before linearized reading' (duration: 222.831915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:10:23.979553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.353535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-30T23:10:23.979663Z","caller":"traceutil/trace.go:171","msg":"trace[987828868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3386; }","duration":"192.49345ms","start":"2026-07-30T23:10:23.787145Z","end":"2026-07-30T23:10:23.979638Z","steps":["trace[987828868] 'agreement among raft nodes before linearized reading' (duration: 192.244323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:10:23.979931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.653276ms","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-07-30T23:10:23.979984Z","caller":"traceutil/trace.go:171","msg":"trace[1463109649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3386; }","duration":"191.727129ms","start":"2026-07-30T23:10:23.788245Z","end":"2026-07-30T23:10:23.979972Z","steps":["trace[1463109649] 'agreement among raft nodes before linearized reading' (duration: 191.565664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:10:23.979709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.31821ms","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-07-30T23:10:23.979642Z","caller":"traceutil/trace.go:171","msg":"trace[666742493] transaction","detail":"{read_only:false; response_revision:3386; number_of_response:1; }","duration":"211.638624ms","start":"2026-07-30T23:10:23.767653Z","end":"2026-07-30T23:10:23.979292Z","steps":["trace[666742493] 'process raft request' (duration: 211.420638ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:10:23.980078Z","caller":"traceutil/trace.go:171","msg":"trace[127334761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3386; }","duration":"187.70412ms","start":"2026-07-30T23:10:23.79236Z","end":"2026-07-30T23:10:23.980064Z","steps":["trace[127334761] 'agreement among raft nodes before linearized reading' (duration: 187.266498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:10:24.20171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.272146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:10:24.201995Z","caller":"traceutil/trace.go:171","msg":"trace[746526752] transaction","detail":"{read_only:false; response_revision:3388; number_of_response:1; }","duration":"217.133932ms","start":"2026-07-30T23:10:23.984827Z","end":"2026-07-30T23:10:24.201961Z","steps":["trace[746526752] 'process raft request' (duration: 28.491317ms)","trace[746526752] 'compare' (duration: 187.966707ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:10:24.202024Z","caller":"traceutil/trace.go:171","msg":"trace[331012665] linearizableReadLoop","detail":"{readStateIndex:3513; appliedIndex:3510; }","duration":"162.542773ms","start":"2026-07-30T23:10:24.039456Z","end":"2026-07-30T23:10:24.201998Z","steps":["trace[331012665] 'read index received' (duration: 32.091µs)","trace[331012665] 'applied index is now lower than readState.Index' (duration: 162.509182ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:10:24.202125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.671127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:10:24.202094Z","caller":"traceutil/trace.go:171","msg":"trace[1448532637] transaction","detail":"{read_only:false; response_revision:3389; number_of_response:1; }","duration":"217.226135ms","start":"2026-07-30T23:10:23.984836Z","end":"2026-07-30T23:10:24.202062Z","steps":["trace[1448532637] 'process raft request' (duration: 217.002389ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:10:24.202161Z","caller":"traceutil/trace.go:171","msg":"trace[839736091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3390; }","duration":"162.746979ms","start":"2026-07-30T23:10:24.039396Z","end":"2026-07-30T23:10:24.202143Z","steps":["trace[839736091] 'agreement among raft nodes before linearized reading' (duration: 162.682177ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:10:24.202162Z","caller":"traceutil/trace.go:171","msg":"trace[1787815900] transaction","detail":"{read_only:false; response_revision:3390; number_of_response:1; }","duration":"217.229275ms","start":"2026-07-30T23:10:23.98491Z","end":"2026-07-30T23:10:24.20214Z","steps":["trace[1787815900] 'process raft request' (duration: 217.020089ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:14:23.374236Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2938} {"level":"info","ts":"2026-07-30T23:14:23.466019Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2938,"took":"89.760058ms","hash":4015869361,"current-db-size-bytes":35340288,"current-db-size":"35 MB","current-db-size-in-use-bytes":10756096,"current-db-size-in-use":"11 MB"} {"level":"info","ts":"2026-07-30T23:14:23.466095Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4015869361,"revision":2938,"compact-revision":-1} {"level":"info","ts":"2026-07-30T23:18:55.752615Z","caller":"traceutil/trace.go:171","msg":"trace[2052573051] transaction","detail":"{read_only:false; response_revision:6818; number_of_response:1; }","duration":"146.37634ms","start":"2026-07-30T23:18:55.606201Z","end":"2026-07-30T23:18:55.752578Z","steps":["trace[2052573051] 'process raft request' (duration: 146.122074ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:18:56.13985Z","caller":"traceutil/trace.go:171","msg":"trace[984904609] transaction","detail":"{read_only:false; response_revision:6820; number_of_response:1; }","duration":"119.30421ms","start":"2026-07-30T23:18:56.020511Z","end":"2026-07-30T23:18:56.139815Z","steps":["trace[984904609] 'process raft request' (duration: 43.566716ms)","trace[984904609] 'compare' (duration: 75.492998ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:18:56.14006Z","caller":"traceutil/trace.go:171","msg":"trace[40894100] linearizableReadLoop","detail":"{readStateIndex:7054; appliedIndex:7052; }","duration":"100.256566ms","start":"2026-07-30T23:18:56.039762Z","end":"2026-07-30T23:18:56.140018Z","steps":["trace[40894100] 'read index received' (duration: 24.231244ms)","trace[40894100] 'applied index is now lower than readState.Index' (duration: 76.023452ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:18:56.140156Z","caller":"traceutil/trace.go:171","msg":"trace[1352542406] transaction","detail":"{read_only:false; response_revision:6821; number_of_response:1; }","duration":"119.456144ms","start":"2026-07-30T23:18:56.02065Z","end":"2026-07-30T23:18:56.140106Z","steps":["trace[1352542406] 'process raft request' (duration: 119.098774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:18:56.140241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.466472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:18:56.140306Z","caller":"traceutil/trace.go:171","msg":"trace[489062696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6821; }","duration":"100.605805ms","start":"2026-07-30T23:18:56.039686Z","end":"2026-07-30T23:18:56.140292Z","steps":["trace[489062696] 'agreement among raft nodes before linearized reading' (duration: 100.490532ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:18:56.30979Z","caller":"traceutil/trace.go:171","msg":"trace[291378419] linearizableReadLoop","detail":"{readStateIndex:7055; appliedIndex:7054; }","duration":"168.243591ms","start":"2026-07-30T23:18:56.141515Z","end":"2026-07-30T23:18:56.309759Z","steps":["trace[291378419] 'read index received' (duration: 114.438549ms)","trace[291378419] 'applied index is now lower than readState.Index' (duration: 53.799322ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:18:56.309862Z","caller":"traceutil/trace.go:171","msg":"trace[88969356] transaction","detail":"{read_only:false; response_revision:6822; number_of_response:1; }","duration":"186.01775ms","start":"2026-07-30T23:18:56.123815Z","end":"2026-07-30T23:18:56.309833Z","steps":["trace[88969356] 'process raft request' (duration: 132.051733ms)","trace[88969356] 'compare' (duration: 53.749281ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:18:56.309978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.438096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:18:56.310065Z","caller":"traceutil/trace.go:171","msg":"trace[696423573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6822; }","duration":"168.57197ms","start":"2026-07-30T23:18:56.141475Z","end":"2026-07-30T23:18:56.310047Z","steps":["trace[696423573] 'agreement among raft nodes before linearized reading' (duration: 168.415146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:18:56.310161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.652909ms","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-07-30T23:18:56.310235Z","caller":"traceutil/trace.go:171","msg":"trace[885058720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6822; }","duration":"106.796682ms","start":"2026-07-30T23:18:56.20342Z","end":"2026-07-30T23:18:56.310217Z","steps":["trace[885058720] 'agreement among raft nodes before linearized reading' (duration: 106.534255ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:19:23.380877Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4922} {"level":"info","ts":"2026-07-30T23:19:23.426426Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4922,"took":"43.937246ms","hash":2005397921,"current-db-size-bytes":35340288,"current-db-size":"35 MB","current-db-size-in-use-bytes":10211328,"current-db-size-in-use":"10 MB"} {"level":"info","ts":"2026-07-30T23:19:23.426485Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2005397921,"revision":4922,"compact-revision":2938} {"level":"info","ts":"2026-07-30T23:22:35.905538Z","caller":"traceutil/trace.go:171","msg":"trace[425810344] linearizableReadLoop","detail":"{readStateIndex:8969; appliedIndex:8968; }","duration":"171.552142ms","start":"2026-07-30T23:22:35.733957Z","end":"2026-07-30T23:22:35.905509Z","steps":["trace[425810344] 'read index received' (duration: 171.296665ms)","trace[425810344] 'applied index is now lower than readState.Index' (duration: 254.227µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:22:35.905729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.748547ms","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-07-30T23:22:35.905705Z","caller":"traceutil/trace.go:171","msg":"trace[990991595] transaction","detail":"{read_only:false; response_revision:8684; number_of_response:1; }","duration":"294.464861ms","start":"2026-07-30T23:22:35.611184Z","end":"2026-07-30T23:22:35.905649Z","steps":["trace[990991595] 'process raft request' (duration: 294.141572ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:22:35.905786Z","caller":"traceutil/trace.go:171","msg":"trace[1012013356] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8684; }","duration":"171.834699ms","start":"2026-07-30T23:22:35.733935Z","end":"2026-07-30T23:22:35.90577Z","steps":["trace[1012013356] 'agreement among raft nodes before linearized reading' (duration: 171.719256ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:22:36.117408Z","caller":"traceutil/trace.go:171","msg":"trace[1895826800] transaction","detail":"{read_only:false; response_revision:8685; number_of_response:1; }","duration":"197.232426ms","start":"2026-07-30T23:22:35.920136Z","end":"2026-07-30T23:22:36.117368Z","steps":["trace[1895826800] 'process raft request' (duration: 196.619279ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:22:47.543297Z","caller":"traceutil/trace.go:171","msg":"trace[675822648] transaction","detail":"{read_only:false; response_revision:8759; number_of_response:1; }","duration":"299.296332ms","start":"2026-07-30T23:22:47.243968Z","end":"2026-07-30T23:22:47.543264Z","steps":["trace[675822648] 'process raft request' (duration: 299.025015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:22:48.758049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.112613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-07-30T23:22:48.758153Z","caller":"traceutil/trace.go:171","msg":"trace[1074117590] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:8766; }","duration":"200.281258ms","start":"2026-07-30T23:22:48.557848Z","end":"2026-07-30T23:22:48.758129Z","steps":["trace[1074117590] 'range keys from in-memory index tree' (duration: 199.900928ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:22:49.65418Z","caller":"traceutil/trace.go:171","msg":"trace[2096028826] linearizableReadLoop","detail":"{readStateIndex:9060; appliedIndex:9059; }","duration":"146.608139ms","start":"2026-07-30T23:22:49.507545Z","end":"2026-07-30T23:22:49.654153Z","steps":["trace[2096028826] 'read index received' (duration: 146.493006ms)","trace[2096028826] 'applied index is now lower than readState.Index' (duration: 113.573µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:22:49.654504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.921657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-07-30T23:22:49.654576Z","caller":"traceutil/trace.go:171","msg":"trace[1809166763] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:8772; }","duration":"147.045481ms","start":"2026-07-30T23:22:49.507509Z","end":"2026-07-30T23:22:49.654555Z","steps":["trace[1809166763] 'agreement among raft nodes before linearized reading' (duration: 146.759613ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:22:49.654555Z","caller":"traceutil/trace.go:171","msg":"trace[482983175] transaction","detail":"{read_only:false; response_revision:8772; number_of_response:1; }","duration":"246.961638ms","start":"2026-07-30T23:22:49.40754Z","end":"2026-07-30T23:22:49.654501Z","steps":["trace[482983175] 'process raft request' (duration: 246.419644ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:22:49.705838Z","caller":"traceutil/trace.go:171","msg":"trace[519987918] transaction","detail":"{read_only:false; response_revision:8773; number_of_response:1; }","duration":"155.54749ms","start":"2026-07-30T23:22:49.550273Z","end":"2026-07-30T23:22:49.705821Z","steps":["trace[519987918] 'process raft request' (duration: 155.441397ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:22:50.206718Z","caller":"traceutil/trace.go:171","msg":"trace[880635855] linearizableReadLoop","detail":"{readStateIndex:9063; appliedIndex:9062; }","duration":"167.380849ms","start":"2026-07-30T23:22:50.039301Z","end":"2026-07-30T23:22:50.206681Z","steps":["trace[880635855] 'read index received' (duration: 167.106512ms)","trace[880635855] 'applied index is now lower than readState.Index' (duration: 273.257µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:22:50.206775Z","caller":"traceutil/trace.go:171","msg":"trace[290115710] transaction","detail":"{read_only:false; response_revision:8775; number_of_response:1; }","duration":"225.141788ms","start":"2026-07-30T23:22:49.981594Z","end":"2026-07-30T23:22:50.206736Z","steps":["trace[290115710] 'process raft request' (duration: 224.886462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:22:50.207107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.612285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:22:50.207209Z","caller":"traceutil/trace.go:171","msg":"trace[1462948340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8775; }","duration":"167.959045ms","start":"2026-07-30T23:22:50.039227Z","end":"2026-07-30T23:22:50.207186Z","steps":["trace[1462948340] 'agreement among raft nodes before linearized reading' (duration: 167.591535ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:13.946103Z","caller":"traceutil/trace.go:171","msg":"trace[1275079380] transaction","detail":"{read_only:false; response_revision:8982; number_of_response:1; }","duration":"132.008774ms","start":"2026-07-30T23:23:13.814045Z","end":"2026-07-30T23:23:13.946054Z","steps":["trace[1275079380] 'process raft request' (duration: 131.749057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:17.54832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.071561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:23:17.548408Z","caller":"traceutil/trace.go:171","msg":"trace[1255617334] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:9009; }","duration":"154.208744ms","start":"2026-07-30T23:23:17.394182Z","end":"2026-07-30T23:23:17.54839Z","steps":["trace[1255617334] 'count revisions from in-memory index tree' (duration: 153.981728ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:18.064228Z","caller":"traceutil/trace.go:171","msg":"trace[164477679] linearizableReadLoop","detail":"{readStateIndex:9307; appliedIndex:9306; }","duration":"137.329948ms","start":"2026-07-30T23:23:17.92687Z","end":"2026-07-30T23:23:18.0642Z","steps":["trace[164477679] 'read index received' (duration: 137.113392ms)","trace[164477679] 'applied index is now lower than readState.Index' (duration: 215.176µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:23:18.064472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.591146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-30T23:23:18.064561Z","caller":"traceutil/trace.go:171","msg":"trace[1675897157] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:9010; }","duration":"137.74688ms","start":"2026-07-30T23:23:17.926799Z","end":"2026-07-30T23:23:18.064546Z","steps":["trace[1675897157] 'agreement among raft nodes before linearized reading' (duration: 137.509453ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:18.064897Z","caller":"traceutil/trace.go:171","msg":"trace[1570719750] transaction","detail":"{read_only:false; response_revision:9010; number_of_response:1; }","duration":"244.530553ms","start":"2026-07-30T23:23:17.819752Z","end":"2026-07-30T23:23:18.064283Z","steps":["trace[1570719750] 'process raft request' (duration: 244.218455ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:18.205212Z","caller":"traceutil/trace.go:171","msg":"trace[1310876359] transaction","detail":"{read_only:false; response_revision:9012; number_of_response:1; }","duration":"136.047664ms","start":"2026-07-30T23:23:18.069132Z","end":"2026-07-30T23:23:18.205179Z","steps":["trace[1310876359] 'process raft request' (duration: 136.001753ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:18.205239Z","caller":"traceutil/trace.go:171","msg":"trace[2112030281] transaction","detail":"{read_only:false; response_revision:9011; number_of_response:1; }","duration":"204.192103ms","start":"2026-07-30T23:23:18.000999Z","end":"2026-07-30T23:23:18.205191Z","steps":["trace[2112030281] 'process raft request' (duration: 202.743444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:18.205315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.45771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:23:18.205211Z","caller":"traceutil/trace.go:171","msg":"trace[379737273] linearizableReadLoop","detail":"{readStateIndex:9308; appliedIndex:9307; }","duration":"140.819283ms","start":"2026-07-30T23:23:18.064352Z","end":"2026-07-30T23:23:18.205171Z","steps":["trace[379737273] 'read index received' (duration: 139.416165ms)","trace[379737273] 'applied index is now lower than readState.Index' (duration: 1.399868ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:23:18.205367Z","caller":"traceutil/trace.go:171","msg":"trace[999377187] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:9012; }","duration":"151.577403ms","start":"2026-07-30T23:23:18.053777Z","end":"2026-07-30T23:23:18.205354Z","steps":["trace[999377187] 'agreement among raft nodes before linearized reading' (duration: 151.46904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:18.205431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.76986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:23:18.205504Z","caller":"traceutil/trace.go:171","msg":"trace[460251880] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:9012; }","duration":"154.945334ms","start":"2026-07-30T23:23:18.050543Z","end":"2026-07-30T23:23:18.205488Z","steps":["trace[460251880] 'agreement among raft nodes before linearized reading' (duration: 154.747639ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:30.875076Z","caller":"traceutil/trace.go:171","msg":"trace[2042474388] linearizableReadLoop","detail":"{readStateIndex:9406; appliedIndex:9405; }","duration":"239.991011ms","start":"2026-07-30T23:23:30.635061Z","end":"2026-07-30T23:23:30.875052Z","steps":["trace[2042474388] 'read index received' (duration: 239.730013ms)","trace[2042474388] 'applied index is now lower than readState.Index' (duration: 259.327µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:23:30.875136Z","caller":"traceutil/trace.go:171","msg":"trace[988680789] transaction","detail":"{read_only:false; response_revision:9107; number_of_response:1; }","duration":"241.06402ms","start":"2026-07-30T23:23:30.634033Z","end":"2026-07-30T23:23:30.875097Z","steps":["trace[988680789] 'process raft request' (duration: 240.776922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:30.875336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.156275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-30T23:23:30.875385Z","caller":"traceutil/trace.go:171","msg":"trace[1929610678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9107; }","duration":"240.265078ms","start":"2026-07-30T23:23:30.635108Z","end":"2026-07-30T23:23:30.875373Z","steps":["trace[1929610678] 'agreement among raft nodes before linearized reading' (duration: 240.100034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:30.87537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.278948ms","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-07-30T23:23:30.875469Z","caller":"traceutil/trace.go:171","msg":"trace[139233361] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9107; }","duration":"240.411882ms","start":"2026-07-30T23:23:30.635035Z","end":"2026-07-30T23:23:30.875446Z","steps":["trace[139233361] 'agreement among raft nodes before linearized reading' (duration: 240.152265ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:31.268345Z","caller":"traceutil/trace.go:171","msg":"trace[355797241] transaction","detail":"{read_only:false; response_revision:9108; number_of_response:1; }","duration":"386.677791ms","start":"2026-07-30T23:23:30.881643Z","end":"2026-07-30T23:23:31.268321Z","steps":["trace[355797241] 'process raft request' (duration: 310.317149ms)","trace[355797241] 'compare' (duration: 76.212338ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:23:31.268499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:23:30.881621Z","time spent":"386.794165ms","remote":"127.0.0.1:46206","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-07-30T23:23:31.523231Z","caller":"traceutil/trace.go:171","msg":"trace[274794496] transaction","detail":"{read_only:false; response_revision:9109; number_of_response:1; }","duration":"639.5596ms","start":"2026-07-30T23:23:30.883615Z","end":"2026-07-30T23:23:31.523174Z","steps":["trace[274794496] 'process raft request' (duration: 639.210221ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:31.523321Z","caller":"traceutil/trace.go:171","msg":"trace[1845538166] transaction","detail":"{read_only:false; response_revision:9110; number_of_response:1; }","duration":"594.669119ms","start":"2026-07-30T23:23:30.928595Z","end":"2026-07-30T23:23:31.523264Z","steps":["trace[1845538166] 'process raft request' (duration: 594.480324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:31.523452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:23:30.883586Z","time spent":"639.752346ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:23:31.523482Z","caller":"traceutil/trace.go:171","msg":"trace[1237148625] linearizableReadLoop","detail":"{readStateIndex:9408; appliedIndex:9406; }","duration":"595.588094ms","start":"2026-07-30T23:23:30.927854Z","end":"2026-07-30T23:23:31.523442Z","steps":["trace[1237148625] 'read index received' (duration: 264.02328ms)","trace[1237148625] 'applied index is now lower than readState.Index' (duration: 331.561533ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:23:31.523558Z","caller":"traceutil/trace.go:171","msg":"trace[598480392] transaction","detail":"{read_only:false; response_revision:9111; number_of_response:1; }","duration":"581.815052ms","start":"2026-07-30T23:23:30.941708Z","end":"2026-07-30T23:23:31.523523Z","steps":["trace[598480392] 'process raft request' (duration: 581.432821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:31.523561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:23:30.928584Z","time spent":"594.863264ms","remote":"127.0.0.1:46064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:23:31.523739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:23:30.941679Z","time spent":"581.959565ms","remote":"127.0.0.1:46192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:23:31.523919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.057286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-w4kl5\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-07-30T23:23:31.524009Z","caller":"traceutil/trace.go:171","msg":"trace[2039349014] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-w4kl5; range_end:; response_count:1; response_revision:9111; }","duration":"596.199769ms","start":"2026-07-30T23:23:30.927795Z","end":"2026-07-30T23:23:31.523995Z","steps":["trace[2039349014] 'agreement among raft nodes before linearized reading' (duration: 595.905662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:31.524047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:23:30.927771Z","time spent":"596.268272ms","remote":"127.0.0.1:46156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4673,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-w4kl5\" "} {"level":"info","ts":"2026-07-30T23:23:31.547101Z","caller":"traceutil/trace.go:171","msg":"trace[1141305317] transaction","detail":"{read_only:false; response_revision:9112; number_of_response:1; }","duration":"348.83975ms","start":"2026-07-30T23:23:31.19823Z","end":"2026-07-30T23:23:31.54707Z","steps":["trace[1141305317] 'process raft request' (duration: 348.458339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:31.547308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:23:31.198208Z","time spent":"348.986734ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:23:31.54742Z","caller":"traceutil/trace.go:171","msg":"trace[2116619660] transaction","detail":"{read_only:false; response_revision:9113; number_of_response:1; }","duration":"336.493217ms","start":"2026-07-30T23:23:31.21086Z","end":"2026-07-30T23:23:31.547354Z","steps":["trace[2116619660] 'process raft request' (duration: 336.143758ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:31.547537Z","caller":"traceutil/trace.go:171","msg":"trace[1350157036] transaction","detail":"{read_only:false; response_revision:9114; number_of_response:1; }","duration":"318.040859ms","start":"2026-07-30T23:23:31.229479Z","end":"2026-07-30T23:23:31.54752Z","steps":["trace[1350157036] 'process raft request' (duration: 317.755051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:31.547616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:23:31.229463Z","time spent":"318.11355ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:23:31.547597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.499171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:23:31.547657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.288409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-30T23:23:31.547687Z","caller":"traceutil/trace.go:171","msg":"trace[1898557074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9114; }","duration":"508.643435ms","start":"2026-07-30T23:23:31.039019Z","end":"2026-07-30T23:23:31.547662Z","steps":["trace[1898557074] 'agreement among raft nodes before linearized reading' (duration: 508.501732ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:31.547712Z","caller":"traceutil/trace.go:171","msg":"trace[1271501483] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:9114; }","duration":"240.398032ms","start":"2026-07-30T23:23:31.307302Z","end":"2026-07-30T23:23:31.5477Z","steps":["trace[1271501483] 'agreement among raft nodes before linearized reading' (duration: 240.242007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:31.547732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:23:31.039003Z","time spent":"508.719457ms","remote":"127.0.0.1:45966","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-07-30T23:23:31.547731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:23:31.210829Z","time spent":"336.733443ms","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:23:31.547778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.364172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107259"} {"level":"info","ts":"2026-07-30T23:23:31.547847Z","caller":"traceutil/trace.go:171","msg":"trace[1701782840] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:9114; }","duration":"470.516616ms","start":"2026-07-30T23:23:31.077312Z","end":"2026-07-30T23:23:31.547828Z","steps":["trace[1701782840] 'agreement among raft nodes before linearized reading' (duration: 470.238998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:31.547906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:23:31.077281Z","time spent":"470.616109ms","remote":"127.0.0.1:46080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":2,"response size":107282,"request content":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" "} {"level":"warn","ts":"2026-07-30T23:23:33.085485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.320717ms","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-07-30T23:23:33.08551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.070667ms","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-07-30T23:23:33.085601Z","caller":"traceutil/trace.go:171","msg":"trace[1828537495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9120; }","duration":"608.452911ms","start":"2026-07-30T23:23:32.477127Z","end":"2026-07-30T23:23:33.08558Z","steps":["trace[1828537495] 'range keys from in-memory index tree' (duration: 608.086051ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:33.085633Z","caller":"traceutil/trace.go:171","msg":"trace[2121477685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9120; }","duration":"205.272223ms","start":"2026-07-30T23:23:32.880328Z","end":"2026-07-30T23:23:33.0856Z","steps":["trace[2121477685] 'range keys from in-memory index tree' (duration: 204.767349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:33.085649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:23:32.477024Z","time spent":"608.614515ms","remote":"127.0.0.1:46206","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-07-30T23:23:33.085875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.880972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-30T23:23:33.08592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.385318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363678"} {"level":"info","ts":"2026-07-30T23:23:33.085987Z","caller":"traceutil/trace.go:171","msg":"trace[1558823310] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:9120; }","duration":"618.44983ms","start":"2026-07-30T23:23:32.467516Z","end":"2026-07-30T23:23:33.085966Z","steps":["trace[1558823310] 'range keys from in-memory index tree' (duration: 618.069ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:33.085982Z","caller":"traceutil/trace.go:171","msg":"trace[1699492327] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9120; }","duration":"609.045017ms","start":"2026-07-30T23:23:32.476909Z","end":"2026-07-30T23:23:33.085954Z","steps":["trace[1699492327] 'range keys from in-memory index tree' (duration: 608.676066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:33.086022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:23:32.467495Z","time spent":"618.518552ms","remote":"127.0.0.1:46080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":6,"response size":363701,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-07-30T23:23:33.086033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:23:32.476896Z","time spent":"609.127529ms","remote":"127.0.0.1:46206","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-07-30T23:23:33.086136Z","caller":"traceutil/trace.go:171","msg":"trace[1963632692] linearizableReadLoop","detail":"{readStateIndex:9421; appliedIndex:9420; }","duration":"146.899936ms","start":"2026-07-30T23:23:32.939219Z","end":"2026-07-30T23:23:33.086119Z","steps":["trace[1963632692] 'read index received' (duration: 113.479644ms)","trace[1963632692] 'applied index is now lower than readState.Index' (duration: 33.419272ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:23:33.086279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.050271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T23:23:33.086313Z","caller":"traceutil/trace.go:171","msg":"trace[797837677] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:9121; }","duration":"147.093642ms","start":"2026-07-30T23:23:32.939209Z","end":"2026-07-30T23:23:33.086302Z","steps":["trace[797837677] 'agreement among raft nodes before linearized reading' (duration: 147.01755ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:33.086234Z","caller":"traceutil/trace.go:171","msg":"trace[359930512] transaction","detail":"{read_only:false; response_revision:9121; number_of_response:1; }","duration":"203.746862ms","start":"2026-07-30T23:23:32.882429Z","end":"2026-07-30T23:23:33.086176Z","steps":["trace[359930512] 'process raft request' (duration: 170.261647ms)","trace[359930512] 'compare' (duration: 33.29143ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:23:34.048739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.779167ms","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-07-30T23:23:34.048841Z","caller":"traceutil/trace.go:171","msg":"trace[1195757450] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9135; }","duration":"138.963982ms","start":"2026-07-30T23:23:33.909855Z","end":"2026-07-30T23:23:34.048819Z","steps":["trace[1195757450] 'count revisions from in-memory index tree' (duration: 138.646994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:34.049177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.546635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:23 size:378668"} {"level":"info","ts":"2026-07-30T23:23:34.049239Z","caller":"traceutil/trace.go:171","msg":"trace[188574845] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:23; response_revision:9135; }","duration":"110.648377ms","start":"2026-07-30T23:23:33.938574Z","end":"2026-07-30T23:23:34.049222Z","steps":["trace[188574845] 'range keys from in-memory index tree' (duration: 109.982979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:34.049503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.630635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12463"} {"level":"info","ts":"2026-07-30T23:23:34.049626Z","caller":"traceutil/trace.go:171","msg":"trace[1706720983] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9135; }","duration":"141.797619ms","start":"2026-07-30T23:23:33.907801Z","end":"2026-07-30T23:23:34.049598Z","steps":["trace[1706720983] 'range keys from in-memory index tree' (duration: 140.762952ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:34.998836Z","caller":"traceutil/trace.go:171","msg":"trace[1427521643] linearizableReadLoop","detail":"{readStateIndex:9438; appliedIndex:9437; }","duration":"218.258894ms","start":"2026-07-30T23:23:34.780553Z","end":"2026-07-30T23:23:34.998811Z","steps":["trace[1427521643] 'read index received' (duration: 218.10981ms)","trace[1427521643] 'applied index is now lower than readState.Index' (duration: 147.714µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:23:34.998995Z","caller":"traceutil/trace.go:171","msg":"trace[1484996315] transaction","detail":"{read_only:false; response_revision:9138; number_of_response:1; }","duration":"248.493101ms","start":"2026-07-30T23:23:34.75049Z","end":"2026-07-30T23:23:34.998983Z","steps":["trace[1484996315] 'process raft request' (duration: 248.12086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:34.999401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.821739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/rook-ceph/\" range_end:\"/registry/secrets/rook-ceph0\" ","response":"range_response_count:1 size:307175"} {"level":"info","ts":"2026-07-30T23:23:34.999511Z","caller":"traceutil/trace.go:171","msg":"trace[1321585186] range","detail":"{range_begin:/registry/secrets/rook-ceph/; range_end:/registry/secrets/rook-ceph0; response_count:1; response_revision:9138; }","duration":"218.955422ms","start":"2026-07-30T23:23:34.780533Z","end":"2026-07-30T23:23:34.999489Z","steps":["trace[1321585186] 'agreement among raft nodes before linearized reading' (duration: 218.536491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:35.00848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.968725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-07-30T23:23:35.00858Z","caller":"traceutil/trace.go:171","msg":"trace[666618485] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:9139; }","duration":"195.14623ms","start":"2026-07-30T23:23:34.81341Z","end":"2026-07-30T23:23:35.008557Z","steps":["trace[666618485] 'agreement among raft nodes before linearized reading' (duration: 194.839192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:35.008656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.577553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T23:23:35.008701Z","caller":"traceutil/trace.go:171","msg":"trace[1109846392] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:9139; }","duration":"161.653326ms","start":"2026-07-30T23:23:34.847037Z","end":"2026-07-30T23:23:35.00869Z","steps":["trace[1109846392] 'agreement among raft nodes before linearized reading' (duration: 161.533882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:35.257592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.43201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:23:35.257719Z","caller":"traceutil/trace.go:171","msg":"trace[1198225464] linearizableReadLoop","detail":"{readStateIndex:9440; appliedIndex:9439; }","duration":"138.587663ms","start":"2026-07-30T23:23:35.119111Z","end":"2026-07-30T23:23:35.257699Z","steps":["trace[1198225464] 'read index received' (duration: 76.322µs)","trace[1198225464] 'applied index is now lower than readState.Index' (duration: 138.50989ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:23:35.257898Z","caller":"traceutil/trace.go:171","msg":"trace[937448815] transaction","detail":"{read_only:false; response_revision:9140; number_of_response:1; }","duration":"244.866583ms","start":"2026-07-30T23:23:35.012984Z","end":"2026-07-30T23:23:35.257851Z","steps":["trace[937448815] 'process raft request' (duration: 90.049042ms)","trace[937448815] 'compare' (duration: 154.006439ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:23:35.258055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.929722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-30T23:23:35.25815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.876983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-07-30T23:23:35.25855Z","caller":"traceutil/trace.go:171","msg":"trace[1343852872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9140; }","duration":"139.464996ms","start":"2026-07-30T23:23:35.119037Z","end":"2026-07-30T23:23:35.258502Z","steps":["trace[1343852872] 'agreement among raft nodes before linearized reading' (duration: 138.790998ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:35.25886Z","caller":"traceutil/trace.go:171","msg":"trace[585375613] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:9140; }","duration":"118.408748ms","start":"2026-07-30T23:23:35.140214Z","end":"2026-07-30T23:23:35.258623Z","steps":["trace[585375613] 'agreement among raft nodes before linearized reading' (duration: 117.76031ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:35.394397Z","caller":"traceutil/trace.go:171","msg":"trace[1024058523] linearizableReadLoop","detail":"{readStateIndex:9443; appliedIndex:9440; }","duration":"105.18067ms","start":"2026-07-30T23:23:35.289185Z","end":"2026-07-30T23:23:35.394366Z","steps":["trace[1024058523] 'read index received' (duration: 17.143993ms)","trace[1024058523] 'applied index is now lower than readState.Index' (duration: 88.034967ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:23:35.394492Z","caller":"traceutil/trace.go:171","msg":"trace[1967401288] transaction","detail":"{read_only:false; response_revision:9142; number_of_response:1; }","duration":"130.932826ms","start":"2026-07-30T23:23:35.263503Z","end":"2026-07-30T23:23:35.394436Z","steps":["trace[1967401288] 'process raft request' (duration: 94.764789ms)","trace[1967401288] 'compare' (duration: 35.824248ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:23:35.394968Z","caller":"traceutil/trace.go:171","msg":"trace[1890152683] transaction","detail":"{read_only:false; response_revision:9143; number_of_response:1; }","duration":"131.11192ms","start":"2026-07-30T23:23:35.263787Z","end":"2026-07-30T23:23:35.394899Z","steps":["trace[1890152683] 'process raft request' (duration: 130.520764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:35.395135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.91698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-30T23:23:35.395189Z","caller":"traceutil/trace.go:171","msg":"trace[1544241520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9143; }","duration":"106.028713ms","start":"2026-07-30T23:23:35.289147Z","end":"2026-07-30T23:23:35.395176Z","steps":["trace[1544241520] 'agreement among raft nodes before linearized reading' (duration: 105.342524ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:43.59239Z","caller":"traceutil/trace.go:171","msg":"trace[686196416] linearizableReadLoop","detail":"{readStateIndex:9519; appliedIndex:9518; }","duration":"182.176969ms","start":"2026-07-30T23:23:43.410182Z","end":"2026-07-30T23:23:43.592359Z","steps":["trace[686196416] 'read index received' (duration: 181.85285ms)","trace[686196416] 'applied index is now lower than readState.Index' (duration: 322.919µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:23:43.592737Z","caller":"traceutil/trace.go:171","msg":"trace[1177386128] transaction","detail":"{read_only:false; response_revision:9216; number_of_response:1; }","duration":"250.640378ms","start":"2026-07-30T23:23:43.342068Z","end":"2026-07-30T23:23:43.592708Z","steps":["trace[1177386128] 'process raft request' (duration: 250.081773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:43.59287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.860244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver\" ","response":"range_response_count:1 size:724"} {"level":"warn","ts":"2026-07-30T23:23:43.592843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.647712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ingress-nginx\" ","response":"range_response_count:1 size:1075"} {"level":"info","ts":"2026-07-30T23:23:43.592925Z","caller":"traceutil/trace.go:171","msg":"trace[225935331] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver; range_end:; response_count:1; response_revision:9216; }","duration":"180.954787ms","start":"2026-07-30T23:23:43.411957Z","end":"2026-07-30T23:23:43.592911Z","steps":["trace[225935331] 'agreement among raft nodes before linearized reading' (duration: 180.820733ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:43.592957Z","caller":"traceutil/trace.go:171","msg":"trace[173267958] range","detail":"{range_begin:/registry/clusterrolebindings/ingress-nginx; range_end:; response_count:1; response_revision:9216; }","duration":"182.807576ms","start":"2026-07-30T23:23:43.410123Z","end":"2026-07-30T23:23:43.592931Z","steps":["trace[173267958] 'agreement among raft nodes before linearized reading' (duration: 182.57853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:43.593017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.829622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-30T23:23:43.593132Z","caller":"traceutil/trace.go:171","msg":"trace[479786150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9216; }","duration":"148.010587ms","start":"2026-07-30T23:23:43.445088Z","end":"2026-07-30T23:23:43.593099Z","steps":["trace[479786150] 'agreement among raft nodes before linearized reading' (duration: 147.708759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:43.593164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.069407ms","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-07-30T23:23:43.593272Z","caller":"traceutil/trace.go:171","msg":"trace[546806006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9216; }","duration":"165.229642ms","start":"2026-07-30T23:23:43.42802Z","end":"2026-07-30T23:23:43.59325Z","steps":["trace[546806006] 'agreement among raft nodes before linearized reading' (duration: 164.80689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:43.593268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.601062ms","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-07-30T23:23:43.59341Z","caller":"traceutil/trace.go:171","msg":"trace[1167103023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9216; }","duration":"165.823137ms","start":"2026-07-30T23:23:43.427555Z","end":"2026-07-30T23:23:43.593378Z","steps":["trace[1167103023] 'agreement among raft nodes before linearized reading' (duration: 165.416006ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:43.709825Z","caller":"traceutil/trace.go:171","msg":"trace[1103575287] linearizableReadLoop","detail":"{readStateIndex:9520; appliedIndex:9519; }","duration":"111.759398ms","start":"2026-07-30T23:23:43.598035Z","end":"2026-07-30T23:23:43.709795Z","steps":["trace[1103575287] 'read index received' (duration: 109.110877ms)","trace[1103575287] 'applied index is now lower than readState.Index' (duration: 2.646311ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:23:43.709881Z","caller":"traceutil/trace.go:171","msg":"trace[1544494930] transaction","detail":"{read_only:false; response_revision:9217; number_of_response:1; }","duration":"112.19773ms","start":"2026-07-30T23:23:43.597657Z","end":"2026-07-30T23:23:43.709855Z","steps":["trace[1544494930] 'process raft request' (duration: 109.423075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:43.709999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.936883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ingress-nginx\" ","response":"range_response_count:1 size:1075"} {"level":"info","ts":"2026-07-30T23:23:43.710108Z","caller":"traceutil/trace.go:171","msg":"trace[366486460] range","detail":"{range_begin:/registry/clusterrolebindings/ingress-nginx; range_end:; response_count:1; response_revision:9217; }","duration":"112.082087ms","start":"2026-07-30T23:23:43.598003Z","end":"2026-07-30T23:23:43.710085Z","steps":["trace[366486460] 'agreement among raft nodes before linearized reading' (duration: 111.883241ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:43.711999Z","caller":"traceutil/trace.go:171","msg":"trace[947151198] transaction","detail":"{read_only:false; response_revision:9218; number_of_response:1; }","duration":"113.712341ms","start":"2026-07-30T23:23:43.598271Z","end":"2026-07-30T23:23:43.711984Z","steps":["trace[947151198] 'process raft request' (duration: 113.533366ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:43.712365Z","caller":"traceutil/trace.go:171","msg":"trace[945960301] transaction","detail":"{read_only:false; response_revision:9219; number_of_response:1; }","duration":"113.861495ms","start":"2026-07-30T23:23:43.598494Z","end":"2026-07-30T23:23:43.712355Z","steps":["trace[945960301] 'process raft request' (duration: 113.452624ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:43.712529Z","caller":"traceutil/trace.go:171","msg":"trace[687998704] transaction","detail":"{read_only:false; response_revision:9220; number_of_response:1; }","duration":"113.844335ms","start":"2026-07-30T23:23:43.598675Z","end":"2026-07-30T23:23:43.712519Z","steps":["trace[687998704] 'process raft request' (duration: 113.609488ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:43.712701Z","caller":"traceutil/trace.go:171","msg":"trace[435607464] transaction","detail":"{read_only:false; response_revision:9221; number_of_response:1; }","duration":"108.199532ms","start":"2026-07-30T23:23:43.60447Z","end":"2026-07-30T23:23:43.71267Z","steps":["trace[435607464] 'process raft request' (duration: 107.981446ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:43.712783Z","caller":"traceutil/trace.go:171","msg":"trace[1539493060] transaction","detail":"{read_only:false; response_revision:9222; number_of_response:1; }","duration":"104.736219ms","start":"2026-07-30T23:23:43.608009Z","end":"2026-07-30T23:23:43.712745Z","steps":["trace[1539493060] 'process raft request' (duration: 104.577304ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:23:43.712833Z","caller":"traceutil/trace.go:171","msg":"trace[121428672] transaction","detail":"{read_only:false; response_revision:9223; number_of_response:1; }","duration":"104.078461ms","start":"2026-07-30T23:23:43.608748Z","end":"2026-07-30T23:23:43.712826Z","steps":["trace[121428672] 'process raft request' (duration: 103.926387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:23:43.712796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.564833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver\" ","response":"range_response_count:1 size:724"} {"level":"info","ts":"2026-07-30T23:23:43.712992Z","caller":"traceutil/trace.go:171","msg":"trace[145655481] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver; range_end:; response_count:1; response_revision:9223; }","duration":"114.77045ms","start":"2026-07-30T23:23:43.598191Z","end":"2026-07-30T23:23:43.712961Z","steps":["trace[145655481] 'agreement among raft nodes before linearized reading' (duration: 114.536963ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:24:23.387464Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7015} {"level":"info","ts":"2026-07-30T23:24:23.442104Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7015,"took":"53.33907ms","hash":297014391,"current-db-size-bytes":35340288,"current-db-size":"35 MB","current-db-size-in-use-bytes":13824000,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-07-30T23:24:23.442191Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":297014391,"revision":7015,"compact-revision":4922} {"level":"info","ts":"2026-07-30T23:24:43.890859Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-30T23:24:43.898132Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-30T23:24:43.898272Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}