{"level":"warn","ts":"2026-09-19T06:04:23.665681Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T06:04:23.665819Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.85: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://162.253.55.85:2380","--initial-cluster=instance=https://162.253.55.85:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.85:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.85: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-09-19T06:04:23.665904Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T06:04:23.665953Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.85:2380"]} {"level":"info","ts":"2026-09-19T06:04:23.665984Z","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-09-19T06:04:23.667018Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"]} {"level":"info","ts":"2026-09-19T06:04:23.667139Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.85:2380"],"listen-peer-urls":["https://162.253.55.85:2380"],"advertise-client-urls":["https://162.253.55.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.85: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-09-19T06:04:23.67257Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.075036ms"} {"level":"info","ts":"2026-09-19T06:04:23.680065Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"427150911cb3975a","cluster-id":"f76f6804bd8187de"} {"level":"info","ts":"2026-09-19T06:04:23.680186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T06:04:23.680207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became follower at term 0"} {"level":"info","ts":"2026-09-19T06:04:23.680246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 427150911cb3975a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T06:04:23.68026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became follower at term 1"} {"level":"info","ts":"2026-09-19T06:04:23.680322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=(4787696463053559642)"} {"level":"warn","ts":"2026-09-19T06:04:23.688127Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T06:04:23.689712Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T06:04:23.690929Z","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-09-19T06:04:23.692913Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"427150911cb3975a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T06:04:23.693074Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"427150911cb3975a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T06:04:23.693168Z","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-09-19T06:04:23.693205Z","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-09-19T06:04:23.693215Z","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-09-19T06:04:23.694982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=(4787696463053559642)"} {"level":"info","ts":"2026-09-19T06:04:23.695585Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f76f6804bd8187de","local-member-id":"427150911cb3975a","added-peer-id":"427150911cb3975a","added-peer-peer-urls":["https://162.253.55.85:2380"]} {"level":"info","ts":"2026-09-19T06:04:23.697226Z","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-09-19T06:04:23.697365Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.85:2380"} {"level":"info","ts":"2026-09-19T06:04:23.697411Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.85:2380"} {"level":"info","ts":"2026-09-19T06:04:23.697522Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"427150911cb3975a","initial-advertise-peer-urls":["https://162.253.55.85:2380"],"listen-peer-urls":["https://162.253.55.85:2380"],"advertise-client-urls":["https://162.253.55.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T06:04:23.697581Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T06:04:24.280831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T06:04:24.280882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T06:04:24.280923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a received MsgPreVoteResp from 427150911cb3975a at term 1"} {"level":"info","ts":"2026-09-19T06:04:24.280956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became candidate at term 2"} {"level":"info","ts":"2026-09-19T06:04:24.280982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a received MsgVoteResp from 427150911cb3975a at term 2"} {"level":"info","ts":"2026-09-19T06:04:24.280997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became leader at term 2"} {"level":"info","ts":"2026-09-19T06:04:24.281014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 427150911cb3975a elected leader 427150911cb3975a at term 2"} {"level":"info","ts":"2026-09-19T06:04:24.281963Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"427150911cb3975a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.85:2379]}","request-path":"/0/members/427150911cb3975a/attributes","cluster-id":"f76f6804bd8187de","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T06:04:24.282264Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:04:24.28241Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T06:04:24.282733Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T06:04:24.2828Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T06:04:24.282844Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T06:04:24.283359Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f76f6804bd8187de","local-member-id":"427150911cb3975a","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:04:24.2835Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:04:24.28353Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:04:24.284473Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T06:04:24.286788Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.85:2379"} {"level":"info","ts":"2026-09-19T06:04:26.00338Z","caller":"traceutil/trace.go:171","msg":"trace[2129032864] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"113.413954ms","start":"2026-09-19T06:04:25.889934Z","end":"2026-09-19T06:04:26.003348Z","steps":["trace[2129032864] 'process raft request' (duration: 94.237422ms)","trace[2129032864] 'compare' (duration: 19.04902ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:05:04.549004Z","caller":"traceutil/trace.go:171","msg":"trace[1643928927] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"137.480164ms","start":"2026-09-19T06:05:04.411498Z","end":"2026-09-19T06:05:04.548978Z","steps":["trace[1643928927] 'process raft request' (duration: 131.481303ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:04.549364Z","caller":"traceutil/trace.go:171","msg":"trace[17826227] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"124.483991ms","start":"2026-09-19T06:05:04.424869Z","end":"2026-09-19T06:05:04.549353Z","steps":["trace[17826227] 'process raft request' (duration: 124.180992ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:04.549346Z","caller":"traceutil/trace.go:171","msg":"trace[1737139642] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"104.449476ms","start":"2026-09-19T06:05:04.444872Z","end":"2026-09-19T06:05:04.549322Z","steps":["trace[1737139642] 'process raft request' (duration: 104.390272ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:08.275619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.1257ms","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-09-19T06:05:08.275812Z","caller":"traceutil/trace.go:171","msg":"trace[2109467514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"189.361214ms","start":"2026-09-19T06:05:08.086426Z","end":"2026-09-19T06:05:08.275787Z","steps":["trace[2109467514] 'range keys from in-memory index tree' (duration: 188.979722ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:08.742122Z","caller":"traceutil/trace.go:171","msg":"trace[1113061406] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"208.681946ms","start":"2026-09-19T06:05:08.533411Z","end":"2026-09-19T06:05:08.742093Z","steps":["trace[1113061406] 'process raft request' (duration: 208.515216ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:12.840904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.334265ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:05:12.841047Z","caller":"traceutil/trace.go:171","msg":"trace[2142790722] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:650; }","duration":"190.515864ms","start":"2026-09-19T06:05:12.650511Z","end":"2026-09-19T06:05:12.841027Z","steps":["trace[2142790722] 'range keys from in-memory index tree' (duration: 190.317245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:12.84108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.427795ms","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-09-19T06:05:12.841113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.464287ms","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-09-19T06:05:12.841195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.152173ms","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-09-19T06:05:12.841315Z","caller":"traceutil/trace.go:171","msg":"trace[1965198914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"497.311071ms","start":"2026-09-19T06:05:12.343977Z","end":"2026-09-19T06:05:12.841288Z","steps":["trace[1965198914] 'range keys from in-memory index tree' (duration: 496.975082ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:12.841235Z","caller":"traceutil/trace.go:171","msg":"trace[2057682698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:650; }","duration":"296.671409ms","start":"2026-09-19T06:05:12.54453Z","end":"2026-09-19T06:05:12.841202Z","steps":["trace[2057682698] 'range keys from in-memory index tree' (duration: 296.228955ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:12.841404Z","caller":"traceutil/trace.go:171","msg":"trace[1060588434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"544.634686ms","start":"2026-09-19T06:05:12.296552Z","end":"2026-09-19T06:05:12.841187Z","steps":["trace[1060588434] 'range keys from in-memory index tree' (duration: 544.111638ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:12.841368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:05:12.343963Z","time spent":"497.392906ms","remote":"127.0.0.1:43476","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-09-19T06:05:12.841499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:05:12.296531Z","time spent":"544.948384ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-19T06:05:31.265767Z","caller":"traceutil/trace.go:171","msg":"trace[17537889] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"306.853029ms","start":"2026-09-19T06:05:30.95889Z","end":"2026-09-19T06:05:31.265743Z","steps":["trace[17537889] 'process raft request' (duration: 306.716543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:31.266533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:05:30.958861Z","time spent":"306.988076ms","remote":"127.0.0.1:43476","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-09-19T06:05:31.492755Z","caller":"traceutil/trace.go:171","msg":"trace[324957374] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:845; }","duration":"457.177745ms","start":"2026-09-19T06:05:31.035559Z","end":"2026-09-19T06:05:31.492736Z","steps":["trace[324957374] 'read index received' (duration: 230.43204ms)","trace[324957374] 'applied index is now lower than readState.Index' (duration: 226.744775ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:05:31.49288Z","caller":"traceutil/trace.go:171","msg":"trace[975970787] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"529.827753ms","start":"2026-09-19T06:05:30.963037Z","end":"2026-09-19T06:05:31.492865Z","steps":["trace[975970787] 'process raft request' (duration: 529.537979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:31.492981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:05:30.963011Z","time spent":"529.900406ms","remote":"127.0.0.1:43476","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-09-19T06:05:31.493083Z","caller":"traceutil/trace.go:171","msg":"trace[1888403898] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"327.341655ms","start":"2026-09-19T06:05:31.165513Z","end":"2026-09-19T06:05:31.492855Z","steps":["trace[1888403898] 'process raft request' (duration: 327.179098ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:31.493224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.662636ms","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-09-19T06:05:31.493255Z","caller":"traceutil/trace.go:171","msg":"trace[1209276127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:816; }","duration":"457.693778ms","start":"2026-09-19T06:05:31.035553Z","end":"2026-09-19T06:05:31.493246Z","steps":["trace[1209276127] 'agreement among raft nodes before linearized reading' (duration: 457.273719ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:31.493277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:05:31.035496Z","time spent":"457.776912ms","remote":"127.0.0.1:43476","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-09-19T06:05:31.493534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:05:31.165482Z","time spent":"327.727112ms","remote":"127.0.0.1:43440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:05:31.496875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.242989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:05:31.496908Z","caller":"traceutil/trace.go:171","msg":"trace[13012617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:817; }","duration":"461.307881ms","start":"2026-09-19T06:05:31.035592Z","end":"2026-09-19T06:05:31.4969Z","steps":["trace[13012617] 'agreement among raft nodes before linearized reading' (duration: 461.240738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:31.496926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:05:31.035578Z","time spent":"461.344703ms","remote":"127.0.0.1:43160","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-09-19T06:06:02.515226Z","caller":"traceutil/trace.go:171","msg":"trace[1507388891] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"160.736701ms","start":"2026-09-19T06:06:02.35445Z","end":"2026-09-19T06:06:02.515187Z","steps":["trace[1507388891] 'process raft request' (duration: 160.535924ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:21.043288Z","caller":"traceutil/trace.go:171","msg":"trace[1133653120] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"108.132252ms","start":"2026-09-19T06:06:20.935117Z","end":"2026-09-19T06:06:21.04325Z","steps":["trace[1133653120] 'process raft request' (duration: 103.707661ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:33.098432Z","caller":"traceutil/trace.go:171","msg":"trace[841365044] transaction","detail":"{read_only:false; response_revision:1581; number_of_response:1; }","duration":"158.031658ms","start":"2026-09-19T06:06:32.940376Z","end":"2026-09-19T06:06:33.098407Z","steps":["trace[841365044] 'process raft request' (duration: 157.703328ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:33.123401Z","caller":"traceutil/trace.go:171","msg":"trace[863225776] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"134.106592ms","start":"2026-09-19T06:06:32.989264Z","end":"2026-09-19T06:06:33.12337Z","steps":["trace[863225776] 'process raft request' (duration: 133.955958ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:06:33.400689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.585586ms","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-09-19T06:06:33.400787Z","caller":"traceutil/trace.go:171","msg":"trace[1924187236] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1582; }","duration":"164.73816ms","start":"2026-09-19T06:06:33.236023Z","end":"2026-09-19T06:06:33.400762Z","steps":["trace[1924187236] 'range keys from in-memory index tree' (duration: 164.191965ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:33.401582Z","caller":"traceutil/trace.go:171","msg":"trace[275595648] transaction","detail":"{read_only:false; response_revision:1583; number_of_response:1; }","duration":"163.60698ms","start":"2026-09-19T06:06:33.237949Z","end":"2026-09-19T06:06:33.401556Z","steps":["trace[275595648] 'process raft request' (duration: 163.289881ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:33.478574Z","caller":"traceutil/trace.go:171","msg":"trace[47993472] linearizableReadLoop","detail":"{readStateIndex:1634; appliedIndex:1632; }","duration":"124.808454ms","start":"2026-09-19T06:06:33.353738Z","end":"2026-09-19T06:06:33.478547Z","steps":["trace[47993472] 'read index received' (duration: 46.962428ms)","trace[47993472] 'applied index is now lower than readState.Index' (duration: 77.844135ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:06:33.478731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.986309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T06:06:33.47891Z","caller":"traceutil/trace.go:171","msg":"trace[1661339658] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1584; }","duration":"125.069001ms","start":"2026-09-19T06:06:33.353687Z","end":"2026-09-19T06:06:33.478756Z","steps":["trace[1661339658] 'agreement among raft nodes before linearized reading' (duration: 124.935678ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:53.744651Z","caller":"traceutil/trace.go:171","msg":"trace[45788750] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"138.823317ms","start":"2026-09-19T06:06:53.605787Z","end":"2026-09-19T06:06:53.74461Z","steps":["trace[45788750] 'process raft request' (duration: 125.654214ms)","trace[45788750] 'compare' (duration: 12.913457ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:06:54.431832Z","caller":"traceutil/trace.go:171","msg":"trace[1399034517] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1969; }","duration":"301.023354ms","start":"2026-09-19T06:06:54.130752Z","end":"2026-09-19T06:06:54.431776Z","steps":["trace[1399034517] 'read index received' (duration: 300.85458ms)","trace[1399034517] 'applied index is now lower than readState.Index' (duration: 167.554µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:06:54.431878Z","caller":"traceutil/trace.go:171","msg":"trace[1770738018] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"363.455848ms","start":"2026-09-19T06:06:54.068387Z","end":"2026-09-19T06:06:54.431843Z","steps":["trace[1770738018] 'process raft request' (duration: 363.225123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:06:54.432066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:06:54.068361Z","time spent":"363.603282ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:06:54.432079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.30839ms","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-09-19T06:06:54.432138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.127419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:06:54.432198Z","caller":"traceutil/trace.go:171","msg":"trace[15834872] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1894; }","duration":"286.212462ms","start":"2026-09-19T06:06:54.145967Z","end":"2026-09-19T06:06:54.432179Z","steps":["trace[15834872] 'agreement among raft nodes before linearized reading' (duration: 286.106879ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:54.432388Z","caller":"traceutil/trace.go:171","msg":"trace[810011661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1894; }","duration":"301.456504ms","start":"2026-09-19T06:06:54.130703Z","end":"2026-09-19T06:06:54.43216Z","steps":["trace[810011661] 'agreement among raft nodes before linearized reading' (duration: 301.244109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:06:54.432482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:06:54.130681Z","time spent":"301.786292ms","remote":"127.0.0.1:43476","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-09-19T06:06:54.855353Z","caller":"traceutil/trace.go:171","msg":"trace[1845104222] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"178.631593ms","start":"2026-09-19T06:06:54.676688Z","end":"2026-09-19T06:06:54.85532Z","steps":["trace[1845104222] 'process raft request' (duration: 178.421428ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:09.098022Z","caller":"traceutil/trace.go:171","msg":"trace[694536037] linearizableReadLoop","detail":"{readStateIndex:2102; appliedIndex:2101; }","duration":"118.445374ms","start":"2026-09-19T06:07:08.979561Z","end":"2026-09-19T06:07:09.098006Z","steps":["trace[694536037] 'read index received' (duration: 117.628545ms)","trace[694536037] 'applied index is now lower than readState.Index' (duration: 812.429µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:09.098231Z","caller":"traceutil/trace.go:171","msg":"trace[50873198] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"164.441507ms","start":"2026-09-19T06:07:08.933782Z","end":"2026-09-19T06:07:09.098223Z","steps":["trace[50873198] 'process raft request' (duration: 163.396233ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:09.098347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.781962ms","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-09-19T06:07:09.098372Z","caller":"traceutil/trace.go:171","msg":"trace[675536773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2022; }","duration":"118.859393ms","start":"2026-09-19T06:07:08.979506Z","end":"2026-09-19T06:07:09.098365Z","steps":["trace[675536773] 'agreement among raft nodes before linearized reading' (duration: 118.759581ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:09.104435Z","caller":"traceutil/trace.go:171","msg":"trace[2075349315] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"109.12372ms","start":"2026-09-19T06:07:08.995302Z","end":"2026-09-19T06:07:09.104426Z","steps":["trace[2075349315] 'process raft request' (duration: 109.039818ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:25.214425Z","caller":"traceutil/trace.go:171","msg":"trace[1349703857] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"415.883469ms","start":"2026-09-19T06:07:24.798518Z","end":"2026-09-19T06:07:25.214401Z","steps":["trace[1349703857] 'process raft request' (duration: 415.673184ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:25.214766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:24.798484Z","time spent":"416.095214ms","remote":"127.0.0.1:43476","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-09-19T06:07:25.30464Z","caller":"traceutil/trace.go:171","msg":"trace[314162172] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"137.606263ms","start":"2026-09-19T06:07:25.167014Z","end":"2026-09-19T06:07:25.304621Z","steps":["trace[314162172] 'process raft request' (duration: 137.341707ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:25.304801Z","caller":"traceutil/trace.go:171","msg":"trace[633193106] linearizableReadLoop","detail":"{readStateIndex:2201; appliedIndex:2199; }","duration":"138.745978ms","start":"2026-09-19T06:07:25.16604Z","end":"2026-09-19T06:07:25.304786Z","steps":["trace[633193106] 'read index received' (duration: 48.107852ms)","trace[633193106] 'applied index is now lower than readState.Index' (duration: 90.636656ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:25.304928Z","caller":"traceutil/trace.go:171","msg":"trace[1739701944] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"134.158379ms","start":"2026-09-19T06:07:25.170761Z","end":"2026-09-19T06:07:25.304919Z","steps":["trace[1739701944] 'process raft request' (duration: 133.807811ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:25.305032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.971673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:07:25.304417Z","caller":"traceutil/trace.go:171","msg":"trace[902549960] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"140.611728ms","start":"2026-09-19T06:07:25.163763Z","end":"2026-09-19T06:07:25.304375Z","steps":["trace[902549960] 'process raft request' (duration: 61.415298ms)","trace[902549960] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/08db1feb.percona.com; req_size:506; } (duration: 78.810991ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:25.305064Z","caller":"traceutil/trace.go:171","msg":"trace[1946532329] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2120; }","duration":"139.061515ms","start":"2026-09-19T06:07:25.165992Z","end":"2026-09-19T06:07:25.305054Z","steps":["trace[1946532329] 'agreement among raft nodes before linearized reading' (duration: 138.991513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:25.305421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.221713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-19T06:07:25.305447Z","caller":"traceutil/trace.go:171","msg":"trace[835647814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2120; }","duration":"122.278235ms","start":"2026-09-19T06:07:25.18316Z","end":"2026-09-19T06:07:25.305438Z","steps":["trace[835647814] 'agreement among raft nodes before linearized reading' (duration: 122.191263ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:25.305536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.943944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-19T06:07:25.305557Z","caller":"traceutil/trace.go:171","msg":"trace[952209471] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2120; }","duration":"106.007595ms","start":"2026-09-19T06:07:25.199543Z","end":"2026-09-19T06:07:25.30555Z","steps":["trace[952209471] 'agreement among raft nodes before linearized reading' (duration: 105.953264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:25.66637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.299027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:07:25.666474Z","caller":"traceutil/trace.go:171","msg":"trace[1754148926] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"357.312048ms","start":"2026-09-19T06:07:25.309139Z","end":"2026-09-19T06:07:25.666451Z","steps":["trace[1754148926] 'process raft request' (duration: 143.733795ms)","trace[1754148926] 'compare' (duration: 213.190104ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:25.666528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:25.309124Z","time spent":"357.37757ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:07:25.747968Z","caller":"traceutil/trace.go:171","msg":"trace[432147195] linearizableReadLoop","detail":"{readStateIndex:2206; appliedIndex:2205; }","duration":"193.166594ms","start":"2026-09-19T06:07:25.554772Z","end":"2026-09-19T06:07:25.747938Z","steps":["trace[432147195] 'read index received' (duration: 192.736605ms)","trace[432147195] 'applied index is now lower than readState.Index' (duration: 428.949µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:25.748011Z","caller":"traceutil/trace.go:171","msg":"trace[391341004] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"305.177048ms","start":"2026-09-19T06:07:25.442817Z","end":"2026-09-19T06:07:25.747994Z","steps":["trace[391341004] 'process raft request' (duration: 304.956624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:25.748224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.43297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-09-19T06:07:25.748276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:25.442788Z","time spent":"305.260469ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:07:25.748324Z","caller":"traceutil/trace.go:171","msg":"trace[122028518] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2123; }","duration":"193.604123ms","start":"2026-09-19T06:07:25.5547Z","end":"2026-09-19T06:07:25.748304Z","steps":["trace[122028518] 'agreement among raft nodes before linearized reading' (duration: 193.392419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:25.748636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.499372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:126273"} {"level":"info","ts":"2026-09-19T06:07:25.748725Z","caller":"traceutil/trace.go:171","msg":"trace[939558950] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2123; }","duration":"193.630095ms","start":"2026-09-19T06:07:25.555077Z","end":"2026-09-19T06:07:25.748707Z","steps":["trace[939558950] 'agreement among raft nodes before linearized reading' (duration: 193.022352ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:26.302273Z","caller":"traceutil/trace.go:171","msg":"trace[642494855] linearizableReadLoop","detail":"{readStateIndex:2207; appliedIndex:2206; }","duration":"343.50051ms","start":"2026-09-19T06:07:25.958744Z","end":"2026-09-19T06:07:26.302244Z","steps":["trace[642494855] 'read index received' (duration: 343.210613ms)","trace[642494855] 'applied index is now lower than readState.Index' (duration: 288.647µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:26.302378Z","caller":"traceutil/trace.go:171","msg":"trace[1705020730] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"392.783696ms","start":"2026-09-19T06:07:25.909559Z","end":"2026-09-19T06:07:26.302343Z","steps":["trace[1705020730] 'process raft request' (duration: 392.48915ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:26.30253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:25.909537Z","time spent":"392.908268ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:07:26.302526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.774844ms","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-09-19T06:07:26.302595Z","caller":"traceutil/trace.go:171","msg":"trace[967610105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2124; }","duration":"343.922988ms","start":"2026-09-19T06:07:25.958653Z","end":"2026-09-19T06:07:26.302576Z","steps":["trace[967610105] 'agreement among raft nodes before linearized reading' (duration: 343.704253ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:26.302622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.396069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T06:07:26.302693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:25.958611Z","time spent":"344.043721ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-19T06:07:26.302738Z","caller":"traceutil/trace.go:171","msg":"trace[441541897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2124; }","duration":"268.535882ms","start":"2026-09-19T06:07:26.034188Z","end":"2026-09-19T06:07:26.302724Z","steps":["trace[441541897] 'agreement among raft nodes before linearized reading' (duration: 268.353158ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:26.302777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.083468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-19T06:07:26.30288Z","caller":"traceutil/trace.go:171","msg":"trace[67867849] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2124; }","duration":"301.216471ms","start":"2026-09-19T06:07:26.001644Z","end":"2026-09-19T06:07:26.30286Z","steps":["trace[67867849] 'agreement among raft nodes before linearized reading' (duration: 300.752111ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:26.302975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:26.001629Z","time spent":"301.330553ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-09-19T06:07:26.853321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.726068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:07:26.853481Z","caller":"traceutil/trace.go:171","msg":"trace[1031118797] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"546.308483ms","start":"2026-09-19T06:07:26.307139Z","end":"2026-09-19T06:07:26.853447Z","steps":["trace[1031118797] 'process raft request' (duration: 406.331769ms)","trace[1031118797] 'compare' (duration: 138.647865ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:26.853569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:26.30712Z","time spent":"546.408174ms","remote":"127.0.0.1:43476","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-09-19T06:07:26.885883Z","caller":"traceutil/trace.go:171","msg":"trace[1335978295] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"578.613123ms","start":"2026-09-19T06:07:26.307222Z","end":"2026-09-19T06:07:26.885835Z","steps":["trace[1335978295] 'process raft request' (duration: 578.384448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:26.886131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:26.307195Z","time spent":"578.798917ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:07:27.585187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.844659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:07:27.585368Z","caller":"traceutil/trace.go:171","msg":"trace[2034519477] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"694.189022ms","start":"2026-09-19T06:07:26.891147Z","end":"2026-09-19T06:07:27.585336Z","steps":["trace[2034519477] 'process raft request' (duration: 158.058437ms)","trace[2034519477] 'compare' (duration: 535.620184ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:27.585452Z","caller":"traceutil/trace.go:171","msg":"trace[2134950250] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"273.884307ms","start":"2026-09-19T06:07:27.311553Z","end":"2026-09-19T06:07:27.585438Z","steps":["trace[2134950250] 'process raft request' (duration: 273.771234ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:27.585477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:26.89112Z","time spent":"694.310775ms","remote":"127.0.0.1:43476","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-09-19T06:07:27.585596Z","caller":"traceutil/trace.go:171","msg":"trace[2086308828] linearizableReadLoop","detail":"{readStateIndex:2210; appliedIndex:2209; }","duration":"366.102572ms","start":"2026-09-19T06:07:27.219481Z","end":"2026-09-19T06:07:27.585583Z","steps":["trace[2086308828] 'read index received' (duration: 54.671µs)","trace[2086308828] 'applied index is now lower than readState.Index' (duration: 366.046251ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:27.585947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.47351ms","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-09-19T06:07:27.585989Z","caller":"traceutil/trace.go:171","msg":"trace[668788137] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2128; }","duration":"366.759356ms","start":"2026-09-19T06:07:27.219218Z","end":"2026-09-19T06:07:27.585977Z","steps":["trace[668788137] 'agreement among raft nodes before linearized reading' (duration: 366.577012ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:27.586035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:27.219189Z","time spent":"366.823957ms","remote":"127.0.0.1:43476","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-09-19T06:07:28.247896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.829042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:07:28.248104Z","caller":"traceutil/trace.go:171","msg":"trace[1392491313] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"656.565945ms","start":"2026-09-19T06:07:27.591522Z","end":"2026-09-19T06:07:28.248088Z","steps":["trace[1392491313] 'process raft request' (duration: 656.524584ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.248168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:27.591491Z","time spent":"656.644947ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:07:28.248308Z","caller":"traceutil/trace.go:171","msg":"trace[1492773290] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"936.470651ms","start":"2026-09-19T06:07:27.311703Z","end":"2026-09-19T06:07:28.248174Z","steps":["trace[1492773290] 'process raft request' (duration: 447.282132ms)","trace[1492773290] 'compare' (duration: 488.353561ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:28.248361Z","caller":"traceutil/trace.go:171","msg":"trace[716407604] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"936.297396ms","start":"2026-09-19T06:07:27.312041Z","end":"2026-09-19T06:07:28.248339Z","steps":["trace[716407604] 'process raft request' (duration: 935.939479ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:28.248377Z","caller":"traceutil/trace.go:171","msg":"trace[1056445549] linearizableReadLoop","detail":"{readStateIndex:2213; appliedIndex:2211; }","duration":"662.733286ms","start":"2026-09-19T06:07:27.585622Z","end":"2026-09-19T06:07:28.248355Z","steps":["trace[1056445549] 'read index received' (duration: 173.354104ms)","trace[1056445549] 'applied index is now lower than readState.Index' (duration: 489.375412ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:28.248414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:27.312005Z","time spent":"936.379488ms","remote":"127.0.0.1:43476","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-09-19T06:07:28.248525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:27.311595Z","time spent":"936.789397ms","remote":"127.0.0.1:43476","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-09-19T06:07:28.248614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.593864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18d6a3d611fbf6e1\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-09-19T06:07:28.248682Z","caller":"traceutil/trace.go:171","msg":"trace[1885034062] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18d6a3d611fbf6e1; range_end:; response_count:1; response_revision:2131; }","duration":"761.686596ms","start":"2026-09-19T06:07:27.486965Z","end":"2026-09-19T06:07:28.248652Z","steps":["trace[1885034062] 'agreement among raft nodes before linearized reading' (duration: 761.506952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.248707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:27.48694Z","time spent":"761.760728ms","remote":"127.0.0.1:43260","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.18d6a3d611fbf6e1\" "} {"level":"warn","ts":"2026-09-19T06:07:28.248922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.156308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:07:28.248952Z","caller":"traceutil/trace.go:171","msg":"trace[669896663] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2131; }","duration":"459.185039ms","start":"2026-09-19T06:07:27.789756Z","end":"2026-09-19T06:07:28.248941Z","steps":["trace[669896663] 'agreement among raft nodes before linearized reading' (duration: 459.129848ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.248973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:27.789679Z","time spent":"459.287122ms","remote":"127.0.0.1:35004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-19T06:07:28.248993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.485687ms","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-09-19T06:07:28.249017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.752592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:07:28.249035Z","caller":"traceutil/trace.go:171","msg":"trace[521515183] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2131; }","duration":"353.574579ms","start":"2026-09-19T06:07:27.895445Z","end":"2026-09-19T06:07:28.24902Z","steps":["trace[521515183] 'agreement among raft nodes before linearized reading' (duration: 353.375234ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:28.249065Z","caller":"traceutil/trace.go:171","msg":"trace[1037381493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2131; }","duration":"214.829293ms","start":"2026-09-19T06:07:28.034207Z","end":"2026-09-19T06:07:28.249036Z","steps":["trace[1037381493] 'agreement among raft nodes before linearized reading' (duration: 214.781242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.24905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.888368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T06:07:28.249078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.494111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-19T06:07:28.249143Z","caller":"traceutil/trace.go:171","msg":"trace[2122319782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2131; }","duration":"416.99832ms","start":"2026-09-19T06:07:27.83212Z","end":"2026-09-19T06:07:28.249119Z","steps":["trace[2122319782] 'agreement among raft nodes before linearized reading' (duration: 416.836426ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:28.249151Z","caller":"traceutil/trace.go:171","msg":"trace[1943552720] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2131; }","duration":"578.614053ms","start":"2026-09-19T06:07:27.67052Z","end":"2026-09-19T06:07:28.249134Z","steps":["trace[1943552720] 'agreement among raft nodes before linearized reading' (duration: 578.312457ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.249142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.107332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.85\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-19T06:07:28.249191Z","caller":"traceutil/trace.go:171","msg":"trace[322574509] range","detail":"{range_begin:/registry/masterleases/162.253.55.85; range_end:; response_count:1; response_revision:2131; }","duration":"318.213444ms","start":"2026-09-19T06:07:27.930969Z","end":"2026-09-19T06:07:28.249182Z","steps":["trace[322574509] 'agreement among raft nodes before linearized reading' (duration: 318.114212ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.249194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:27.832091Z","time spent":"417.090281ms","remote":"127.0.0.1:43160","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-09-19T06:07:28.249191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:27.670494Z","time spent":"578.687105ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-19T06:07:28.249066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:27.895398Z","time spent":"353.659701ms","remote":"127.0.0.1:59258","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-09-19T06:07:28.249213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:27.930939Z","time spent":"318.268096ms","remote":"127.0.0.1:43212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.85\" "} {"level":"warn","ts":"2026-09-19T06:07:28.249236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.034832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-09-19T06:07:28.249262Z","caller":"traceutil/trace.go:171","msg":"trace[1693163723] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2131; }","duration":"563.093234ms","start":"2026-09-19T06:07:27.686161Z","end":"2026-09-19T06:07:28.249254Z","steps":["trace[1693163723] 'agreement among raft nodes before linearized reading' (duration: 563.003332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.24928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:27.686147Z","time spent":"563.128294ms","remote":"127.0.0.1:43386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12408,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-19T06:07:28.249447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.204817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-19T06:07:28.249525Z","caller":"traceutil/trace.go:171","msg":"trace[1680939599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2131; }","duration":"900.34388ms","start":"2026-09-19T06:07:27.349161Z","end":"2026-09-19T06:07:28.249505Z","steps":["trace[1680939599] 'agreement among raft nodes before linearized reading' (duration: 899.963662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.249637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:27.34914Z","time spent":"900.488073ms","remote":"127.0.0.1:43476","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-09-19T06:07:29.098788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.29449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:07:29.098995Z","caller":"traceutil/trace.go:171","msg":"trace[621182146] linearizableReadLoop","detail":"{readStateIndex:2218; appliedIndex:2214; }","duration":"835.895698ms","start":"2026-09-19T06:07:28.26309Z","end":"2026-09-19T06:07:29.098986Z","steps":["trace[621182146] 'read index received' (duration: 197.370822ms)","trace[621182146] 'applied index is now lower than readState.Index' (duration: 638.524256ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:29.099054Z","caller":"traceutil/trace.go:171","msg":"trace[1249685085] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"847.257618ms","start":"2026-09-19T06:07:28.251789Z","end":"2026-09-19T06:07:29.099047Z","steps":["trace[1249685085] 'process raft request' (duration: 208.651782ms)","trace[1249685085] 'compare' (duration: 638.225289ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:29.099087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:28.251771Z","time spent":"847.30034ms","remote":"127.0.0.1:43260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:07:29.099189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:28.252141Z","time spent":"847.047444ms","remote":"127.0.0.1:43212","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-19T06:07:29.09936Z","caller":"traceutil/trace.go:171","msg":"trace[2113224365] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"843.244753ms","start":"2026-09-19T06:07:28.256107Z","end":"2026-09-19T06:07:29.099352Z","steps":["trace[2113224365] 'process raft request' (duration: 842.825165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:29.099402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:28.256081Z","time spent":"843.300104ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:07:29.099487Z","caller":"traceutil/trace.go:171","msg":"trace[1898722601] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"839.802171ms","start":"2026-09-19T06:07:28.259677Z","end":"2026-09-19T06:07:29.099479Z","steps":["trace[1898722601] 'process raft request' (duration: 839.290641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:29.099535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:28.259617Z","time spent":"839.895582ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:07:29.099626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.536521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:07:29.099646Z","caller":"traceutil/trace.go:171","msg":"trace[497184950] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2134; }","duration":"836.587492ms","start":"2026-09-19T06:07:28.263054Z","end":"2026-09-19T06:07:29.099642Z","steps":["trace[497184950] 'agreement among raft nodes before linearized reading' (duration: 836.553091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:29.099678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:28.263034Z","time spent":"836.638463ms","remote":"127.0.0.1:43602","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-09-19T06:07:29.099829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.351383ms","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-09-19T06:07:29.099879Z","caller":"traceutil/trace.go:171","msg":"trace[1623107219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2134; }","duration":"792.443265ms","start":"2026-09-19T06:07:28.307423Z","end":"2026-09-19T06:07:29.099866Z","steps":["trace[1623107219] 'agreement among raft nodes before linearized reading' (duration: 792.294062ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:29.099908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:28.307401Z","time spent":"792.500096ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-19T06:07:29.100175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.535224ms","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-09-19T06:07:29.100231Z","caller":"traceutil/trace.go:171","msg":"trace[568964829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2134; }","duration":"241.655317ms","start":"2026-09-19T06:07:28.858563Z","end":"2026-09-19T06:07:29.100219Z","steps":["trace[568964829] 'agreement among raft nodes before linearized reading' (duration: 241.474903ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:11.956156Z","caller":"traceutil/trace.go:171","msg":"trace[372032751] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"259.962817ms","start":"2026-09-19T06:09:11.696174Z","end":"2026-09-19T06:09:11.956137Z","steps":["trace[372032751] 'process raft request' (duration: 258.147433ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:08.634392Z","caller":"traceutil/trace.go:171","msg":"trace[1047397695] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"117.359775ms","start":"2026-09-19T06:14:08.517011Z","end":"2026-09-19T06:14:08.634371Z","steps":["trace[1047397695] 'process raft request' (duration: 117.184602ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:11.783171Z","caller":"traceutil/trace.go:171","msg":"trace[238621673] linearizableReadLoop","detail":"{readStateIndex:5117; appliedIndex:5116; }","duration":"120.626268ms","start":"2026-09-19T06:14:11.662523Z","end":"2026-09-19T06:14:11.78315Z","steps":["trace[238621673] 'read index received' (duration: 108.594273ms)","trace[238621673] 'applied index is now lower than readState.Index' (duration: 12.030625ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:11.783273Z","caller":"traceutil/trace.go:171","msg":"trace[1842156400] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"128.498039ms","start":"2026-09-19T06:14:11.654763Z","end":"2026-09-19T06:14:11.783261Z","steps":["trace[1842156400] 'process raft request' (duration: 116.454234ms)","trace[1842156400] 'compare' (duration: 11.528005ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:14:11.783521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.950034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-19T06:14:11.783552Z","caller":"traceutil/trace.go:171","msg":"trace[130038134] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4924; }","duration":"121.043085ms","start":"2026-09-19T06:14:11.662501Z","end":"2026-09-19T06:14:11.783544Z","steps":["trace[130038134] 'agreement among raft nodes before linearized reading' (duration: 120.865542ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:11.783723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.000684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:14:11.783803Z","caller":"traceutil/trace.go:171","msg":"trace[1132575948] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:0; response_revision:4924; }","duration":"121.204219ms","start":"2026-09-19T06:14:11.66258Z","end":"2026-09-19T06:14:11.783785Z","steps":["trace[1132575948] 'agreement among raft nodes before linearized reading' (duration: 120.993815ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:13.862454Z","caller":"traceutil/trace.go:171","msg":"trace[1057080124] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"175.036329ms","start":"2026-09-19T06:14:13.687384Z","end":"2026-09-19T06:14:13.862421Z","steps":["trace[1057080124] 'process raft request' (duration: 174.862995ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:13.893644Z","caller":"traceutil/trace.go:171","msg":"trace[713820601] linearizableReadLoop","detail":"{readStateIndex:5172; appliedIndex:5170; }","duration":"150.236635ms","start":"2026-09-19T06:14:13.743383Z","end":"2026-09-19T06:14:13.89362Z","steps":["trace[713820601] 'read index received' (duration: 119.117555ms)","trace[713820601] 'applied index is now lower than readState.Index' (duration: 31.11824ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:13.893742Z","caller":"traceutil/trace.go:171","msg":"trace[268069191] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"150.659944ms","start":"2026-09-19T06:14:13.74306Z","end":"2026-09-19T06:14:13.89372Z","steps":["trace[268069191] 'process raft request' (duration: 150.400828ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:13.893782Z","caller":"traceutil/trace.go:171","msg":"trace[1225505085] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"150.552052ms","start":"2026-09-19T06:14:13.743206Z","end":"2026-09-19T06:14:13.893758Z","steps":["trace[1225505085] 'process raft request' (duration: 150.367219ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:13.894002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.624834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d7d00cc8-42db-446e-883d-b1bc0ec5ec7a\" ","response":"range_response_count:1 size:3759"} {"level":"info","ts":"2026-09-19T06:14:13.894039Z","caller":"traceutil/trace.go:171","msg":"trace[984765234] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d7d00cc8-42db-446e-883d-b1bc0ec5ec7a; range_end:; response_count:1; response_revision:4978; }","duration":"150.715286ms","start":"2026-09-19T06:14:13.743315Z","end":"2026-09-19T06:14:13.89403Z","steps":["trace[984765234] 'agreement among raft nodes before linearized reading' (duration: 150.581113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:13.972849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.348872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-19T06:14:13.972911Z","caller":"traceutil/trace.go:171","msg":"trace[1782211630] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4980; }","duration":"171.447115ms","start":"2026-09-19T06:14:13.801449Z","end":"2026-09-19T06:14:13.972896Z","steps":["trace[1782211630] 'agreement among raft nodes before linearized reading' (duration: 171.319612ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:13.972941Z","caller":"traceutil/trace.go:171","msg":"trace[691179555] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"104.6302ms","start":"2026-09-19T06:14:13.868296Z","end":"2026-09-19T06:14:13.972926Z","steps":["trace[691179555] 'process raft request' (duration: 88.615036ms)","trace[691179555] 'compare' (duration: 15.601757ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:15.813791Z","caller":"traceutil/trace.go:171","msg":"trace[707465700] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"137.004564ms","start":"2026-09-19T06:14:15.676765Z","end":"2026-09-19T06:14:15.81377Z","steps":["trace[707465700] 'process raft request' (duration: 136.890412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:19.235889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.839611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:14:19.235995Z","caller":"traceutil/trace.go:171","msg":"trace[582636490] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:5091; }","duration":"172.996315ms","start":"2026-09-19T06:14:19.062972Z","end":"2026-09-19T06:14:19.235969Z","steps":["trace[582636490] 'count revisions from in-memory index tree' (duration: 172.76525ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:19.236201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.723945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:14:19.236268Z","caller":"traceutil/trace.go:171","msg":"trace[880928671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5091; }","duration":"202.821317ms","start":"2026-09-19T06:14:19.033431Z","end":"2026-09-19T06:14:19.236253Z","steps":["trace[880928671] 'range keys from in-memory index tree' (duration: 202.643414ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:19.678691Z","caller":"traceutil/trace.go:171","msg":"trace[402928933] linearizableReadLoop","detail":"{readStateIndex:5292; appliedIndex:5291; }","duration":"188.550599ms","start":"2026-09-19T06:14:19.49012Z","end":"2026-09-19T06:14:19.67867Z","steps":["trace[402928933] 'read index received' (duration: 175.426924ms)","trace[402928933] 'applied index is now lower than readState.Index' (duration: 13.122365ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:19.678828Z","caller":"traceutil/trace.go:171","msg":"trace[2137620519] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"251.209685ms","start":"2026-09-19T06:14:19.427591Z","end":"2026-09-19T06:14:19.6788Z","steps":["trace[2137620519] 'process raft request' (duration: 238.08535ms)","trace[2137620519] 'compare' (duration: 12.693686ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:14:19.679076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.933206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-19T06:14:19.679162Z","caller":"traceutil/trace.go:171","msg":"trace[2075175923] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5093; }","duration":"189.066439ms","start":"2026-09-19T06:14:19.490078Z","end":"2026-09-19T06:14:19.679144Z","steps":["trace[2075175923] 'agreement among raft nodes before linearized reading' (duration: 188.843535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:19.679087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.355651ms","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-09-19T06:14:19.679278Z","caller":"traceutil/trace.go:171","msg":"trace[89414585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5093; }","duration":"115.600286ms","start":"2026-09-19T06:14:19.563658Z","end":"2026-09-19T06:14:19.679258Z","steps":["trace[89414585] 'agreement among raft nodes before linearized reading' (duration: 115.255749ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:20.248268Z","caller":"traceutil/trace.go:171","msg":"trace[2013181474] linearizableReadLoop","detail":"{readStateIndex:5294; appliedIndex:5293; }","duration":"532.965065ms","start":"2026-09-19T06:14:19.715277Z","end":"2026-09-19T06:14:20.248242Z","steps":["trace[2013181474] 'read index received' (duration: 27.316552ms)","trace[2013181474] 'applied index is now lower than readState.Index' (duration: 505.647353ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:20.248319Z","caller":"traceutil/trace.go:171","msg":"trace[752190600] transaction","detail":"{read_only:false; response_revision:5095; number_of_response:1; }","duration":"563.574313ms","start":"2026-09-19T06:14:19.684719Z","end":"2026-09-19T06:14:20.248294Z","steps":["trace[752190600] 'process raft request' (duration: 562.887079ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:20.248387Z","caller":"traceutil/trace.go:171","msg":"trace[889899603] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"530.796941ms","start":"2026-09-19T06:14:19.717551Z","end":"2026-09-19T06:14:20.248347Z","steps":["trace[889899603] 'process raft request' (duration: 530.625558ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:20.248522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:19.717536Z","time spent":"530.921635ms","remote":"127.0.0.1:43260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":904,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T06:14:20.248607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:19.68469Z","time spent":"563.687366ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:14:20.249238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.947135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-4dzkc\" ","response":"range_response_count:1 size:9753"} {"level":"info","ts":"2026-09-19T06:14:20.249296Z","caller":"traceutil/trace.go:171","msg":"trace[1387985083] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-4dzkc; range_end:; response_count:1; response_revision:5096; }","duration":"534.048257ms","start":"2026-09-19T06:14:19.715235Z","end":"2026-09-19T06:14:20.249284Z","steps":["trace[1387985083] 'agreement among raft nodes before linearized reading' (duration: 533.122839ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:20.249327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:19.715211Z","time spent":"534.107957ms","remote":"127.0.0.1:43386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9776,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-4dzkc\" "} {"level":"info","ts":"2026-09-19T06:14:20.254942Z","caller":"traceutil/trace.go:171","msg":"trace[1614010173] transaction","detail":"{read_only:false; response_revision:5097; number_of_response:1; }","duration":"264.330936ms","start":"2026-09-19T06:14:19.990585Z","end":"2026-09-19T06:14:20.254916Z","steps":["trace[1614010173] 'process raft request' (duration: 264.138053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:20.255202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.813125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:14:20.255253Z","caller":"traceutil/trace.go:171","msg":"trace[581950713] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:5098; }","duration":"451.910327ms","start":"2026-09-19T06:14:19.803331Z","end":"2026-09-19T06:14:20.255241Z","steps":["trace[581950713] 'agreement among raft nodes before linearized reading' (duration: 451.730353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:20.255284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:19.80331Z","time spent":"451.965778ms","remote":"127.0.0.1:43554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-09-19T06:14:20.255464Z","caller":"traceutil/trace.go:171","msg":"trace[708639251] transaction","detail":"{read_only:false; response_revision:5098; number_of_response:1; }","duration":"227.707707ms","start":"2026-09-19T06:14:20.027737Z","end":"2026-09-19T06:14:20.255445Z","steps":["trace[708639251] 'process raft request' (duration: 227.127725ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:20.255506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.084639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-19T06:14:20.25556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.756012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T06:14:20.255687Z","caller":"traceutil/trace.go:171","msg":"trace[779362623] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:5098; }","duration":"300.921466ms","start":"2026-09-19T06:14:19.954754Z","end":"2026-09-19T06:14:20.255675Z","steps":["trace[779362623] 'agreement among raft nodes before linearized reading' (duration: 300.752772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:20.255728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:19.954733Z","time spent":"300.984847ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":25,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-09-19T06:14:20.255733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.860512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-19T06:14:20.255796Z","caller":"traceutil/trace.go:171","msg":"trace[2008551674] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5098; }","duration":"241.980205ms","start":"2026-09-19T06:14:20.0138Z","end":"2026-09-19T06:14:20.25578Z","steps":["trace[2008551674] 'agreement among raft nodes before linearized reading' (duration: 241.791181ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:20.255563Z","caller":"traceutil/trace.go:171","msg":"trace[1253777797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5098; }","duration":"246.17806ms","start":"2026-09-19T06:14:20.009365Z","end":"2026-09-19T06:14:20.255543Z","steps":["trace[1253777797] 'agreement among raft nodes before linearized reading' (duration: 245.980916ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:24.370836Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2853} {"level":"info","ts":"2026-09-19T06:14:24.523135Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2853,"took":"151.073543ms","hash":1558202115,"current-db-size-bytes":50679808,"current-db-size":"51 MB","current-db-size-in-use-bytes":26738688,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-19T06:14:24.52318Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1558202115,"revision":2853,"compact-revision":-1} {"level":"info","ts":"2026-09-19T06:14:26.700724Z","caller":"traceutil/trace.go:171","msg":"trace[142374582] linearizableReadLoop","detail":"{readStateIndex:5366; appliedIndex:5364; }","duration":"373.281744ms","start":"2026-09-19T06:14:26.327414Z","end":"2026-09-19T06:14:26.700695Z","steps":["trace[142374582] 'read index received' (duration: 328.208787ms)","trace[142374582] 'applied index is now lower than readState.Index' (duration: 45.071607ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:14:26.70117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.227456ms","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-09-19T06:14:26.70127Z","caller":"traceutil/trace.go:171","msg":"trace[1303085878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5164; }","duration":"323.306127ms","start":"2026-09-19T06:14:26.377905Z","end":"2026-09-19T06:14:26.701211Z","steps":["trace[1303085878] 'agreement among raft nodes before linearized reading' (duration: 323.168595ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:26.701402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:26.377892Z","time spent":"323.492132ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-19T06:14:26.701325Z","caller":"traceutil/trace.go:171","msg":"trace[939572969] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"377.078359ms","start":"2026-09-19T06:14:26.324225Z","end":"2026-09-19T06:14:26.701303Z","steps":["trace[939572969] 'process raft request' (duration: 330.676815ms)","trace[939572969] 'compare' (duration: 45.421994ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:14:26.701162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.742613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-19T06:14:26.701855Z","caller":"traceutil/trace.go:171","msg":"trace[1289959798] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5164; }","duration":"374.437497ms","start":"2026-09-19T06:14:26.327362Z","end":"2026-09-19T06:14:26.701799Z","steps":["trace[1289959798] 'agreement among raft nodes before linearized reading' (duration: 373.5876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:26.701959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:26.32735Z","time spent":"374.57555ms","remote":"127.0.0.1:43286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1366,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"warn","ts":"2026-09-19T06:14:26.701993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.644467ms","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-09-19T06:14:26.702073Z","caller":"traceutil/trace.go:171","msg":"trace[205187196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5164; }","duration":"262.749259ms","start":"2026-09-19T06:14:26.439301Z","end":"2026-09-19T06:14:26.702051Z","steps":["trace[205187196] 'agreement among raft nodes before linearized reading' (duration: 262.512574ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:26.702287Z","caller":"traceutil/trace.go:171","msg":"trace[1217303376] transaction","detail":"{read_only:false; response_revision:5164; number_of_response:1; }","duration":"376.905205ms","start":"2026-09-19T06:14:26.325367Z","end":"2026-09-19T06:14:26.702272Z","steps":["trace[1217303376] 'process raft request' (duration: 375.232262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:26.702359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:26.325351Z","time spent":"376.964567ms","remote":"127.0.0.1:43288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1351,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T06:14:26.702739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.528044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-09-19T06:14:26.702798Z","caller":"traceutil/trace.go:171","msg":"trace[1100836787] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5164; }","duration":"192.614977ms","start":"2026-09-19T06:14:26.510165Z","end":"2026-09-19T06:14:26.70278Z","steps":["trace[1100836787] 'agreement among raft nodes before linearized reading' (duration: 191.537275ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:26.703042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:26.324213Z","time spent":"378.770023ms","remote":"127.0.0.1:43260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":740,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T06:14:26.826387Z","caller":"traceutil/trace.go:171","msg":"trace[469067471] linearizableReadLoop","detail":"{readStateIndex:5367; appliedIndex:5366; }","duration":"117.634508ms","start":"2026-09-19T06:14:26.708723Z","end":"2026-09-19T06:14:26.826358Z","steps":["trace[469067471] 'read index received' (duration: 112.112617ms)","trace[469067471] 'applied index is now lower than readState.Index' (duration: 5.520671ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:26.826499Z","caller":"traceutil/trace.go:171","msg":"trace[1573910840] transaction","detail":"{read_only:false; response_revision:5165; number_of_response:1; }","duration":"119.622427ms","start":"2026-09-19T06:14:26.706841Z","end":"2026-09-19T06:14:26.826464Z","steps":["trace[1573910840] 'process raft request' (duration: 113.868481ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:26.826834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.088407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-19T06:14:26.82697Z","caller":"traceutil/trace.go:171","msg":"trace[108646516] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5165; }","duration":"118.270241ms","start":"2026-09-19T06:14:26.708679Z","end":"2026-09-19T06:14:26.826949Z","steps":["trace[108646516] 'agreement among raft nodes before linearized reading' (duration: 117.817232ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:26.840158Z","caller":"traceutil/trace.go:171","msg":"trace[966654223] transaction","detail":"{read_only:false; response_revision:5166; number_of_response:1; }","duration":"130.173869ms","start":"2026-09-19T06:14:26.70996Z","end":"2026-09-19T06:14:26.840134Z","steps":["trace[966654223] 'process raft request' (duration: 129.940194ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:26.840625Z","caller":"traceutil/trace.go:171","msg":"trace[266588434] transaction","detail":"{read_only:false; response_revision:5168; number_of_response:1; }","duration":"128.649709ms","start":"2026-09-19T06:14:26.711956Z","end":"2026-09-19T06:14:26.840606Z","steps":["trace[266588434] 'process raft request' (duration: 128.117108ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:26.840765Z","caller":"traceutil/trace.go:171","msg":"trace[947303670] transaction","detail":"{read_only:false; response_revision:5167; number_of_response:1; }","duration":"130.538537ms","start":"2026-09-19T06:14:26.71019Z","end":"2026-09-19T06:14:26.840728Z","steps":["trace[947303670] 'process raft request' (duration: 129.833483ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:27.12213Z","caller":"traceutil/trace.go:171","msg":"trace[1043592966] linearizableReadLoop","detail":"{readStateIndex:5379; appliedIndex:5378; }","duration":"144.790294ms","start":"2026-09-19T06:14:26.977309Z","end":"2026-09-19T06:14:27.122099Z","steps":["trace[1043592966] 'read index received' (duration: 98.245757ms)","trace[1043592966] 'applied index is now lower than readState.Index' (duration: 46.542847ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:27.122177Z","caller":"traceutil/trace.go:171","msg":"trace[1809969221] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"150.280833ms","start":"2026-09-19T06:14:26.971859Z","end":"2026-09-19T06:14:27.12214Z","steps":["trace[1809969221] 'process raft request' (duration: 103.689547ms)","trace[1809969221] 'compare' (duration: 46.398263ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:14:27.122399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.298273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-09-19T06:14:27.122442Z","caller":"traceutil/trace.go:171","msg":"trace[967437416] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5177; }","duration":"144.382384ms","start":"2026-09-19T06:14:26.978046Z","end":"2026-09-19T06:14:27.122428Z","steps":["trace[967437416] 'agreement among raft nodes before linearized reading' (duration: 144.215491ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:27.122791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.468786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-19T06:14:27.122849Z","caller":"traceutil/trace.go:171","msg":"trace[855722077] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5177; }","duration":"145.556799ms","start":"2026-09-19T06:14:26.977277Z","end":"2026-09-19T06:14:27.122834Z","steps":["trace[855722077] 'agreement among raft nodes before linearized reading' (duration: 144.935206ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:27.387762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.946134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:14:27.387901Z","caller":"traceutil/trace.go:171","msg":"trace[2007158072] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"138.929435ms","start":"2026-09-19T06:14:27.248958Z","end":"2026-09-19T06:14:27.387887Z","steps":["trace[2007158072] 'process raft request' (duration: 138.877394ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:27.387878Z","caller":"traceutil/trace.go:171","msg":"trace[2084165677] transaction","detail":"{read_only:false; response_revision:5179; number_of_response:1; }","duration":"210.648787ms","start":"2026-09-19T06:14:27.177191Z","end":"2026-09-19T06:14:27.38784Z","steps":["trace[2084165677] 'process raft request' (duration: 77.825385ms)","trace[2084165677] 'compare' (duration: 131.797801ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:27.387981Z","caller":"traceutil/trace.go:171","msg":"trace[175008478] linearizableReadLoop","detail":"{readStateIndex:5381; appliedIndex:5380; }","duration":"205.053015ms","start":"2026-09-19T06:14:27.182895Z","end":"2026-09-19T06:14:27.387948Z","steps":["trace[175008478] 'read index received' (duration: 72.110221ms)","trace[175008478] 'applied index is now lower than readState.Index' (duration: 132.939344ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:14:27.388272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.484903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-19T06:14:27.388329Z","caller":"traceutil/trace.go:171","msg":"trace[1141371935] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5180; }","duration":"201.565145ms","start":"2026-09-19T06:14:27.186737Z","end":"2026-09-19T06:14:27.388302Z","steps":["trace[1141371935] 'agreement among raft nodes before linearized reading' (duration: 201.35124ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:27.388404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.497563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4848"} {"level":"warn","ts":"2026-09-19T06:14:27.388405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.611385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-19T06:14:27.388435Z","caller":"traceutil/trace.go:171","msg":"trace[1440645612] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5180; }","duration":"205.558874ms","start":"2026-09-19T06:14:27.182866Z","end":"2026-09-19T06:14:27.388424Z","steps":["trace[1440645612] 'agreement among raft nodes before linearized reading' (duration: 205.203877ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:27.388465Z","caller":"traceutil/trace.go:171","msg":"trace[1411223097] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5180; }","duration":"199.731327ms","start":"2026-09-19T06:14:27.188717Z","end":"2026-09-19T06:14:27.388448Z","steps":["trace[1411223097] 'agreement among raft nodes before linearized reading' (duration: 199.492892ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:27.388652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.870139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-09-19T06:14:27.388679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.116035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61381"} {"level":"info","ts":"2026-09-19T06:14:27.388714Z","caller":"traceutil/trace.go:171","msg":"trace[1822950562] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:5180; }","duration":"199.178347ms","start":"2026-09-19T06:14:27.189526Z","end":"2026-09-19T06:14:27.388705Z","steps":["trace[1822950562] 'agreement among raft nodes before linearized reading' (duration: 198.656736ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:27.388707Z","caller":"traceutil/trace.go:171","msg":"trace[42472676] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5180; }","duration":"192.943941ms","start":"2026-09-19T06:14:27.195747Z","end":"2026-09-19T06:14:27.388691Z","steps":["trace[42472676] 'agreement among raft nodes before linearized reading' (duration: 192.402059ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:27.628862Z","caller":"traceutil/trace.go:171","msg":"trace[1109671174] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"234.293852ms","start":"2026-09-19T06:14:27.394548Z","end":"2026-09-19T06:14:27.628842Z","steps":["trace[1109671174] 'process raft request' (duration: 234.10979ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:27.629096Z","caller":"traceutil/trace.go:171","msg":"trace[694805627] linearizableReadLoop","detail":"{readStateIndex:5386; appliedIndex:5383; }","duration":"153.224722ms","start":"2026-09-19T06:14:27.475861Z","end":"2026-09-19T06:14:27.629086Z","steps":["trace[694805627] 'read index received' (duration: 152.822365ms)","trace[694805627] 'applied index is now lower than readState.Index' (duration: 401.647µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:27.629158Z","caller":"traceutil/trace.go:171","msg":"trace[1689876453] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"179.075173ms","start":"2026-09-19T06:14:27.450074Z","end":"2026-09-19T06:14:27.62915Z","steps":["trace[1689876453] 'process raft request' (duration: 178.93478ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:27.6291Z","caller":"traceutil/trace.go:171","msg":"trace[182580468] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"216.047755ms","start":"2026-09-19T06:14:27.413036Z","end":"2026-09-19T06:14:27.629084Z","steps":["trace[182580468] 'process raft request' (duration: 215.75171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:27.629307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.801882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:14:27.629365Z","caller":"traceutil/trace.go:171","msg":"trace[1620951951] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:0; response_revision:5184; }","duration":"233.899895ms","start":"2026-09-19T06:14:27.395456Z","end":"2026-09-19T06:14:27.629356Z","steps":["trace[1620951951] 'agreement among raft nodes before linearized reading' (duration: 233.761822ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:27.631493Z","caller":"traceutil/trace.go:171","msg":"trace[1719006458] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"151.394455ms","start":"2026-09-19T06:14:27.48008Z","end":"2026-09-19T06:14:27.631474Z","steps":["trace[1719006458] 'process raft request' (duration: 151.328064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:27.631565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.010008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-19T06:14:27.632088Z","caller":"traceutil/trace.go:171","msg":"trace[1318811949] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5185; }","duration":"152.563619ms","start":"2026-09-19T06:14:27.47951Z","end":"2026-09-19T06:14:27.632074Z","steps":["trace[1318811949] 'agreement among raft nodes before linearized reading' (duration: 151.957067ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:39.619369Z","caller":"traceutil/trace.go:171","msg":"trace[714439191] transaction","detail":"{read_only:false; response_revision:5376; number_of_response:1; }","duration":"515.512518ms","start":"2026-09-19T06:14:39.103824Z","end":"2026-09-19T06:14:39.619337Z","steps":["trace[714439191] 'process raft request' (duration: 515.321815ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:39.619683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:39.103807Z","time spent":"515.772243ms","remote":"127.0.0.1:43476","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-09-19T06:14:39.622256Z","caller":"traceutil/trace.go:171","msg":"trace[365199355] linearizableReadLoop","detail":"{readStateIndex:5584; appliedIndex:5582; }","duration":"286.623861ms","start":"2026-09-19T06:14:39.335614Z","end":"2026-09-19T06:14:39.622238Z","steps":["trace[365199355] 'read index received' (duration: 283.632032ms)","trace[365199355] 'applied index is now lower than readState.Index' (duration: 2.990959ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:14:39.622355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.735693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:14:39.622407Z","caller":"traceutil/trace.go:171","msg":"trace[1228919812] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:5377; }","duration":"286.839265ms","start":"2026-09-19T06:14:39.335553Z","end":"2026-09-19T06:14:39.622392Z","steps":["trace[1228919812] 'agreement among raft nodes before linearized reading' (duration: 286.750773ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:39.622547Z","caller":"traceutil/trace.go:171","msg":"trace[1057400644] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"323.136111ms","start":"2026-09-19T06:14:39.299391Z","end":"2026-09-19T06:14:39.622527Z","steps":["trace[1057400644] 'process raft request' (duration: 322.767074ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:39.622735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:39.299369Z","time spent":"323.301315ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:14:40.031427Z","caller":"traceutil/trace.go:171","msg":"trace[1409041962] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"117.811615ms","start":"2026-09-19T06:14:39.913587Z","end":"2026-09-19T06:14:40.031399Z","steps":["trace[1409041962] 'process raft request' (duration: 117.505089ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:15:20.599652Z","caller":"traceutil/trace.go:171","msg":"trace[1596226927] linearizableReadLoop","detail":"{readStateIndex:5942; appliedIndex:5941; }","duration":"339.637959ms","start":"2026-09-19T06:15:20.259986Z","end":"2026-09-19T06:15:20.599624Z","steps":["trace[1596226927] 'read index received' (duration: 339.544837ms)","trace[1596226927] 'applied index is now lower than readState.Index' (duration: 91.782µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:15:20.600097Z","caller":"traceutil/trace.go:171","msg":"trace[766961992] transaction","detail":"{read_only:false; response_revision:5726; number_of_response:1; }","duration":"352.246427ms","start":"2026-09-19T06:15:20.247549Z","end":"2026-09-19T06:15:20.599795Z","steps":["trace[766961992] 'process raft request' (duration: 351.933701ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:15:20.600533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:15:20.247535Z","time spent":"352.692046ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:15:20.601167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.164509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-09-19T06:15:20.601232Z","caller":"traceutil/trace.go:171","msg":"trace[1931727344] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5726; }","duration":"341.279262ms","start":"2026-09-19T06:15:20.259936Z","end":"2026-09-19T06:15:20.601216Z","steps":["trace[1931727344] 'agreement among raft nodes before linearized reading' (duration: 339.834383ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:15:20.601353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:15:20.259922Z","time spent":"341.419864ms","remote":"127.0.0.1:43288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2207434,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-09-19T06:15:20.607275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.923128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T06:15:20.607692Z","caller":"traceutil/trace.go:171","msg":"trace[914104532] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:5729; }","duration":"213.408286ms","start":"2026-09-19T06:15:20.394267Z","end":"2026-09-19T06:15:20.607675Z","steps":["trace[914104532] 'agreement among raft nodes before linearized reading' (duration: 212.905857ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:15:20.610779Z","caller":"traceutil/trace.go:171","msg":"trace[2037253785] transaction","detail":"{read_only:false; response_revision:5727; number_of_response:1; }","duration":"119.279405ms","start":"2026-09-19T06:15:20.491455Z","end":"2026-09-19T06:15:20.610734Z","steps":["trace[2037253785] 'process raft request' (duration: 115.528032ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:15:42.891714Z","caller":"traceutil/trace.go:171","msg":"trace[353910927] transaction","detail":"{read_only:false; response_revision:5897; number_of_response:1; }","duration":"119.692727ms","start":"2026-09-19T06:15:42.771987Z","end":"2026-09-19T06:15:42.891679Z","steps":["trace[353910927] 'process raft request' (duration: 103.249456ms)","trace[353910927] 'compare' (duration: 16.32926ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:18:41.727494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.528523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:18:41.727579Z","caller":"traceutil/trace.go:171","msg":"trace[1741716505] transaction","detail":"{read_only:false; response_revision:7147; number_of_response:1; }","duration":"194.828281ms","start":"2026-09-19T06:18:41.532736Z","end":"2026-09-19T06:18:41.727565Z","steps":["trace[1741716505] 'process raft request' (duration: 39.161673ms)","trace[1741716505] 'compare' (duration: 155.371748ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:19:24.378595Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5125} {"level":"info","ts":"2026-09-19T06:19:24.453389Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5125,"took":"73.46479ms","hash":3483356913,"current-db-size-bytes":50679808,"current-db-size":"51 MB","current-db-size-in-use-bytes":19509248,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-19T06:19:24.45348Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3483356913,"revision":5125,"compact-revision":2853}