{"level":"warn","ts":"2026-05-18T18:26:59.315311Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-18T18:26:59.315388Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-05-18T18:26:59.315466Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-18T18:26:59.315481Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-05-18T18:26:59.315496Z","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-05-18T18:26:59.316104Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-05-18T18:26:59.316178Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-05-18T18:26:59.319637Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.256071ms"} {"level":"info","ts":"2026-05-18T18:26:59.325559Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-05-18T18:26:59.325697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-18T18:26:59.325741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-05-18T18:26:59.325766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-18T18:26:59.325799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-05-18T18:26:59.325855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-05-18T18:26:59.336091Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-18T18:26:59.337544Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-18T18:26:59.338634Z","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-05-18T18:26:59.340863Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-18T18:26:59.341383Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-18T18:26:59.34171Z","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-05-18T18:26:59.344009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-05-18T18:26:59.344108Z","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-05-18T18:26:59.344308Z","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-05-18T18:26:59.344778Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-05-18T18:26:59.346509Z","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-05-18T18:26:59.346701Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-18T18:26:59.34674Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-18T18:26:59.347365Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-05-18T18:26:59.347396Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-05-18T18:26:59.926613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-18T18:26:59.926711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-18T18:26:59.92678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-05-18T18:26:59.926815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-05-18T18:26:59.926841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-05-18T18:26:59.92686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-05-18T18:26:59.926889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-05-18T18:26:59.92821Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T18:26:59.929487Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-18T18:26:59.929493Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-18T18:26:59.929541Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-18T18:26:59.929804Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-18T18:26:59.92984Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-18T18:26:59.93025Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T18:26:59.930455Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T18:26:59.930562Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T18:26:59.93137Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-18T18:26:59.932623Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"warn","ts":"2026-05-18T18:27:02.626543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.840663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-18T18:27:02.626713Z","caller":"traceutil/trace.go:171","msg":"trace[280806188] transaction","detail":"{read_only:false; response_revision:126; number_of_response:1; }","duration":"194.277591ms","start":"2026-05-18T18:27:02.43241Z","end":"2026-05-18T18:27:02.626687Z","steps":["trace[280806188] 'process raft request' (duration: 79.650324ms)","trace[280806188] 'compare' (duration: 113.628509ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T18:27:12.708693Z","caller":"traceutil/trace.go:171","msg":"trace[779843138] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"277.667056ms","start":"2026-05-18T18:27:12.430998Z","end":"2026-05-18T18:27:12.708665Z","steps":["trace[779843138] 'process raft request' (duration: 277.482113ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:27:37.443302Z","caller":"traceutil/trace.go:171","msg":"trace[117939386] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"608.525179ms","start":"2026-05-18T18:27:36.834737Z","end":"2026-05-18T18:27:37.443262Z","steps":["trace[117939386] 'process raft request' (duration: 585.311476ms)","trace[117939386] 'compare' (duration: 22.976638ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T18:27:37.443784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:27:36.834698Z","time spent":"608.716972ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T18:27:37.492655Z","caller":"traceutil/trace.go:171","msg":"trace[346290577] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"299.22213ms","start":"2026-05-18T18:27:37.193369Z","end":"2026-05-18T18:27:37.492591Z","steps":["trace[346290577] 'process raft request' (duration: 298.578879ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:29:17.314637Z","caller":"traceutil/trace.go:171","msg":"trace[245343121] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"188.09638ms","start":"2026-05-18T18:29:17.126517Z","end":"2026-05-18T18:29:17.314614Z","steps":["trace[245343121] 'read index received' (duration: 187.897476ms)","trace[245343121] 'applied index is now lower than readState.Index' (duration: 198.024µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T18:29:17.314688Z","caller":"traceutil/trace.go:171","msg":"trace[1949020814] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"227.157493ms","start":"2026-05-18T18:29:17.087513Z","end":"2026-05-18T18:29:17.31467Z","steps":["trace[1949020814] 'process raft request' (duration: 226.96236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:29:17.314812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.288183ms","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-05-18T18:29:17.314845Z","caller":"traceutil/trace.go:171","msg":"trace[1544191333] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1842; }","duration":"188.378915ms","start":"2026-05-18T18:29:17.12646Z","end":"2026-05-18T18:29:17.314839Z","steps":["trace[1544191333] 'agreement among raft nodes before linearized reading' (duration: 188.261123ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:29:17.45648Z","caller":"traceutil/trace.go:171","msg":"trace[1471947008] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"109.026533ms","start":"2026-05-18T18:29:17.347428Z","end":"2026-05-18T18:29:17.456454Z","steps":["trace[1471947008] 'process raft request' (duration: 108.85885ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:29:17.481977Z","caller":"traceutil/trace.go:171","msg":"trace[205805779] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"106.326652ms","start":"2026-05-18T18:29:17.375605Z","end":"2026-05-18T18:29:17.481931Z","steps":["trace[205805779] 'process raft request' (duration: 106.076448ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:29:31.173746Z","caller":"traceutil/trace.go:171","msg":"trace[995795939] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"228.564811ms","start":"2026-05-18T18:29:30.94516Z","end":"2026-05-18T18:29:31.173725Z","steps":["trace[995795939] 'process raft request' (duration: 228.443428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:30:01.633386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.197585ms","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-05-18T18:30:01.633471Z","caller":"traceutil/trace.go:171","msg":"trace[620213523] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2139; }","duration":"101.340007ms","start":"2026-05-18T18:30:01.532113Z","end":"2026-05-18T18:30:01.633453Z","steps":["trace[620213523] 'range keys from in-memory index tree' (duration: 101.018251ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:30:07.445526Z","caller":"traceutil/trace.go:171","msg":"trace[1341838043] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"148.584737ms","start":"2026-05-18T18:30:07.296917Z","end":"2026-05-18T18:30:07.445501Z","steps":["trace[1341838043] 'process raft request' (duration: 148.426094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:30:08.035731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.905863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T18:30:08.035979Z","caller":"traceutil/trace.go:171","msg":"trace[486128763] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"343.219443ms","start":"2026-05-18T18:30:07.692726Z","end":"2026-05-18T18:30:08.035946Z","steps":["trace[486128763] 'process raft request' (duration: 129.013693ms)","trace[486128763] 'compare' (duration: 213.782431ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T18:30:08.036017Z","caller":"traceutil/trace.go:171","msg":"trace[1419625273] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"129.970911ms","start":"2026-05-18T18:30:07.906024Z","end":"2026-05-18T18:30:08.035995Z","steps":["trace[1419625273] 'process raft request' (duration: 129.90668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:30:08.036133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:30:07.692706Z","time spent":"343.347206ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T18:30:08.036203Z","caller":"traceutil/trace.go:171","msg":"trace[949528037] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2252; }","duration":"339.78332ms","start":"2026-05-18T18:30:07.696357Z","end":"2026-05-18T18:30:08.036141Z","steps":["trace[949528037] 'read index received' (duration: 125.362356ms)","trace[949528037] 'applied index is now lower than readState.Index' (duration: 214.418294ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T18:30:08.036372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.001303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T18:30:08.036406Z","caller":"traceutil/trace.go:171","msg":"trace[435182874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2173; }","duration":"340.074324ms","start":"2026-05-18T18:30:07.696323Z","end":"2026-05-18T18:30:08.036397Z","steps":["trace[435182874] 'agreement among raft nodes before linearized reading' (duration: 339.991373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:30:08.036434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:30:07.696308Z","time spent":"340.119416ms","remote":"127.0.0.1:41452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-18T18:30:08.036436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.295289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T18:30:08.036498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.020698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T18:30:08.036563Z","caller":"traceutil/trace.go:171","msg":"trace[887450014] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2173; }","duration":"118.120219ms","start":"2026-05-18T18:30:07.918424Z","end":"2026-05-18T18:30:08.036544Z","steps":["trace[887450014] 'agreement among raft nodes before linearized reading' (duration: 118.007317ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:30:08.036561Z","caller":"traceutil/trace.go:171","msg":"trace[750745788] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2173; }","duration":"215.430142ms","start":"2026-05-18T18:30:07.821105Z","end":"2026-05-18T18:30:08.036535Z","steps":["trace[750745788] 'agreement among raft nodes before linearized reading' (duration: 215.249728ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:30:13.431467Z","caller":"traceutil/trace.go:171","msg":"trace[1105390994] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"152.083305ms","start":"2026-05-18T18:30:13.279361Z","end":"2026-05-18T18:30:13.431445Z","steps":["trace[1105390994] 'process raft request' (duration: 151.933452ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:31:04.460155Z","caller":"traceutil/trace.go:171","msg":"trace[196635566] transaction","detail":"{read_only:false; response_revision:2649; number_of_response:1; }","duration":"128.88784ms","start":"2026-05-18T18:31:04.331224Z","end":"2026-05-18T18:31:04.460112Z","steps":["trace[196635566] 'process raft request' (duration: 128.704056ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:35:32.328221Z","caller":"traceutil/trace.go:171","msg":"trace[1026914784] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"120.299289ms","start":"2026-05-18T18:35:32.207898Z","end":"2026-05-18T18:35:32.328197Z","steps":["trace[1026914784] 'process raft request' (duration: 120.166637ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:35:32.32841Z","caller":"traceutil/trace.go:171","msg":"trace[1614528527] linearizableReadLoop","detail":"{readStateIndex:5013; appliedIndex:5013; }","duration":"117.450656ms","start":"2026-05-18T18:35:32.210952Z","end":"2026-05-18T18:35:32.328402Z","steps":["trace[1614528527] 'read index received' (duration: 117.447356ms)","trace[1614528527] 'applied index is now lower than readState.Index' (duration: 2.71µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T18:35:32.328521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.555288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-faf729dc-61a1-4629-9a78-76f9f3b408e1\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-05-18T18:35:32.328544Z","caller":"traceutil/trace.go:171","msg":"trace[523493768] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-faf729dc-61a1-4629-9a78-76f9f3b408e1; range_end:; response_count:1; response_revision:4832; }","duration":"117.64833ms","start":"2026-05-18T18:35:32.210887Z","end":"2026-05-18T18:35:32.328535Z","steps":["trace[523493768] 'agreement among raft nodes before linearized reading' (duration: 117.544048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:35:35.348078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.480054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T18:35:35.348232Z","caller":"traceutil/trace.go:171","msg":"trace[140048599] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"280.186529ms","start":"2026-05-18T18:35:35.068025Z","end":"2026-05-18T18:35:35.348211Z","steps":["trace[140048599] 'process raft request' (duration: 113.488521ms)","trace[140048599] 'compare' (duration: 166.333701ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T18:35:35.353579Z","caller":"traceutil/trace.go:171","msg":"trace[2098063346] linearizableReadLoop","detail":"{readStateIndex:5084; appliedIndex:5083; }","duration":"111.77446ms","start":"2026-05-18T18:35:35.241777Z","end":"2026-05-18T18:35:35.353552Z","steps":["trace[2098063346] 'read index received' (duration: 111.649558ms)","trace[2098063346] 'applied index is now lower than readState.Index' (duration: 123.732µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T18:35:35.35373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.975603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0f319909-440a-4487-96ab-cfdd27494723\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T18:35:35.353785Z","caller":"traceutil/trace.go:171","msg":"trace[135579959] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0f319909-440a-4487-96ab-cfdd27494723; range_end:; response_count:0; response_revision:4899; }","duration":"112.099366ms","start":"2026-05-18T18:35:35.241672Z","end":"2026-05-18T18:35:35.353771Z","steps":["trace[135579959] 'agreement among raft nodes before linearized reading' (duration: 111.979204ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:35:35.353624Z","caller":"traceutil/trace.go:171","msg":"trace[1579333736] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"186.334035ms","start":"2026-05-18T18:35:35.167271Z","end":"2026-05-18T18:35:35.353605Z","steps":["trace[1579333736] 'process raft request' (duration: 186.130111ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:35:35.354495Z","caller":"traceutil/trace.go:171","msg":"trace[1530658189] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"104.687337ms","start":"2026-05-18T18:35:35.249795Z","end":"2026-05-18T18:35:35.354482Z","steps":["trace[1530658189] 'process raft request' (duration: 104.529844ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:35:38.086736Z","caller":"traceutil/trace.go:171","msg":"trace[265380579] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"179.572298ms","start":"2026-05-18T18:35:37.907138Z","end":"2026-05-18T18:35:38.086711Z","steps":["trace[265380579] 'process raft request' (duration: 179.421594ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:35:38.107831Z","caller":"traceutil/trace.go:171","msg":"trace[539703295] linearizableReadLoop","detail":"{readStateIndex:5226; appliedIndex:5225; }","duration":"196.732168ms","start":"2026-05-18T18:35:37.911067Z","end":"2026-05-18T18:35:38.107799Z","steps":["trace[539703295] 'read index received' (duration: 176.109932ms)","trace[539703295] 'applied index is now lower than readState.Index' (duration: 20.620866ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T18:35:38.107859Z","caller":"traceutil/trace.go:171","msg":"trace[1661464324] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"197.548683ms","start":"2026-05-18T18:35:37.910278Z","end":"2026-05-18T18:35:38.107826Z","steps":["trace[1661464324] 'process raft request' (duration: 197.333399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:35:38.108406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.310498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:720924"} {"level":"info","ts":"2026-05-18T18:35:38.108525Z","caller":"traceutil/trace.go:171","msg":"trace[1309974716] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5038; }","duration":"197.459471ms","start":"2026-05-18T18:35:37.911032Z","end":"2026-05-18T18:35:38.108492Z","steps":["trace[1309974716] 'agreement among raft nodes before linearized reading' (duration: 196.85274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:35:42.540371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.087717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T18:35:42.540661Z","caller":"traceutil/trace.go:171","msg":"trace[1943287800] linearizableReadLoop","detail":"{readStateIndex:5320; appliedIndex:5319; }","duration":"386.175359ms","start":"2026-05-18T18:35:42.154455Z","end":"2026-05-18T18:35:42.54063Z","steps":["trace[1943287800] 'read index received' (duration: 29.594522ms)","trace[1943287800] 'applied index is now lower than readState.Index' (duration: 356.579107ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T18:35:42.540729Z","caller":"traceutil/trace.go:171","msg":"trace[502805196] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"411.318249ms","start":"2026-05-18T18:35:42.129328Z","end":"2026-05-18T18:35:42.540646Z","steps":["trace[502805196] 'process raft request' (duration: 54.790494ms)","trace[502805196] 'compare' (duration: 355.880573ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T18:35:42.540996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:35:42.129301Z","time spent":"411.571615ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T18:35:42.541072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.573677ms","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-05-18T18:35:42.541225Z","caller":"traceutil/trace.go:171","msg":"trace[519632615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5128; }","duration":"386.759921ms","start":"2026-05-18T18:35:42.154427Z","end":"2026-05-18T18:35:42.541187Z","steps":["trace[519632615] 'agreement among raft nodes before linearized reading' (duration: 386.282471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:35:42.541366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:35:42.154414Z","time spent":"386.865893ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-18T18:35:42.669311Z","caller":"traceutil/trace.go:171","msg":"trace[9458298] linearizableReadLoop","detail":"{readStateIndex:5321; appliedIndex:5320; }","duration":"128.558233ms","start":"2026-05-18T18:35:42.540723Z","end":"2026-05-18T18:35:42.669281Z","steps":["trace[9458298] 'read index received' (duration: 126.172089ms)","trace[9458298] 'applied index is now lower than readState.Index' (duration: 2.385004ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T18:35:42.669872Z","caller":"traceutil/trace.go:171","msg":"trace[2098136402] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"365.736949ms","start":"2026-05-18T18:35:42.304119Z","end":"2026-05-18T18:35:42.669856Z","steps":["trace[2098136402] 'process raft request' (duration: 362.536068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:35:42.67012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:35:42.304102Z","time spent":"365.862841ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T18:35:42.670306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.408911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16232"} {"level":"info","ts":"2026-05-18T18:35:42.67044Z","caller":"traceutil/trace.go:171","msg":"trace[994783291] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5129; }","duration":"335.587644ms","start":"2026-05-18T18:35:42.334779Z","end":"2026-05-18T18:35:42.670366Z","steps":["trace[994783291] 'agreement among raft nodes before linearized reading' (duration: 335.246798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:35:42.670502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:35:42.334747Z","time spent":"335.740677ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16256,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-05-18T18:35:42.670745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.46322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-18T18:35:42.670767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.924802ms","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-05-18T18:35:42.670838Z","caller":"traceutil/trace.go:171","msg":"trace[587264515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5129; }","duration":"244.606262ms","start":"2026-05-18T18:35:42.426213Z","end":"2026-05-18T18:35:42.670819Z","steps":["trace[587264515] 'agreement among raft nodes before linearized reading' (duration: 244.300087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:35:42.670847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.631028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T18:35:42.670851Z","caller":"traceutil/trace.go:171","msg":"trace[779881227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5129; }","duration":"481.036664ms","start":"2026-05-18T18:35:42.189793Z","end":"2026-05-18T18:35:42.670829Z","steps":["trace[779881227] 'agreement among raft nodes before linearized reading' (duration: 480.82301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:35:42.670834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.258907ms","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-05-18T18:35:42.670903Z","caller":"traceutil/trace.go:171","msg":"trace[2102335028] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:5129; }","duration":"295.75112ms","start":"2026-05-18T18:35:42.375135Z","end":"2026-05-18T18:35:42.670886Z","steps":["trace[2102335028] 'agreement among raft nodes before linearized reading' (duration: 295.626517ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:35:42.670927Z","caller":"traceutil/trace.go:171","msg":"trace[580783538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5129; }","duration":"451.401559ms","start":"2026-05-18T18:35:42.219506Z","end":"2026-05-18T18:35:42.670908Z","steps":["trace[580783538] 'agreement among raft nodes before linearized reading' (duration: 451.079593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:35:42.670974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:35:42.219493Z","time spent":"451.472021ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-18T18:35:42.671022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.215478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5c5f889bd4-v2sf2.18b0bcb5aef176fd\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-05-18T18:35:42.671057Z","caller":"traceutil/trace.go:171","msg":"trace[228981509] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5c5f889bd4-v2sf2.18b0bcb5aef176fd; range_end:; response_count:1; response_revision:5129; }","duration":"335.268958ms","start":"2026-05-18T18:35:42.335774Z","end":"2026-05-18T18:35:42.671043Z","steps":["trace[228981509] 'agreement among raft nodes before linearized reading' (duration: 335.179697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:35:42.671094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:35:42.33576Z","time spent":"335.327299ms","remote":"127.0.0.1:41538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":920,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5c5f889bd4-v2sf2.18b0bcb5aef176fd\" "} {"level":"warn","ts":"2026-05-18T18:35:42.670911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:35:42.189784Z","time spent":"481.118245ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-18T18:35:42.671286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.522961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17455"} {"level":"warn","ts":"2026-05-18T18:35:42.67141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.718626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-18T18:35:42.671507Z","caller":"traceutil/trace.go:171","msg":"trace[1241769687] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5129; }","duration":"274.855699ms","start":"2026-05-18T18:35:42.396626Z","end":"2026-05-18T18:35:42.671481Z","steps":["trace[1241769687] 'agreement among raft nodes before linearized reading' (duration: 273.796769ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:35:42.671449Z","caller":"traceutil/trace.go:171","msg":"trace[1347848527] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5129; }","duration":"375.666933ms","start":"2026-05-18T18:35:42.295721Z","end":"2026-05-18T18:35:42.671388Z","steps":["trace[1347848527] 'agreement among raft nodes before linearized reading' (duration: 374.9833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:35:42.671744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:35:42.29571Z","time spent":"376.01373ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":17479,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-18T18:35:42.673278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.733774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/ipmi-exporter\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T18:35:42.673399Z","caller":"traceutil/trace.go:171","msg":"trace[1282320211] range","detail":"{range_begin:/registry/configmaps/monitoring/ipmi-exporter; range_end:; response_count:0; response_revision:5130; }","duration":"115.892047ms","start":"2026-05-18T18:35:42.557491Z","end":"2026-05-18T18:35:42.673383Z","steps":["trace[1282320211] 'agreement among raft nodes before linearized reading' (duration: 115.717264ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:35:42.673337Z","caller":"traceutil/trace.go:171","msg":"trace[1967978755] transaction","detail":"{read_only:false; response_revision:5130; number_of_response:1; }","duration":"126.072747ms","start":"2026-05-18T18:35:42.547241Z","end":"2026-05-18T18:35:42.673314Z","steps":["trace[1967978755] 'process raft request' (duration: 125.841393ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:35:42.778711Z","caller":"traceutil/trace.go:171","msg":"trace[1697855856] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"102.275621ms","start":"2026-05-18T18:35:42.676399Z","end":"2026-05-18T18:35:42.778674Z","steps":["trace[1697855856] 'process raft request' (duration: 101.971976ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:35:42.778875Z","caller":"traceutil/trace.go:171","msg":"trace[818209883] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"102.384433ms","start":"2026-05-18T18:35:42.676444Z","end":"2026-05-18T18:35:42.778828Z","steps":["trace[818209883] 'process raft request' (duration: 102.090808ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:35:42.778773Z","caller":"traceutil/trace.go:171","msg":"trace[632029879] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"102.060928ms","start":"2026-05-18T18:35:42.676677Z","end":"2026-05-18T18:35:42.778738Z","steps":["trace[632029879] 'process raft request' (duration: 101.900485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:35:42.778955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.323866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17455"} {"level":"info","ts":"2026-05-18T18:35:42.779412Z","caller":"traceutil/trace.go:171","msg":"trace[464809144] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5136; }","duration":"100.811245ms","start":"2026-05-18T18:35:42.67858Z","end":"2026-05-18T18:35:42.779391Z","steps":["trace[464809144] 'agreement among raft nodes before linearized reading' (duration: 100.189802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:35:50.895038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.820202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-18T18:35:50.895217Z","caller":"traceutil/trace.go:171","msg":"trace[20475682] linearizableReadLoop","detail":"{readStateIndex:5466; appliedIndex:5465; }","duration":"150.82757ms","start":"2026-05-18T18:35:50.744364Z","end":"2026-05-18T18:35:50.895192Z","steps":["trace[20475682] 'read index received' (duration: 16.768643ms)","trace[20475682] 'applied index is now lower than readState.Index' (duration: 134.057497ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T18:35:50.89524Z","caller":"traceutil/trace.go:171","msg":"trace[227278667] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"175.501371ms","start":"2026-05-18T18:35:50.719698Z","end":"2026-05-18T18:35:50.895199Z","steps":["trace[227278667] 'process raft request' (duration: 41.423004ms)","trace[227278667] 'compare' (duration: 133.581838ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T18:35:50.89548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.089875ms","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-05-18T18:35:50.895549Z","caller":"traceutil/trace.go:171","msg":"trace[26797424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5270; }","duration":"151.199917ms","start":"2026-05-18T18:35:50.744333Z","end":"2026-05-18T18:35:50.895532Z","steps":["trace[26797424] 'agreement among raft nodes before linearized reading' (duration: 150.946423ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:36:00.286056Z","caller":"traceutil/trace.go:171","msg":"trace[161279903] transaction","detail":"{read_only:false; response_revision:5431; number_of_response:1; }","duration":"106.599553ms","start":"2026-05-18T18:36:00.179427Z","end":"2026-05-18T18:36:00.286026Z","steps":["trace[161279903] 'process raft request' (duration: 106.44095ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:36:00.810391Z","caller":"traceutil/trace.go:171","msg":"trace[14405652] linearizableReadLoop","detail":"{readStateIndex:5630; appliedIndex:5629; }","duration":"112.426042ms","start":"2026-05-18T18:36:00.69792Z","end":"2026-05-18T18:36:00.810346Z","steps":["trace[14405652] 'read index received' (duration: 111.958823ms)","trace[14405652] 'applied index is now lower than readState.Index' (duration: 466.379µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T18:36:00.81052Z","caller":"traceutil/trace.go:171","msg":"trace[418447196] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"271.498636ms","start":"2026-05-18T18:36:00.538977Z","end":"2026-05-18T18:36:00.810475Z","steps":["trace[418447196] 'process raft request' (duration: 270.954216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:36:00.810612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.679297ms","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-05-18T18:36:00.810649Z","caller":"traceutil/trace.go:171","msg":"trace[1452817986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5432; }","duration":"112.774648ms","start":"2026-05-18T18:36:00.697864Z","end":"2026-05-18T18:36:00.810638Z","steps":["trace[1452817986] 'agreement among raft nodes before linearized reading' (duration: 112.600345ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:36:02.837268Z","caller":"traceutil/trace.go:171","msg":"trace[384694804] transaction","detail":"{read_only:false; response_revision:5457; number_of_response:1; }","duration":"137.693634ms","start":"2026-05-18T18:36:02.699527Z","end":"2026-05-18T18:36:02.837221Z","steps":["trace[384694804] 'process raft request' (duration: 137.409419ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:36:59.959099Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2974} {"level":"info","ts":"2026-05-18T18:37:00.029551Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2974,"took":"68.904904ms","hash":1734805711,"current-db-size-bytes":57122816,"current-db-size":"57 MB","current-db-size-in-use-bytes":33615872,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-18T18:37:00.029608Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1734805711,"revision":2974,"compact-revision":-1} {"level":"info","ts":"2026-05-18T18:41:59.968102Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6035} {"level":"info","ts":"2026-05-18T18:42:00.063711Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6035,"took":"94.073956ms","hash":324386363,"current-db-size-bytes":57122816,"current-db-size":"57 MB","current-db-size-in-use-bytes":21618688,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-18T18:42:00.063786Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":324386363,"revision":6035,"compact-revision":2974} {"level":"warn","ts":"2026-05-18T18:42:21.49863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.841315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-trmdv\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-18T18:42:21.498928Z","caller":"traceutil/trace.go:171","msg":"trace[1206009181] range","detail":"{range_begin:/registry/pods/openstack/keepalived-trmdv; range_end:; response_count:1; response_revision:8374; }","duration":"260.209591ms","start":"2026-05-18T18:42:21.238702Z","end":"2026-05-18T18:42:21.498912Z","steps":["trace[1206009181] 'range keys from in-memory index tree' (duration: 259.631261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:42:21.498689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.739588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T18:42:21.499027Z","caller":"traceutil/trace.go:171","msg":"trace[1045395986] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:8374; }","duration":"102.138546ms","start":"2026-05-18T18:42:21.396866Z","end":"2026-05-18T18:42:21.499004Z","steps":["trace[1045395986] 'count revisions from in-memory index tree' (duration: 101.622306ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:42:22.971471Z","caller":"traceutil/trace.go:171","msg":"trace[775836333] transaction","detail":"{read_only:false; response_revision:8376; number_of_response:1; }","duration":"120.228616ms","start":"2026-05-18T18:42:22.851217Z","end":"2026-05-18T18:42:22.971445Z","steps":["trace[775836333] 'process raft request' (duration: 120.052422ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:42:22.987967Z","caller":"traceutil/trace.go:171","msg":"trace[1577856160] transaction","detail":"{read_only:false; response_revision:8377; number_of_response:1; }","duration":"118.220407ms","start":"2026-05-18T18:42:22.869729Z","end":"2026-05-18T18:42:22.987949Z","steps":["trace[1577856160] 'process raft request' (duration: 118.042704ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:42:23.127831Z","caller":"traceutil/trace.go:171","msg":"trace[867717524] transaction","detail":"{read_only:false; response_revision:8379; number_of_response:1; }","duration":"134.231648ms","start":"2026-05-18T18:42:22.99357Z","end":"2026-05-18T18:42:23.127802Z","steps":["trace[867717524] 'process raft request' (duration: 54.39697ms)","trace[867717524] 'compare' (duration: 79.37863ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T18:42:23.12802Z","caller":"traceutil/trace.go:171","msg":"trace[987459256] transaction","detail":"{read_only:false; response_revision:8380; number_of_response:1; }","duration":"110.104275ms","start":"2026-05-18T18:42:23.017906Z","end":"2026-05-18T18:42:23.12801Z","steps":["trace[987459256] 'process raft request' (duration: 109.747118ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:42:51.617395Z","caller":"traceutil/trace.go:171","msg":"trace[1700209370] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"258.95836ms","start":"2026-05-18T18:42:51.358404Z","end":"2026-05-18T18:42:51.617362Z","steps":["trace[1700209370] 'process raft request' (duration: 258.759927ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:42:51.61842Z","caller":"traceutil/trace.go:171","msg":"trace[135741702] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"259.935579ms","start":"2026-05-18T18:42:51.35845Z","end":"2026-05-18T18:42:51.618385Z","steps":["trace[135741702] 'process raft request' (duration: 259.668224ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:42:51.618687Z","caller":"traceutil/trace.go:171","msg":"trace[1436258590] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"255.886182ms","start":"2026-05-18T18:42:51.362753Z","end":"2026-05-18T18:42:51.618639Z","steps":["trace[1436258590] 'process raft request' (duration: 255.573796ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:42:55.213902Z","caller":"traceutil/trace.go:171","msg":"trace[930775844] linearizableReadLoop","detail":"{readStateIndex:8909; appliedIndex:8908; }","duration":"106.746933ms","start":"2026-05-18T18:42:55.107122Z","end":"2026-05-18T18:42:55.213869Z","steps":["trace[930775844] 'read index received' (duration: 44.346342ms)","trace[930775844] 'applied index is now lower than readState.Index' (duration: 62.398881ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T18:42:55.214234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.09701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T18:42:55.214362Z","caller":"traceutil/trace.go:171","msg":"trace[1525670380] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:8607; }","duration":"107.279054ms","start":"2026-05-18T18:42:55.107069Z","end":"2026-05-18T18:42:55.214348Z","steps":["trace[1525670380] 'agreement among raft nodes before linearized reading' (duration: 107.04061ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:42:55.214367Z","caller":"traceutil/trace.go:171","msg":"trace[1636238943] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"124.578689ms","start":"2026-05-18T18:42:55.089758Z","end":"2026-05-18T18:42:55.214336Z","steps":["trace[1636238943] 'process raft request' (duration: 79.987802ms)","trace[1636238943] 'compare' (duration: 43.956904ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T18:43:05.318346Z","caller":"traceutil/trace.go:171","msg":"trace[177808571] transaction","detail":"{read_only:false; response_revision:8700; number_of_response:1; }","duration":"168.211236ms","start":"2026-05-18T18:43:05.150121Z","end":"2026-05-18T18:43:05.318332Z","steps":["trace[177808571] 'process raft request' (duration: 168.090734ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:43:05.325653Z","caller":"traceutil/trace.go:171","msg":"trace[446517305] transaction","detail":"{read_only:false; response_revision:8701; number_of_response:1; }","duration":"136.019152ms","start":"2026-05-18T18:43:05.18959Z","end":"2026-05-18T18:43:05.325609Z","steps":["trace[446517305] 'process raft request' (duration: 135.851509ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:43:06.312762Z","caller":"traceutil/trace.go:171","msg":"trace[97838173] linearizableReadLoop","detail":"{readStateIndex:9020; appliedIndex:9019; }","duration":"160.747677ms","start":"2026-05-18T18:43:06.151987Z","end":"2026-05-18T18:43:06.312735Z","steps":["trace[97838173] 'read index received' (duration: 160.528093ms)","trace[97838173] 'applied index is now lower than readState.Index' (duration: 218.034µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T18:43:06.312854Z","caller":"traceutil/trace.go:171","msg":"trace[1647360627] transaction","detail":"{read_only:false; response_revision:8713; number_of_response:1; }","duration":"292.185763ms","start":"2026-05-18T18:43:06.02063Z","end":"2026-05-18T18:43:06.312816Z","steps":["trace[1647360627] 'process raft request' (duration: 291.828667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:43:06.312976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.963672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-zmnks\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-05-18T18:43:06.31302Z","caller":"traceutil/trace.go:171","msg":"trace[270475386] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-zmnks; range_end:; response_count:1; response_revision:8713; }","duration":"161.076494ms","start":"2026-05-18T18:43:06.151931Z","end":"2026-05-18T18:43:06.313008Z","steps":["trace[270475386] 'agreement among raft nodes before linearized reading' (duration: 160.91279ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:43:06.355577Z","caller":"traceutil/trace.go:171","msg":"trace[30007694] transaction","detail":"{read_only:false; response_revision:8714; number_of_response:1; }","duration":"203.459609ms","start":"2026-05-18T18:43:06.152086Z","end":"2026-05-18T18:43:06.355545Z","steps":["trace[30007694] 'process raft request' (duration: 203.319266ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:43:19.932303Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023be.wal"} {"level":"info","ts":"2026-05-18T18:44:51.705252Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-18T18:44:51.711799Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-18T18:44:51.711959Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-18T18:46:59.978996Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8123} {"level":"info","ts":"2026-05-18T18:47:00.041891Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8123,"took":"59.820606ms","hash":1543812900,"current-db-size-bytes":57122816,"current-db-size":"57 MB","current-db-size-in-use-bytes":28008448,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-18T18:47:00.041961Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1543812900,"revision":8123,"compact-revision":6035} {"level":"info","ts":"2026-05-18T18:48:35.703274Z","caller":"traceutil/trace.go:171","msg":"trace[1886126053] transaction","detail":"{read_only:false; response_revision:11092; number_of_response:1; }","duration":"109.498681ms","start":"2026-05-18T18:48:35.593722Z","end":"2026-05-18T18:48:35.70322Z","steps":["trace[1886126053] 'process raft request' (duration: 97.410433ms)","trace[1886126053] 'compare' (duration: 11.693671ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T18:49:42.743745Z","caller":"traceutil/trace.go:171","msg":"trace[1224221363] linearizableReadLoop","detail":"{readStateIndex:12189; appliedIndex:12188; }","duration":"167.050675ms","start":"2026-05-18T18:49:42.576654Z","end":"2026-05-18T18:49:42.743705Z","steps":["trace[1224221363] 'read index received' (duration: 166.726069ms)","trace[1224221363] 'applied index is now lower than readState.Index' (duration: 323.376µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T18:49:42.74384Z","caller":"traceutil/trace.go:171","msg":"trace[250692317] transaction","detail":"{read_only:false; response_revision:11788; number_of_response:1; }","duration":"185.655675ms","start":"2026-05-18T18:49:42.558135Z","end":"2026-05-18T18:49:42.743791Z","steps":["trace[250692317] 'process raft request' (duration: 185.342959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:49:42.744177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.487434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-18T18:49:42.744307Z","caller":"traceutil/trace.go:171","msg":"trace[573778578] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11788; }","duration":"167.682737ms","start":"2026-05-18T18:49:42.576593Z","end":"2026-05-18T18:49:42.744276Z","steps":["trace[573778578] 'agreement among raft nodes before linearized reading' (duration: 167.29823ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:49:59.89591Z","caller":"traceutil/trace.go:171","msg":"trace[1811529708] transaction","detail":"{read_only:false; response_revision:11883; number_of_response:1; }","duration":"115.199109ms","start":"2026-05-18T18:49:59.780676Z","end":"2026-05-18T18:49:59.895875Z","steps":["trace[1811529708] 'process raft request' (duration: 114.963845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:50:00.33268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.449438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T18:50:00.33288Z","caller":"traceutil/trace.go:171","msg":"trace[247416283] linearizableReadLoop","detail":"{readStateIndex:12292; appliedIndex:12291; }","duration":"201.591025ms","start":"2026-05-18T18:50:00.131259Z","end":"2026-05-18T18:50:00.33285Z","steps":["trace[247416283] 'read index received' (duration: 3.686179ms)","trace[247416283] 'applied index is now lower than readState.Index' (duration: 197.897026ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T18:50:00.333096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.829659ms","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-05-18T18:50:00.333148Z","caller":"traceutil/trace.go:171","msg":"trace[519464927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11887; }","duration":"201.919191ms","start":"2026-05-18T18:50:00.131212Z","end":"2026-05-18T18:50:00.333131Z","steps":["trace[519464927] 'agreement among raft nodes before linearized reading' (duration: 201.709517ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:50:00.333122Z","caller":"traceutil/trace.go:171","msg":"trace[660016432] transaction","detail":"{read_only:false; response_revision:11887; number_of_response:1; }","duration":"263.915289ms","start":"2026-05-18T18:50:00.069122Z","end":"2026-05-18T18:50:00.333037Z","steps":["trace[660016432] 'process raft request' (duration: 65.917361ms)","trace[660016432] 'compare' (duration: 197.285304ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T18:51:59.987434Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10489} {"level":"info","ts":"2026-05-18T18:52:00.076029Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10489,"took":"86.070287ms","hash":862875971,"current-db-size-bytes":57122816,"current-db-size":"57 MB","current-db-size-in-use-bytes":26124288,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-18T18:52:00.076113Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":862875971,"revision":10489,"compact-revision":8123} {"level":"info","ts":"2026-05-18T18:56:39.197494Z","caller":"traceutil/trace.go:171","msg":"trace[1527803585] transaction","detail":"{read_only:false; response_revision:14549; number_of_response:1; }","duration":"120.686982ms","start":"2026-05-18T18:56:39.076775Z","end":"2026-05-18T18:56:39.197462Z","steps":["trace[1527803585] 'process raft request' (duration: 120.468998ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:56:39.212584Z","caller":"traceutil/trace.go:171","msg":"trace[616565116] transaction","detail":"{read_only:false; response_revision:14550; number_of_response:1; }","duration":"114.866452ms","start":"2026-05-18T18:56:39.097681Z","end":"2026-05-18T18:56:39.212548Z","steps":["trace[616565116] 'process raft request' (duration: 114.653368ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:56:59.995956Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12701} {"level":"info","ts":"2026-05-18T18:57:00.07677Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12701,"took":"77.016897ms","hash":2292084763,"current-db-size-bytes":57122816,"current-db-size":"57 MB","current-db-size-in-use-bytes":28372992,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-18T18:57:00.076847Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2292084763,"revision":12701,"compact-revision":10489} {"level":"warn","ts":"2026-05-18T18:57:04.558829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.583566ms","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-05-18T18:57:04.558908Z","caller":"traceutil/trace.go:171","msg":"trace[1027417840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14873; }","duration":"296.74958ms","start":"2026-05-18T18:57:04.262143Z","end":"2026-05-18T18:57:04.558893Z","steps":["trace[1027417840] 'range keys from in-memory index tree' (duration: 296.391202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:57:45.777117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.386369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T18:57:45.777241Z","caller":"traceutil/trace.go:171","msg":"trace[711109428] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:15415; }","duration":"136.590793ms","start":"2026-05-18T18:57:45.640624Z","end":"2026-05-18T18:57:45.777214Z","steps":["trace[711109428] 'count revisions from in-memory index tree' (duration: 136.194664ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:57:58.296413Z","caller":"traceutil/trace.go:171","msg":"trace[350685857] transaction","detail":"{read_only:false; response_revision:15513; number_of_response:1; }","duration":"100.341647ms","start":"2026-05-18T18:57:58.196046Z","end":"2026-05-18T18:57:58.296387Z","steps":["trace[350685857] 'process raft request' (duration: 99.932629ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:58:20.503089Z","caller":"traceutil/trace.go:171","msg":"trace[786343] transaction","detail":"{read_only:false; response_revision:15628; number_of_response:1; }","duration":"100.546801ms","start":"2026-05-18T18:58:20.402513Z","end":"2026-05-18T18:58:20.50306Z","steps":["trace[786343] 'process raft request' (duration: 100.368137ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:58:21.414277Z","caller":"traceutil/trace.go:171","msg":"trace[441525237] transaction","detail":"{read_only:false; response_revision:15634; number_of_response:1; }","duration":"193.972247ms","start":"2026-05-18T18:58:21.22024Z","end":"2026-05-18T18:58:21.414212Z","steps":["trace[441525237] 'process raft request' (duration: 193.754542ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T18:58:21.625474Z","caller":"traceutil/trace.go:171","msg":"trace[1451017291] transaction","detail":"{read_only:false; response_revision:15635; number_of_response:1; }","duration":"404.819943ms","start":"2026-05-18T18:58:21.220616Z","end":"2026-05-18T18:58:21.625436Z","steps":["trace[1451017291] 'process raft request' (duration: 315.927492ms)","trace[1451017291] 'compare' (duration: 88.569525ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T18:58:21.62552Z","caller":"traceutil/trace.go:171","msg":"trace[206566861] linearizableReadLoop","detail":"{readStateIndex:16171; appliedIndex:16169; }","duration":"226.163206ms","start":"2026-05-18T18:58:21.399315Z","end":"2026-05-18T18:58:21.625478Z","steps":["trace[206566861] 'read index received' (duration: 14.777829ms)","trace[206566861] 'applied index is now lower than readState.Index' (duration: 211.383517ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T18:58:21.62574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:58:21.220602Z","time spent":"404.982006ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T18:58:21.625978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.640894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-trmdv\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-18T18:58:21.626138Z","caller":"traceutil/trace.go:171","msg":"trace[1263274472] range","detail":"{range_begin:/registry/pods/openstack/keepalived-trmdv; range_end:; response_count:1; response_revision:15636; }","duration":"226.881639ms","start":"2026-05-18T18:58:21.399236Z","end":"2026-05-18T18:58:21.626117Z","steps":["trace[1263274472] 'agreement among raft nodes before linearized reading' (duration: 226.383989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T18:58:21.62614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.481184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-pg8bq\" ","response":"range_response_count:1 size:20754"} {"level":"info","ts":"2026-05-18T18:58:21.626412Z","caller":"traceutil/trace.go:171","msg":"trace[1083876298] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-pg8bq; range_end:; response_count:1; response_revision:15637; }","duration":"141.689668ms","start":"2026-05-18T18:58:21.484551Z","end":"2026-05-18T18:58:21.626241Z","steps":["trace[1083876298] 'agreement among raft nodes before linearized reading' (duration: 141.337311ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:02:00.005062Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14788} {"level":"info","ts":"2026-05-18T19:02:00.100979Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14788,"took":"91.81612ms","hash":2370127851,"current-db-size-bytes":57122816,"current-db-size":"57 MB","current-db-size-in-use-bytes":30547968,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-18T19:02:00.10106Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2370127851,"revision":14788,"compact-revision":12701} {"level":"warn","ts":"2026-05-18T19:04:43.558407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.253559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-18T19:04:43.558558Z","caller":"traceutil/trace.go:171","msg":"trace[273521299] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:18442; }","duration":"169.515534ms","start":"2026-05-18T19:04:43.389016Z","end":"2026-05-18T19:04:43.558531Z","steps":["trace[273521299] 'count revisions from in-memory index tree' (duration: 169.003765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:04:43.558629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.6459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-18T19:04:43.558733Z","caller":"traceutil/trace.go:171","msg":"trace[269407375] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:18442; }","duration":"281.813853ms","start":"2026-05-18T19:04:43.276892Z","end":"2026-05-18T19:04:43.558706Z","steps":["trace[269407375] 'range keys from in-memory index tree' (duration: 281.385826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:04:44.050609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.916647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T19:04:44.050799Z","caller":"traceutil/trace.go:171","msg":"trace[1037215615] linearizableReadLoop","detail":"{readStateIndex:19072; appliedIndex:19071; }","duration":"419.73472ms","start":"2026-05-18T19:04:43.631036Z","end":"2026-05-18T19:04:44.050771Z","steps":["trace[1037215615] 'read index received' (duration: 215.382564ms)","trace[1037215615] 'applied index is now lower than readState.Index' (duration: 204.348175ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:04:44.051022Z","caller":"traceutil/trace.go:171","msg":"trace[530258959] transaction","detail":"{read_only:false; response_revision:18444; number_of_response:1; }","duration":"467.229501ms","start":"2026-05-18T19:04:43.58377Z","end":"2026-05-18T19:04:44.050999Z","steps":["trace[530258959] 'process raft request' (duration: 262.722973ms)","trace[530258959] 'compare' (duration: 203.662682ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:04:44.051148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:04:43.583748Z","time spent":"467.334162ms","remote":"127.0.0.1:41494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:04:44.051429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.827088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T19:04:44.051496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.378271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-18T19:04:44.051512Z","caller":"traceutil/trace.go:171","msg":"trace[5063824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18444; }","duration":"354.95015ms","start":"2026-05-18T19:04:43.696543Z","end":"2026-05-18T19:04:44.051493Z","steps":["trace[5063824] 'agreement among raft nodes before linearized reading' (duration: 354.767487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:04:44.051513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.890605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-krjv2\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-05-18T19:04:44.051546Z","caller":"traceutil/trace.go:171","msg":"trace[2042982487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18444; }","duration":"344.489543ms","start":"2026-05-18T19:04:43.707041Z","end":"2026-05-18T19:04:44.051531Z","steps":["trace[2042982487] 'agreement among raft nodes before linearized reading' (duration: 344.287009ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:04:44.051558Z","caller":"traceutil/trace.go:171","msg":"trace[2144755083] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-krjv2; range_end:; response_count:1; response_revision:18444; }","duration":"233.970126ms","start":"2026-05-18T19:04:43.817573Z","end":"2026-05-18T19:04:44.051544Z","steps":["trace[2144755083] 'agreement among raft nodes before linearized reading' (duration: 233.749142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:04:44.051513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.441012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-18T19:04:44.051585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:04:43.70702Z","time spent":"344.557373ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-18T19:04:44.051593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:04:43.696526Z","time spent":"355.059452ms","remote":"127.0.0.1:41452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-18T19:04:44.051618Z","caller":"traceutil/trace.go:171","msg":"trace[1976982234] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:18444; }","duration":"420.615826ms","start":"2026-05-18T19:04:43.630975Z","end":"2026-05-18T19:04:44.051591Z","steps":["trace[1976982234] 'agreement among raft nodes before linearized reading' (duration: 420.344511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:04:44.051685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:04:43.630939Z","time spent":"420.732048ms","remote":"127.0.0.1:41832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":32,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-05-18T19:06:07.025634Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-18T19:06:07.030306Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-18T19:06:07.030418Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-18T19:07:00.016275Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17374} {"level":"info","ts":"2026-05-18T19:07:00.130523Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17374,"took":"106.078412ms","hash":570416094,"current-db-size-bytes":57122816,"current-db-size":"57 MB","current-db-size-in-use-bytes":34979840,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-18T19:07:00.1306Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":570416094,"revision":17374,"compact-revision":14788} {"level":"warn","ts":"2026-05-18T19:10:53.26299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.846281ms","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:6"} {"level":"info","ts":"2026-05-18T19:10:53.263384Z","caller":"traceutil/trace.go:171","msg":"trace[1382406236] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:21295; }","duration":"138.249279ms","start":"2026-05-18T19:10:53.125089Z","end":"2026-05-18T19:10:53.263339Z","steps":["trace[1382406236] 'count revisions from in-memory index tree' (duration: 137.723529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:10:53.74291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.358357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T19:10:53.743013Z","caller":"traceutil/trace.go:171","msg":"trace[1467569509] linearizableReadLoop","detail":"{readStateIndex:22018; appliedIndex:22017; }","duration":"302.711453ms","start":"2026-05-18T19:10:53.440287Z","end":"2026-05-18T19:10:53.742998Z","steps":["trace[1467569509] 'read index received' (duration: 142.108721ms)","trace[1467569509] 'applied index is now lower than readState.Index' (duration: 160.601052ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:10:53.743085Z","caller":"traceutil/trace.go:171","msg":"trace[1024695897] transaction","detail":"{read_only:false; response_revision:21297; number_of_response:1; }","duration":"313.332234ms","start":"2026-05-18T19:10:53.429744Z","end":"2026-05-18T19:10:53.743076Z","steps":["trace[1024695897] 'process raft request' (duration: 152.704482ms)","trace[1024695897] 'compare' (duration: 160.113003ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:10:53.743132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:10:53.429719Z","time spent":"313.387896ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:10:53.743632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.324964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-05-18T19:10:53.743702Z","caller":"traceutil/trace.go:171","msg":"trace[1347707694] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:21297; }","duration":"303.454377ms","start":"2026-05-18T19:10:53.440229Z","end":"2026-05-18T19:10:53.743683Z","steps":["trace[1347707694] 'agreement among raft nodes before linearized reading' (duration: 303.259163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:10:53.74374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:10:53.440204Z","time spent":"303.527899ms","remote":"127.0.0.1:41634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":447,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-18T19:10:54.057153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.867458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T19:10:54.057239Z","caller":"traceutil/trace.go:171","msg":"trace[1784611783] linearizableReadLoop","detail":"{readStateIndex:22019; appliedIndex:22018; }","duration":"280.828328ms","start":"2026-05-18T19:10:53.776397Z","end":"2026-05-18T19:10:54.057226Z","steps":["trace[1784611783] 'read index received' (duration: 177.696725ms)","trace[1784611783] 'applied index is now lower than readState.Index' (duration: 103.130413ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:10:54.057321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.92027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T19:10:54.057348Z","caller":"traceutil/trace.go:171","msg":"trace[1776734979] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:21298; }","duration":"280.976031ms","start":"2026-05-18T19:10:53.776364Z","end":"2026-05-18T19:10:54.05734Z","steps":["trace[1776734979] 'agreement among raft nodes before linearized reading' (duration: 280.89643ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:10:54.057458Z","caller":"traceutil/trace.go:171","msg":"trace[1464052877] transaction","detail":"{read_only:false; response_revision:21298; number_of_response:1; }","duration":"308.506733ms","start":"2026-05-18T19:10:53.748915Z","end":"2026-05-18T19:10:54.057421Z","steps":["trace[1464052877] 'process raft request' (duration: 205.293708ms)","trace[1464052877] 'compare' (duration: 102.756926ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:10:54.057642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:10:53.748899Z","time spent":"308.648525ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:10:54.306755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.422392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T19:10:54.307312Z","caller":"traceutil/trace.go:171","msg":"trace[45680968] transaction","detail":"{read_only:false; response_revision:21300; number_of_response:1; }","duration":"260.006174ms","start":"2026-05-18T19:10:54.047278Z","end":"2026-05-18T19:10:54.307284Z","steps":["trace[45680968] 'process raft request' (duration: 121.906529ms)","trace[45680968] 'compare' (duration: 136.74888ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:10:54.307496Z","caller":"traceutil/trace.go:171","msg":"trace[1954973356] linearizableReadLoop","detail":"{readStateIndex:22022; appliedIndex:22020; }","duration":"156.881571ms","start":"2026-05-18T19:10:54.150583Z","end":"2026-05-18T19:10:54.307464Z","steps":["trace[1954973356] 'read index received' (duration: 18.44627ms)","trace[1954973356] 'applied index is now lower than readState.Index' (duration: 138.434231ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:10:54.307759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.130916ms","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-05-18T19:10:54.307815Z","caller":"traceutil/trace.go:171","msg":"trace[1022965003] transaction","detail":"{read_only:false; response_revision:21301; number_of_response:1; }","duration":"258.74588ms","start":"2026-05-18T19:10:54.049042Z","end":"2026-05-18T19:10:54.307787Z","steps":["trace[1022965003] 'process raft request' (duration: 257.858184ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:10:54.307833Z","caller":"traceutil/trace.go:171","msg":"trace[2115307931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21301; }","duration":"157.281029ms","start":"2026-05-18T19:10:54.150532Z","end":"2026-05-18T19:10:54.307814Z","steps":["trace[2115307931] 'agreement among raft nodes before linearized reading' (duration: 157.042794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:10:54.813322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.823065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:10:54.813832Z","caller":"traceutil/trace.go:171","msg":"trace[732038700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21303; }","duration":"115.376765ms","start":"2026-05-18T19:10:54.698435Z","end":"2026-05-18T19:10:54.813812Z","steps":["trace[732038700] 'range keys from in-memory index tree' (duration: 114.717843ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:12:00.0246Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19706} {"level":"info","ts":"2026-05-18T19:12:00.135362Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19706,"took":"106.951146ms","hash":1754183153,"current-db-size-bytes":57122816,"current-db-size":"57 MB","current-db-size-in-use-bytes":29962240,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-18T19:12:00.135426Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1754183153,"revision":19706,"compact-revision":17374} {"level":"info","ts":"2026-05-18T19:14:50.555261Z","caller":"traceutil/trace.go:171","msg":"trace[1590294104] transaction","detail":"{read_only:false; response_revision:23738; number_of_response:1; }","duration":"252.84266ms","start":"2026-05-18T19:14:50.302388Z","end":"2026-05-18T19:14:50.55523Z","steps":["trace[1590294104] 'process raft request' (duration: 252.643116ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:14:50.573935Z","caller":"traceutil/trace.go:171","msg":"trace[649464361] transaction","detail":"{read_only:false; response_revision:23739; number_of_response:1; }","duration":"181.680335ms","start":"2026-05-18T19:14:50.392212Z","end":"2026-05-18T19:14:50.573892Z","steps":["trace[649464361] 'process raft request' (duration: 181.41428ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:14:50.728858Z","caller":"traceutil/trace.go:171","msg":"trace[8927194] transaction","detail":"{read_only:false; response_revision:23740; number_of_response:1; }","duration":"137.218115ms","start":"2026-05-18T19:14:50.5916Z","end":"2026-05-18T19:14:50.728818Z","steps":["trace[8927194] 'process raft request' (duration: 104.109909ms)","trace[8927194] 'compare' (duration: 32.881651ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:17:00.030581Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21913} {"level":"info","ts":"2026-05-18T19:17:00.140895Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21913,"took":"105.571616ms","hash":2474927371,"current-db-size-bytes":57544704,"current-db-size":"58 MB","current-db-size-in-use-bytes":43401216,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-18T19:17:00.140948Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2474927371,"revision":21913,"compact-revision":19706} {"level":"warn","ts":"2026-05-18T19:17:49.016379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.098249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T19:17:49.016738Z","caller":"traceutil/trace.go:171","msg":"trace[1251517294] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25632; }","duration":"183.527578ms","start":"2026-05-18T19:17:48.833162Z","end":"2026-05-18T19:17:49.01669Z","steps":["trace[1251517294] 'count revisions from in-memory index tree' (duration: 182.982107ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:17:55.464262Z","caller":"traceutil/trace.go:171","msg":"trace[1112770491] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"101.948886ms","start":"2026-05-18T19:17:55.362279Z","end":"2026-05-18T19:17:55.464228Z","steps":["trace[1112770491] 'process raft request' (duration: 101.774223ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:17:55.618084Z","caller":"traceutil/trace.go:171","msg":"trace[1775545510] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"194.996234ms","start":"2026-05-18T19:17:55.423042Z","end":"2026-05-18T19:17:55.618038Z","steps":["trace[1775545510] 'process raft request' (duration: 183.340164ms)","trace[1775545510] 'compare' (duration: 11.450696ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:17:56.047035Z","caller":"traceutil/trace.go:171","msg":"trace[919292237] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"563.334004ms","start":"2026-05-18T19:17:55.483663Z","end":"2026-05-18T19:17:56.046997Z","steps":["trace[919292237] 'process raft request' (duration: 525.999789ms)","trace[919292237] 'compare' (duration: 36.940638ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:17:56.047212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:55.483643Z","time spent":"563.469766ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:17:56.047Z","caller":"traceutil/trace.go:171","msg":"trace[1910605391] linearizableReadLoop","detail":"{readStateIndex:26537; appliedIndex:26535; }","duration":"518.215581ms","start":"2026-05-18T19:17:55.528743Z","end":"2026-05-18T19:17:56.046959Z","steps":["trace[1910605391] 'read index received' (duration: 77.662988ms)","trace[1910605391] 'applied index is now lower than readState.Index' (duration: 440.551093ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:17:56.047031Z","caller":"traceutil/trace.go:171","msg":"trace[115694998] transaction","detail":"{read_only:false; response_revision:25691; number_of_response:1; }","duration":"493.31354ms","start":"2026-05-18T19:17:55.553701Z","end":"2026-05-18T19:17:56.047014Z","steps":["trace[115694998] 'process raft request' (duration: 493.173948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:17:56.047298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.496006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T19:17:56.0474Z","caller":"traceutil/trace.go:171","msg":"trace[142478469] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:25691; }","duration":"518.67892ms","start":"2026-05-18T19:17:55.528692Z","end":"2026-05-18T19:17:56.047371Z","steps":["trace[142478469] 'agreement among raft nodes before linearized reading' (duration: 518.397585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:17:56.047452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:55.553677Z","time spent":"493.675557ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:17:56.047833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:55.528672Z","time spent":"518.787001ms","remote":"127.0.0.1:40840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":37,"response size":32,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-05-18T19:17:56.376404Z","caller":"traceutil/trace.go:171","msg":"trace[686652546] linearizableReadLoop","detail":"{readStateIndex:26543; appliedIndex:26538; }","duration":"329.094299ms","start":"2026-05-18T19:17:56.047291Z","end":"2026-05-18T19:17:56.376386Z","steps":["trace[686652546] 'read index received' (duration: 286.160677ms)","trace[686652546] 'applied index is now lower than readState.Index' (duration: 42.932482ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:17:56.376552Z","caller":"traceutil/trace.go:171","msg":"trace[1069343349] transaction","detail":"{read_only:false; response_revision:25696; number_of_response:1; }","duration":"333.390119ms","start":"2026-05-18T19:17:56.043115Z","end":"2026-05-18T19:17:56.376506Z","steps":["trace[1069343349] 'process raft request' (duration: 333.220746ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:17:56.376668Z","caller":"traceutil/trace.go:171","msg":"trace[1439634560] transaction","detail":"{read_only:false; response_revision:25693; number_of_response:1; }","duration":"675.327549ms","start":"2026-05-18T19:17:55.70132Z","end":"2026-05-18T19:17:56.376648Z","steps":["trace[1439634560] 'process raft request' (duration: 674.887571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:17:56.376698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.29049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T19:17:56.376724Z","caller":"traceutil/trace.go:171","msg":"trace[1127594682] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25697; }","duration":"448.391162ms","start":"2026-05-18T19:17:55.928325Z","end":"2026-05-18T19:17:56.376716Z","steps":["trace[1127594682] 'agreement among raft nodes before linearized reading' (duration: 448.3073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:17:56.376743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:55.928295Z","time spent":"448.441623ms","remote":"127.0.0.1:50044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-18T19:17:56.376766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:56.043094Z","time spent":"333.548422ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:17:56.376764Z","caller":"traceutil/trace.go:171","msg":"trace[1984493236] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"677.97118ms","start":"2026-05-18T19:17:55.698752Z","end":"2026-05-18T19:17:56.376724Z","steps":["trace[1984493236] 'process raft request' (duration: 634.61367ms)","trace[1984493236] 'compare' (duration: 42.591806ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:17:56.376794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:55.701302Z","time spent":"675.411561ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:17:56.376844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.416204ms","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-05-18T19:17:56.376846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.227486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-18T19:17:56.376904Z","caller":"traceutil/trace.go:171","msg":"trace[1983934563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25697; }","duration":"158.512945ms","start":"2026-05-18T19:17:56.218376Z","end":"2026-05-18T19:17:56.376889Z","steps":["trace[1983934563] 'agreement among raft nodes before linearized reading' (duration: 158.379462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:17:56.376877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.098465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1991"} {"level":"info","ts":"2026-05-18T19:17:56.376912Z","caller":"traceutil/trace.go:171","msg":"trace[748300015] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25697; }","duration":"821.331758ms","start":"2026-05-18T19:17:55.555564Z","end":"2026-05-18T19:17:56.376895Z","steps":["trace[748300015] 'agreement among raft nodes before linearized reading' (duration: 821.135414ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:17:56.376923Z","caller":"traceutil/trace.go:171","msg":"trace[1892000505] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"435.526878ms","start":"2026-05-18T19:17:55.941382Z","end":"2026-05-18T19:17:56.376909Z","steps":["trace[1892000505] 'process raft request' (duration: 434.891517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:17:56.376941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:55.698719Z","time spent":"678.123152ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:17:56.376953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:55.555554Z","time spent":"821.389599ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T19:17:56.376934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.447724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-18T19:17:56.376985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:55.941357Z","time spent":"435.59673ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:17:56.377018Z","caller":"traceutil/trace.go:171","msg":"trace[469834895] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"435.134841ms","start":"2026-05-18T19:17:55.941872Z","end":"2026-05-18T19:17:56.377007Z","steps":["trace[469834895] 'process raft request' (duration: 434.436268ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:17:56.376955Z","caller":"traceutil/trace.go:171","msg":"trace[1587587014] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25697; }","duration":"819.259739ms","start":"2026-05-18T19:17:55.557674Z","end":"2026-05-18T19:17:56.376934Z","steps":["trace[1587587014] 'agreement among raft nodes before linearized reading' (duration: 819.079085ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:17:56.377024Z","caller":"traceutil/trace.go:171","msg":"trace[11948925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25697; }","duration":"501.579587ms","start":"2026-05-18T19:17:55.875425Z","end":"2026-05-18T19:17:56.377004Z","steps":["trace[11948925] 'agreement among raft nodes before linearized reading' (duration: 501.312692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:17:56.377078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:55.87541Z","time spent":"501.657868ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-18T19:17:56.377018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.349588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11789"} {"level":"warn","ts":"2026-05-18T19:17:56.377117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:55.941842Z","time spent":"435.211042ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:17:56.377101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:55.55763Z","time spent":"819.433691ms","remote":"127.0.0.1:41662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2015,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-05-18T19:17:56.377141Z","caller":"traceutil/trace.go:171","msg":"trace[2098530978] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25697; }","duration":"477.498001ms","start":"2026-05-18T19:17:55.899631Z","end":"2026-05-18T19:17:56.377129Z","steps":["trace[2098530978] 'agreement among raft nodes before linearized reading' (duration: 477.277267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:17:56.377207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:55.899615Z","time spent":"477.581853ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11813,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-05-18T19:17:56.770804Z","caller":"traceutil/trace.go:171","msg":"trace[804223190] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"388.839247ms","start":"2026-05-18T19:17:56.38193Z","end":"2026-05-18T19:17:56.770769Z","steps":["trace[804223190] 'process raft request' (duration: 388.580021ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:17:56.771053Z","caller":"traceutil/trace.go:171","msg":"trace[1165644484] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"387.93783ms","start":"2026-05-18T19:17:56.383078Z","end":"2026-05-18T19:17:56.771016Z","steps":["trace[1165644484] 'process raft request' (duration: 387.634144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:17:56.771227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:56.383048Z","time spent":"388.091813ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:17:56.771241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:56.381914Z","time spent":"389.215554ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:17:56.771272Z","caller":"traceutil/trace.go:171","msg":"trace[1380328032] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"386.9263ms","start":"2026-05-18T19:17:56.384329Z","end":"2026-05-18T19:17:56.771255Z","steps":["trace[1380328032] 'process raft request' (duration: 386.614314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:17:56.77136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:56.384318Z","time spent":"386.996621ms","remote":"127.0.0.1:41772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:17:56.771441Z","caller":"traceutil/trace.go:171","msg":"trace[1319798576] linearizableReadLoop","detail":"{readStateIndex:26548; appliedIndex:26544; }","duration":"386.234977ms","start":"2026-05-18T19:17:56.385175Z","end":"2026-05-18T19:17:56.77141Z","steps":["trace[1319798576] 'read index received' (duration: 36.383867ms)","trace[1319798576] 'applied index is now lower than readState.Index' (duration: 349.84966ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:17:56.77154Z","caller":"traceutil/trace.go:171","msg":"trace[1888072859] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"385.395132ms","start":"2026-05-18T19:17:56.386131Z","end":"2026-05-18T19:17:56.771526Z","steps":["trace[1888072859] 'process raft request' (duration: 385.068566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:17:56.77163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:56.386122Z","time spent":"385.458113ms","remote":"127.0.0.1:41772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:17:56.771723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.532283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11789"} {"level":"info","ts":"2026-05-18T19:17:56.771769Z","caller":"traceutil/trace.go:171","msg":"trace[23150061] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25702; }","duration":"386.591744ms","start":"2026-05-18T19:17:56.385166Z","end":"2026-05-18T19:17:56.771758Z","steps":["trace[23150061] 'agreement among raft nodes before linearized reading' (duration: 386.387321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:17:56.771809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:56.385139Z","time spent":"386.661785ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11813,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-05-18T19:17:56.772197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.610809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-18T19:17:56.772253Z","caller":"traceutil/trace.go:171","msg":"trace[886154483] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25702; }","duration":"201.703171ms","start":"2026-05-18T19:17:56.570535Z","end":"2026-05-18T19:17:56.772238Z","steps":["trace[886154483] 'agreement among raft nodes before linearized reading' (duration: 201.555609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:17:56.772253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.959821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-18T19:17:56.772299Z","caller":"traceutil/trace.go:171","msg":"trace[1611614459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25702; }","duration":"332.049033ms","start":"2026-05-18T19:17:56.440237Z","end":"2026-05-18T19:17:56.772286Z","steps":["trace[1611614459] 'agreement among raft nodes before linearized reading' (duration: 331.938401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:17:56.772342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:56.440214Z","time spent":"332.120854ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-18T19:17:56.772942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.528925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T19:17:56.772991Z","caller":"traceutil/trace.go:171","msg":"trace[1252407609] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25702; }","duration":"365.617627ms","start":"2026-05-18T19:17:56.407362Z","end":"2026-05-18T19:17:56.77298Z","steps":["trace[1252407609] 'agreement among raft nodes before linearized reading' (duration: 364.480916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:17:56.773021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:17:56.407349Z","time spent":"365.663898ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":32,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-05-18T19:17:56.933414Z","caller":"traceutil/trace.go:171","msg":"trace[1671139807] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"155.733232ms","start":"2026-05-18T19:17:56.777645Z","end":"2026-05-18T19:17:56.933378Z","steps":["trace[1671139807] 'process raft request' (duration: 155.57493ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:17:56.933672Z","caller":"traceutil/trace.go:171","msg":"trace[732806517] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"156.058509ms","start":"2026-05-18T19:17:56.777602Z","end":"2026-05-18T19:17:56.93366Z","steps":["trace[732806517] 'process raft request' (duration: 154.857905ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:17:56.933847Z","caller":"traceutil/trace.go:171","msg":"trace[138826368] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"156.152201ms","start":"2026-05-18T19:17:56.777658Z","end":"2026-05-18T19:17:56.93381Z","steps":["trace[138826368] 'process raft request' (duration: 155.641381ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:17:57.170605Z","caller":"traceutil/trace.go:171","msg":"trace[945744378] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"230.713599ms","start":"2026-05-18T19:17:56.939857Z","end":"2026-05-18T19:17:57.170571Z","steps":["trace[945744378] 'process raft request' (duration: 216.696954ms)","trace[945744378] 'compare' (duration: 13.693149ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:12.104117Z","caller":"traceutil/trace.go:171","msg":"trace[2102311059] linearizableReadLoop","detail":"{readStateIndex:26728; appliedIndex:26727; }","duration":"147.764762ms","start":"2026-05-18T19:18:11.956332Z","end":"2026-05-18T19:18:12.104096Z","steps":["trace[2102311059] 'read index received' (duration: 147.598699ms)","trace[2102311059] 'applied index is now lower than readState.Index' (duration: 165.373µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:12.104327Z","caller":"traceutil/trace.go:171","msg":"trace[682929554] transaction","detail":"{read_only:false; response_revision:25878; number_of_response:1; }","duration":"163.220743ms","start":"2026-05-18T19:18:11.941098Z","end":"2026-05-18T19:18:12.104319Z","steps":["trace[682929554] 'process raft request' (duration: 162.888407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:12.104528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.17888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-18T19:18:12.104551Z","caller":"traceutil/trace.go:171","msg":"trace[776289102] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25878; }","duration":"148.224601ms","start":"2026-05-18T19:18:11.956321Z","end":"2026-05-18T19:18:12.104546Z","steps":["trace[776289102] 'agreement among raft nodes before linearized reading' (duration: 148.117259ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:12.389784Z","caller":"traceutil/trace.go:171","msg":"trace[696874541] transaction","detail":"{read_only:false; response_revision:25879; number_of_response:1; }","duration":"275.150648ms","start":"2026-05-18T19:18:12.114592Z","end":"2026-05-18T19:18:12.389742Z","steps":["trace[696874541] 'process raft request' (duration: 274.902094ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:12.427859Z","caller":"traceutil/trace.go:171","msg":"trace[1285827109] linearizableReadLoop","detail":"{readStateIndex:26730; appliedIndex:26728; }","duration":"140.088137ms","start":"2026-05-18T19:18:12.287735Z","end":"2026-05-18T19:18:12.427823Z","steps":["trace[1285827109] 'read index received' (duration: 101.965397ms)","trace[1285827109] 'applied index is now lower than readState.Index' (duration: 38.12186ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:12.427996Z","caller":"traceutil/trace.go:171","msg":"trace[81399596] transaction","detail":"{read_only:false; response_revision:25880; number_of_response:1; }","duration":"291.955597ms","start":"2026-05-18T19:18:12.136029Z","end":"2026-05-18T19:18:12.427984Z","steps":["trace[81399596] 'process raft request' (duration: 291.668252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:12.428206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.443524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:18:12.428252Z","caller":"traceutil/trace.go:171","msg":"trace[1256268803] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25880; }","duration":"140.536605ms","start":"2026-05-18T19:18:12.287702Z","end":"2026-05-18T19:18:12.428239Z","steps":["trace[1256268803] 'agreement among raft nodes before linearized reading' (duration: 140.444713ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:12.590334Z","caller":"traceutil/trace.go:171","msg":"trace[930001786] transaction","detail":"{read_only:false; response_revision:25881; number_of_response:1; }","duration":"143.89848ms","start":"2026-05-18T19:18:12.446339Z","end":"2026-05-18T19:18:12.590238Z","steps":["trace[930001786] 'process raft request' (duration: 123.600446ms)","trace[930001786] 'compare' (duration: 20.09646ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:12.941196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.044372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T19:18:12.941478Z","caller":"traceutil/trace.go:171","msg":"trace[843399979] transaction","detail":"{read_only:false; response_revision:25885; number_of_response:1; }","duration":"488.395987ms","start":"2026-05-18T19:18:12.453064Z","end":"2026-05-18T19:18:12.94146Z","steps":["trace[843399979] 'process raft request' (duration: 488.325356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:12.941549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:12.453057Z","time spent":"488.461949ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:12.941694Z","caller":"traceutil/trace.go:171","msg":"trace[1069800472] transaction","detail":"{read_only:false; response_revision:25882; number_of_response:1; }","duration":"492.360303ms","start":"2026-05-18T19:18:12.449272Z","end":"2026-05-18T19:18:12.941633Z","steps":["trace[1069800472] 'process raft request' (duration: 347.788931ms)","trace[1069800472] 'compare' (duration: 143.852658ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:12.941765Z","caller":"traceutil/trace.go:171","msg":"trace[1224514964] transaction","detail":"{read_only:false; response_revision:25883; number_of_response:1; }","duration":"491.336123ms","start":"2026-05-18T19:18:12.450419Z","end":"2026-05-18T19:18:12.941755Z","steps":["trace[1224514964] 'process raft request' (duration: 490.870444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:12.941826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:12.45039Z","time spent":"491.406274ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:12.941961Z","caller":"traceutil/trace.go:171","msg":"trace[1855618623] transaction","detail":"{read_only:false; response_revision:25884; number_of_response:1; }","duration":"489.010459ms","start":"2026-05-18T19:18:12.452942Z","end":"2026-05-18T19:18:12.941952Z","steps":["trace[1855618623] 'process raft request' (duration: 488.397177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:12.941999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:12.449258Z","time spent":"492.542446ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:12.942022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:12.452932Z","time spent":"489.058819ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:12.942419Z","caller":"traceutil/trace.go:171","msg":"trace[1306868979] linearizableReadLoop","detail":"{readStateIndex:26736; appliedIndex:26730; }","duration":"429.969173ms","start":"2026-05-18T19:18:12.512435Z","end":"2026-05-18T19:18:12.942404Z","steps":["trace[1306868979] 'read index received' (duration: 57.631568ms)","trace[1306868979] 'applied index is now lower than readState.Index' (duration: 372.336285ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:12.942697Z","caller":"traceutil/trace.go:171","msg":"trace[479925574] transaction","detail":"{read_only:false; response_revision:25886; number_of_response:1; }","duration":"486.643184ms","start":"2026-05-18T19:18:12.456038Z","end":"2026-05-18T19:18:12.942681Z","steps":["trace[479925574] 'process raft request' (duration: 485.387501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:12.942766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:12.456029Z","time spent":"486.705705ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:12.942975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.520905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-18T19:18:12.943052Z","caller":"traceutil/trace.go:171","msg":"trace[67280162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25886; }","duration":"430.650287ms","start":"2026-05-18T19:18:12.51236Z","end":"2026-05-18T19:18:12.943011Z","steps":["trace[67280162] 'agreement among raft nodes before linearized reading' (duration: 430.400222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:12.943096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:12.512344Z","time spent":"430.741989ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-18T19:18:12.943213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.57836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:18:12.943256Z","caller":"traceutil/trace.go:171","msg":"trace[1332328350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25886; }","duration":"245.676172ms","start":"2026-05-18T19:18:12.697569Z","end":"2026-05-18T19:18:12.943246Z","steps":["trace[1332328350] 'agreement among raft nodes before linearized reading' (duration: 245.60954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:12.943624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.947163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"warn","ts":"2026-05-18T19:18:12.943642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.136012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2cb161dc-cfc1-4ac2-bd71-a25db458e82e\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-05-18T19:18:12.943663Z","caller":"traceutil/trace.go:171","msg":"trace[1656381043] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25886; }","duration":"177.035745ms","start":"2026-05-18T19:18:12.766618Z","end":"2026-05-18T19:18:12.943654Z","steps":["trace[1656381043] 'agreement among raft nodes before linearized reading' (duration: 176.937293ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:12.943684Z","caller":"traceutil/trace.go:171","msg":"trace[13653031] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2cb161dc-cfc1-4ac2-bd71-a25db458e82e; range_end:; response_count:1; response_revision:25886; }","duration":"155.250053ms","start":"2026-05-18T19:18:12.78842Z","end":"2026-05-18T19:18:12.94367Z","steps":["trace[13653031] 'agreement among raft nodes before linearized reading' (duration: 155.08518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:12.943875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.760289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-5c59cc5cc6-5ljbm.18b0bf00e9c2c7ac\" ","response":"range_response_count:1 size:890"} {"level":"info","ts":"2026-05-18T19:18:12.943913Z","caller":"traceutil/trace.go:171","msg":"trace[1835086179] range","detail":"{range_begin:/registry/events/openstack/magnum-api-5c59cc5cc6-5ljbm.18b0bf00e9c2c7ac; range_end:; response_count:1; response_revision:25886; }","duration":"222.820581ms","start":"2026-05-18T19:18:12.721083Z","end":"2026-05-18T19:18:12.943904Z","steps":["trace[1835086179] 'agreement among raft nodes before linearized reading' (duration: 222.720429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:12.944101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.097815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-5c59cc5cc6-5ljbm\" ","response":"range_response_count:1 size:8863"} {"level":"info","ts":"2026-05-18T19:18:12.944135Z","caller":"traceutil/trace.go:171","msg":"trace[1894812895] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-5c59cc5cc6-5ljbm; range_end:; response_count:1; response_revision:25886; }","duration":"223.152717ms","start":"2026-05-18T19:18:12.720974Z","end":"2026-05-18T19:18:12.944127Z","steps":["trace[1894812895] 'agreement among raft nodes before linearized reading' (duration: 223.062905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:12.944488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.700649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-18T19:18:12.944569Z","caller":"traceutil/trace.go:171","msg":"trace[1510683578] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25886; }","duration":"103.817342ms","start":"2026-05-18T19:18:12.840737Z","end":"2026-05-18T19:18:12.944554Z","steps":["trace[1510683578] 'agreement among raft nodes before linearized reading' (duration: 103.624778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:13.221888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.925348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T19:18:13.221993Z","caller":"traceutil/trace.go:171","msg":"trace[839681871] transaction","detail":"{read_only:false; response_revision:25887; number_of_response:1; }","duration":"269.288067ms","start":"2026-05-18T19:18:12.952689Z","end":"2026-05-18T19:18:13.221977Z","steps":["trace[839681871] 'process raft request' (duration: 160.165856ms)","trace[839681871] 'compare' (duration: 108.777265ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:13.53515Z","caller":"traceutil/trace.go:171","msg":"trace[456724145] transaction","detail":"{read_only:false; response_revision:25890; number_of_response:1; }","duration":"582.005926ms","start":"2026-05-18T19:18:12.953117Z","end":"2026-05-18T19:18:13.535123Z","steps":["trace[456724145] 'process raft request' (duration: 581.926154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:13.535334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:12.953108Z","time spent":"582.145789ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:13.535363Z","caller":"traceutil/trace.go:171","msg":"trace[102199358] linearizableReadLoop","detail":"{readStateIndex:26742; appliedIndex:26736; }","duration":"580.745952ms","start":"2026-05-18T19:18:12.954595Z","end":"2026-05-18T19:18:13.535341Z","steps":["trace[102199358] 'read index received' (duration: 158.27437ms)","trace[102199358] 'applied index is now lower than readState.Index' (duration: 422.470502ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:13.53548Z","caller":"traceutil/trace.go:171","msg":"trace[1929814161] transaction","detail":"{read_only:false; response_revision:25891; number_of_response:1; }","duration":"582.23267ms","start":"2026-05-18T19:18:12.953236Z","end":"2026-05-18T19:18:13.535469Z","steps":["trace[1929814161] 'process raft request' (duration: 581.843713ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:13.535452Z","caller":"traceutil/trace.go:171","msg":"trace[1080922996] transaction","detail":"{read_only:false; response_revision:25888; number_of_response:1; }","duration":"582.454445ms","start":"2026-05-18T19:18:12.952966Z","end":"2026-05-18T19:18:13.53542Z","steps":["trace[1080922996] 'process raft request' (duration: 531.432561ms)","trace[1080922996] 'compare' (duration: 50.30769ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:13.535547Z","caller":"traceutil/trace.go:171","msg":"trace[1520444264] transaction","detail":"{read_only:false; response_revision:25889; number_of_response:1; }","duration":"582.423464ms","start":"2026-05-18T19:18:12.953111Z","end":"2026-05-18T19:18:13.535535Z","steps":["trace[1520444264] 'process raft request' (duration: 581.836333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:13.535573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:12.95321Z","time spent":"582.298972ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:13.535602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:12.952954Z","time spent":"582.566346ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:13.535614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:12.953102Z","time spent":"582.479746ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:13.53591Z","caller":"traceutil/trace.go:171","msg":"trace[2044916936] transaction","detail":"{read_only:false; response_revision:25892; number_of_response:1; }","duration":"581.452285ms","start":"2026-05-18T19:18:12.954447Z","end":"2026-05-18T19:18:13.5359Z","steps":["trace[2044916936] 'process raft request' (duration: 580.811013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:13.535961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:12.954441Z","time spent":"581.493236ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8887,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:13.536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.08686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:18:13.536024Z","caller":"traceutil/trace.go:171","msg":"trace[1464809645] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:25892; }","duration":"525.134532ms","start":"2026-05-18T19:18:13.01088Z","end":"2026-05-18T19:18:13.536015Z","steps":["trace[1464809645] 'agreement among raft nodes before linearized reading' (duration: 525.102811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:13.535858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.418271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-18T19:18:13.536083Z","caller":"traceutil/trace.go:171","msg":"trace[2008706893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25892; }","duration":"531.678236ms","start":"2026-05-18T19:18:13.004392Z","end":"2026-05-18T19:18:13.536071Z","steps":["trace[2008706893] 'agreement among raft nodes before linearized reading' (duration: 531.38842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:13.536129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:13.004374Z","time spent":"531.743406ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-18T19:18:13.535968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.034769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-18T19:18:13.536235Z","caller":"traceutil/trace.go:171","msg":"trace[673253689] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:25892; }","duration":"201.331224ms","start":"2026-05-18T19:18:13.334895Z","end":"2026-05-18T19:18:13.536226Z","steps":["trace[673253689] 'agreement among raft nodes before linearized reading' (duration: 201.032879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:13.53587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.280442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-05-18T19:18:13.53633Z","caller":"traceutil/trace.go:171","msg":"trace[481901438] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25892; }","duration":"581.771941ms","start":"2026-05-18T19:18:12.954545Z","end":"2026-05-18T19:18:13.536317Z","steps":["trace[481901438] 'agreement among raft nodes before linearized reading' (duration: 581.215811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:13.536374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:12.95453Z","time spent":"581.833822ms","remote":"127.0.0.1:41554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1336,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"warn","ts":"2026-05-18T19:18:13.536048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:13.010866Z","time spent":"525.174862ms","remote":"127.0.0.1:57468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"warn","ts":"2026-05-18T19:18:13.779629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.283508ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-05-18T19:18:13.930326Z","caller":"traceutil/trace.go:171","msg":"trace[188232808] linearizableReadLoop","detail":"{readStateIndex:26744; appliedIndex:26742; }","duration":"388.324227ms","start":"2026-05-18T19:18:13.541921Z","end":"2026-05-18T19:18:13.930245Z","steps":["trace[188232808] 'read index received' (duration: 112.421304ms)","trace[188232808] 'applied index is now lower than readState.Index' (duration: 275.902033ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:13.930571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.637323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-05-18T19:18:13.930611Z","caller":"traceutil/trace.go:171","msg":"trace[536211213] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25893; }","duration":"388.721625ms","start":"2026-05-18T19:18:13.541877Z","end":"2026-05-18T19:18:13.930598Z","steps":["trace[536211213] 'agreement among raft nodes before linearized reading' (duration: 388.523521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:13.930648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:13.541852Z","time spent":"388.788636ms","remote":"127.0.0.1:41662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1282,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" "} {"level":"info","ts":"2026-05-18T19:18:13.930639Z","caller":"traceutil/trace.go:171","msg":"trace[1503348019] transaction","detail":"{read_only:false; response_revision:25893; number_of_response:1; }","duration":"390.481887ms","start":"2026-05-18T19:18:13.540123Z","end":"2026-05-18T19:18:13.930604Z","steps":["trace[1503348019] 'process raft request' (duration: 389.925117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:13.930791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:13.540105Z","time spent":"390.60687ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:13.978909Z","caller":"traceutil/trace.go:171","msg":"trace[190801458] transaction","detail":"{read_only:false; response_revision:25894; number_of_response:1; }","duration":"274.582978ms","start":"2026-05-18T19:18:13.704306Z","end":"2026-05-18T19:18:13.978889Z","steps":["trace[190801458] 'process raft request' (duration: 274.052968ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:13.979064Z","caller":"traceutil/trace.go:171","msg":"trace[234496959] transaction","detail":"{read_only:false; response_revision:25895; number_of_response:1; }","duration":"228.358224ms","start":"2026-05-18T19:18:13.750699Z","end":"2026-05-18T19:18:13.979057Z","steps":["trace[234496959] 'process raft request' (duration: 227.841895ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:13.97917Z","caller":"traceutil/trace.go:171","msg":"trace[669330106] transaction","detail":"{read_only:false; response_revision:25896; number_of_response:1; }","duration":"198.51297ms","start":"2026-05-18T19:18:13.780652Z","end":"2026-05-18T19:18:13.979165Z","steps":["trace[669330106] 'process raft request' (duration: 197.933399ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:14.276695Z","caller":"traceutil/trace.go:171","msg":"trace[1368578349] linearizableReadLoop","detail":"{readStateIndex:26749; appliedIndex:26748; }","duration":"259.591614ms","start":"2026-05-18T19:18:14.017059Z","end":"2026-05-18T19:18:14.27665Z","steps":["trace[1368578349] 'read index received' (duration: 200.013708ms)","trace[1368578349] 'applied index is now lower than readState.Index' (duration: 59.576826ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:14.276957Z","caller":"traceutil/trace.go:171","msg":"trace[1080951084] transaction","detail":"{read_only:false; response_revision:25898; number_of_response:1; }","duration":"267.527615ms","start":"2026-05-18T19:18:14.009416Z","end":"2026-05-18T19:18:14.276944Z","steps":["trace[1080951084] 'process raft request' (duration: 207.744955ms)","trace[1080951084] 'compare' (duration: 59.24945ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:14.277211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.137085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"warn","ts":"2026-05-18T19:18:14.277267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.121227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-5c59cc5cc6-5ljbm.18b0bf00ee122557\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-05-18T19:18:14.27728Z","caller":"traceutil/trace.go:171","msg":"trace[358583840] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25898; }","duration":"260.256407ms","start":"2026-05-18T19:18:14.017009Z","end":"2026-05-18T19:18:14.277265Z","steps":["trace[358583840] 'agreement among raft nodes before linearized reading' (duration: 259.991202ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:14.277355Z","caller":"traceutil/trace.go:171","msg":"trace[1474019508] range","detail":"{range_begin:/registry/events/openstack/magnum-api-5c59cc5cc6-5ljbm.18b0bf00ee122557; range_end:; response_count:1; response_revision:25898; }","duration":"140.223159ms","start":"2026-05-18T19:18:14.137098Z","end":"2026-05-18T19:18:14.277321Z","steps":["trace[1474019508] 'agreement among raft nodes before linearized reading' (duration: 139.934243ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:14.603458Z","caller":"traceutil/trace.go:171","msg":"trace[1747942132] linearizableReadLoop","detail":"{readStateIndex:26750; appliedIndex:26749; }","duration":"321.509544ms","start":"2026-05-18T19:18:14.281931Z","end":"2026-05-18T19:18:14.603441Z","steps":["trace[1747942132] 'read index received' (duration: 308.880506ms)","trace[1747942132] 'applied index is now lower than readState.Index' (duration: 12.628198ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:14.603582Z","caller":"traceutil/trace.go:171","msg":"trace[1545890014] transaction","detail":"{read_only:false; response_revision:25899; number_of_response:1; }","duration":"323.597004ms","start":"2026-05-18T19:18:14.279976Z","end":"2026-05-18T19:18:14.603573Z","steps":["trace[1545890014] 'process raft request' (duration: 310.826123ms)","trace[1545890014] 'compare' (duration: 12.082398ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:14.603654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:14.279959Z","time spent":"323.637734ms","remote":"127.0.0.1:41538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":733,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:14.603824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.874031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18b0bf074bb33ad2\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-05-18T19:18:14.6039Z","caller":"traceutil/trace.go:171","msg":"trace[451250548] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18b0bf074bb33ad2; range_end:; response_count:1; response_revision:25899; }","duration":"321.996773ms","start":"2026-05-18T19:18:14.281886Z","end":"2026-05-18T19:18:14.603883Z","steps":["trace[451250548] 'agreement among raft nodes before linearized reading' (duration: 321.79873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:14.603941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:14.281878Z","time spent":"322.054145ms","remote":"127.0.0.1:41538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":771,"request content":"key:\"/registry/events/openstack/rabbitmq-manila.18b0bf074bb33ad2\" "} {"level":"warn","ts":"2026-05-18T19:18:14.833784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.00733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T19:18:14.833976Z","caller":"traceutil/trace.go:171","msg":"trace[1510474988] linearizableReadLoop","detail":"{readStateIndex:26752; appliedIndex:26750; }","duration":"230.406434ms","start":"2026-05-18T19:18:14.603555Z","end":"2026-05-18T19:18:14.833961Z","steps":["trace[1510474988] 'read index received' (duration: 113.147439ms)","trace[1510474988] 'applied index is now lower than readState.Index' (duration: 117.257815ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:14.833993Z","caller":"traceutil/trace.go:171","msg":"trace[1756687965] transaction","detail":"{read_only:false; response_revision:25901; number_of_response:1; }","duration":"236.821984ms","start":"2026-05-18T19:18:14.597147Z","end":"2026-05-18T19:18:14.833969Z","steps":["trace[1756687965] 'process raft request' (duration: 236.728343ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:14.834146Z","caller":"traceutil/trace.go:171","msg":"trace[1647544849] transaction","detail":"{read_only:false; response_revision:25902; number_of_response:1; }","duration":"228.307763ms","start":"2026-05-18T19:18:14.605828Z","end":"2026-05-18T19:18:14.834136Z","steps":["trace[1647544849] 'process raft request' (duration: 228.090439ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:14.834169Z","caller":"traceutil/trace.go:171","msg":"trace[1533718758] transaction","detail":"{read_only:false; response_revision:25900; number_of_response:1; }","duration":"399.800534ms","start":"2026-05-18T19:18:14.434359Z","end":"2026-05-18T19:18:14.83416Z","steps":["trace[1533718758] 'process raft request' (duration: 282.324774ms)","trace[1533718758] 'compare' (duration: 116.694084ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:14.834252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:14.43433Z","time spent":"399.856315ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:14.834322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.137081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-5c59cc5cc6-5ljbm.18b0bf00f37e5397\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-05-18T19:18:14.834371Z","caller":"traceutil/trace.go:171","msg":"trace[875277861] range","detail":"{range_begin:/registry/events/openstack/magnum-api-5c59cc5cc6-5ljbm.18b0bf00f37e5397; range_end:; response_count:1; response_revision:25902; }","duration":"228.212833ms","start":"2026-05-18T19:18:14.606144Z","end":"2026-05-18T19:18:14.834357Z","steps":["trace[875277861] 'agreement among raft nodes before linearized reading' (duration: 228.0499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:14.834473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.552186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-18T19:18:14.834501Z","caller":"traceutil/trace.go:171","msg":"trace[1496662874] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25902; }","duration":"131.583636ms","start":"2026-05-18T19:18:14.702911Z","end":"2026-05-18T19:18:14.834494Z","steps":["trace[1496662874] 'agreement among raft nodes before linearized reading' (duration: 131.478764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:14.834506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.806725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:18:14.834523Z","caller":"traceutil/trace.go:171","msg":"trace[1110011304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25902; }","duration":"136.854506ms","start":"2026-05-18T19:18:14.697664Z","end":"2026-05-18T19:18:14.834518Z","steps":["trace[1110011304] 'agreement among raft nodes before linearized reading' (duration: 136.817015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:14.834632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.386394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T19:18:14.834675Z","caller":"traceutil/trace.go:171","msg":"trace[1398330989] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:25902; }","duration":"238.485425ms","start":"2026-05-18T19:18:14.596182Z","end":"2026-05-18T19:18:14.834667Z","steps":["trace[1398330989] 'agreement among raft nodes before linearized reading' (duration: 238.365293ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:14.989232Z","caller":"traceutil/trace.go:171","msg":"trace[1633881909] linearizableReadLoop","detail":"{readStateIndex:26754; appliedIndex:26753; }","duration":"147.562868ms","start":"2026-05-18T19:18:14.841647Z","end":"2026-05-18T19:18:14.98921Z","steps":["trace[1633881909] 'read index received' (duration: 147.423646ms)","trace[1633881909] 'applied index is now lower than readState.Index' (duration: 138.452µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:14.989429Z","caller":"traceutil/trace.go:171","msg":"trace[964187657] transaction","detail":"{read_only:false; response_revision:25903; number_of_response:1; }","duration":"147.872504ms","start":"2026-05-18T19:18:14.841543Z","end":"2026-05-18T19:18:14.989415Z","steps":["trace[964187657] 'process raft request' (duration: 147.510617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:14.989605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.941915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-18T19:18:14.989668Z","caller":"traceutil/trace.go:171","msg":"trace[1553740465] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25903; }","duration":"148.051648ms","start":"2026-05-18T19:18:14.841607Z","end":"2026-05-18T19:18:14.989659Z","steps":["trace[1553740465] 'agreement among raft nodes before linearized reading' (duration: 147.846544ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:15.370595Z","caller":"traceutil/trace.go:171","msg":"trace[540546960] transaction","detail":"{read_only:false; response_revision:25908; number_of_response:1; }","duration":"413.541414ms","start":"2026-05-18T19:18:14.957021Z","end":"2026-05-18T19:18:15.370563Z","steps":["trace[540546960] 'process raft request' (duration: 413.488863ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:15.37098Z","caller":"traceutil/trace.go:171","msg":"trace[1375034815] linearizableReadLoop","detail":"{readStateIndex:26759; appliedIndex:26759; }","duration":"381.57799ms","start":"2026-05-18T19:18:14.989385Z","end":"2026-05-18T19:18:15.370963Z","steps":["trace[1375034815] 'read index received' (duration: 381.565739ms)","trace[1375034815] 'applied index is now lower than readState.Index' (duration: 10.79µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:15.371311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.754657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2cb161dc-cfc1-4ac2-bd71-a25db458e82e\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-05-18T19:18:15.371373Z","caller":"traceutil/trace.go:171","msg":"trace[951361441] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2cb161dc-cfc1-4ac2-bd71-a25db458e82e; range_end:; response_count:1; response_revision:25908; }","duration":"385.833249ms","start":"2026-05-18T19:18:14.985526Z","end":"2026-05-18T19:18:15.371359Z","steps":["trace[951361441] 'agreement among raft nodes before linearized reading' (duration: 385.527493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:15.371417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:14.98551Z","time spent":"385.897211ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3788,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2cb161dc-cfc1-4ac2-bd71-a25db458e82e\" "} {"level":"info","ts":"2026-05-18T19:18:15.372262Z","caller":"traceutil/trace.go:171","msg":"trace[880369110] transaction","detail":"{read_only:false; response_revision:25905; number_of_response:1; }","duration":"419.484176ms","start":"2026-05-18T19:18:14.952741Z","end":"2026-05-18T19:18:15.372225Z","steps":["trace[880369110] 'process raft request' (duration: 417.59843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:15.372483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:14.952735Z","time spent":"419.631419ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:15.372527Z","caller":"traceutil/trace.go:171","msg":"trace[981491686] transaction","detail":"{read_only:false; response_revision:25906; number_of_response:1; }","duration":"419.72484ms","start":"2026-05-18T19:18:14.952778Z","end":"2026-05-18T19:18:15.372503Z","steps":["trace[981491686] 'process raft request' (duration: 417.64608ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:15.372676Z","caller":"traceutil/trace.go:171","msg":"trace[796620092] transaction","detail":"{read_only:false; response_revision:25907; number_of_response:1; }","duration":"419.517246ms","start":"2026-05-18T19:18:14.953112Z","end":"2026-05-18T19:18:15.372629Z","steps":["trace[796620092] 'process raft request' (duration: 417.360605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:15.372736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:14.952774Z","time spent":"419.870333ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:15.372758Z","caller":"traceutil/trace.go:171","msg":"trace[933223114] transaction","detail":"{read_only:false; response_revision:25904; number_of_response:1; }","duration":"420.116538ms","start":"2026-05-18T19:18:14.952598Z","end":"2026-05-18T19:18:15.372715Z","steps":["trace[933223114] 'process raft request' (duration: 417.519899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:15.372962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:14.952577Z","time spent":"420.270591ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:15.372963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:14.953102Z","time spent":"419.667599ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:15.374234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.995419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-18T19:18:15.374361Z","caller":"traceutil/trace.go:171","msg":"trace[2023089587] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25908; }","duration":"480.142151ms","start":"2026-05-18T19:18:14.894192Z","end":"2026-05-18T19:18:15.374334Z","steps":["trace[2023089587] 'agreement among raft nodes before linearized reading' (duration: 477.574763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:15.37442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:14.894163Z","time spent":"480.246944ms","remote":"127.0.0.1:41942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2657,"response size":33,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-18T19:18:15.374997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:14.95701Z","time spent":"413.683527ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:15.392942Z","caller":"traceutil/trace.go:171","msg":"trace[153977343] transaction","detail":"{read_only:false; response_revision:25909; number_of_response:1; }","duration":"137.288853ms","start":"2026-05-18T19:18:15.255626Z","end":"2026-05-18T19:18:15.392915Z","steps":["trace[153977343] 'process raft request' (duration: 137.140431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:15.395928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.060064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18417"} {"level":"info","ts":"2026-05-18T19:18:15.396008Z","caller":"traceutil/trace.go:171","msg":"trace[354452235] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25909; }","duration":"404.198126ms","start":"2026-05-18T19:18:14.99179Z","end":"2026-05-18T19:18:15.395989Z","steps":["trace[354452235] 'agreement among raft nodes before linearized reading' (duration: 403.951402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:15.396059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:14.991759Z","time spent":"404.287709ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18441,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-18T19:18:15.396559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.512333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-5c59cc5cc6-5ljbm\" ","response":"range_response_count:1 size:8905"} {"level":"info","ts":"2026-05-18T19:18:15.396602Z","caller":"traceutil/trace.go:171","msg":"trace[2039792321] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-5c59cc5cc6-5ljbm; range_end:; response_count:1; response_revision:25909; }","duration":"241.600635ms","start":"2026-05-18T19:18:15.154989Z","end":"2026-05-18T19:18:15.39659Z","steps":["trace[2039792321] 'agreement among raft nodes before linearized reading' (duration: 241.492233ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:15.71614Z","caller":"traceutil/trace.go:171","msg":"trace[1985051047] transaction","detail":"{read_only:false; response_revision:25910; number_of_response:1; }","duration":"309.193812ms","start":"2026-05-18T19:18:15.406924Z","end":"2026-05-18T19:18:15.716118Z","steps":["trace[1985051047] 'process raft request' (duration: 309.072809ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:15.716174Z","caller":"traceutil/trace.go:171","msg":"trace[1433103353] linearizableReadLoop","detail":"{readStateIndex:26761; appliedIndex:26761; }","duration":"222.575675ms","start":"2026-05-18T19:18:15.493559Z","end":"2026-05-18T19:18:15.716135Z","steps":["trace[1433103353] 'read index received' (duration: 222.562365ms)","trace[1433103353] 'applied index is now lower than readState.Index' (duration: 10.76µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:15.716259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:15.406896Z","time spent":"309.296384ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8743,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:15.716378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.79797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:18:15.716441Z","caller":"traceutil/trace.go:171","msg":"trace[1796098131] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25910; }","duration":"223.109725ms","start":"2026-05-18T19:18:15.493316Z","end":"2026-05-18T19:18:15.716426Z","steps":["trace[1796098131] 'agreement among raft nodes before linearized reading' (duration: 222.974723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:15.716487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.565942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T19:18:15.716566Z","caller":"traceutil/trace.go:171","msg":"trace[1583627487] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:25910; }","duration":"198.703725ms","start":"2026-05-18T19:18:15.517841Z","end":"2026-05-18T19:18:15.716545Z","steps":["trace[1583627487] 'agreement among raft nodes before linearized reading' (duration: 198.533581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:15.716684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.279012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-18T19:18:15.716752Z","caller":"traceutil/trace.go:171","msg":"trace[25145746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25910; }","duration":"175.386174ms","start":"2026-05-18T19:18:15.541349Z","end":"2026-05-18T19:18:15.716735Z","steps":["trace[25145746] 'agreement among raft nodes before linearized reading' (duration: 175.221981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:15.716924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.576427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-18T19:18:15.716962Z","caller":"traceutil/trace.go:171","msg":"trace[1881421264] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25910; }","duration":"175.618038ms","start":"2026-05-18T19:18:15.541328Z","end":"2026-05-18T19:18:15.716946Z","steps":["trace[1881421264] 'agreement among raft nodes before linearized reading' (duration: 175.479545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:15.716985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.238918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-18T19:18:15.717019Z","caller":"traceutil/trace.go:171","msg":"trace[1029532076] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25910; }","duration":"133.296019ms","start":"2026-05-18T19:18:15.58371Z","end":"2026-05-18T19:18:15.717006Z","steps":["trace[1029532076] 'agreement among raft nodes before linearized reading' (duration: 133.196927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:15.717082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.131849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-18T19:18:15.717137Z","caller":"traceutil/trace.go:171","msg":"trace[1549947401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25910; }","duration":"175.213711ms","start":"2026-05-18T19:18:15.541907Z","end":"2026-05-18T19:18:15.717121Z","steps":["trace[1549947401] 'agreement among raft nodes before linearized reading' (duration: 175.011928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:15.717179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.863823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-18T19:18:15.717209Z","caller":"traceutil/trace.go:171","msg":"trace[1434913528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25910; }","duration":"175.928094ms","start":"2026-05-18T19:18:15.541272Z","end":"2026-05-18T19:18:15.7172Z","steps":["trace[1434913528] 'agreement among raft nodes before linearized reading' (duration: 175.816852ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:15.893802Z","caller":"traceutil/trace.go:171","msg":"trace[1628264852] transaction","detail":"{read_only:false; response_revision:25912; number_of_response:1; }","duration":"169.272739ms","start":"2026-05-18T19:18:15.724502Z","end":"2026-05-18T19:18:15.893775Z","steps":["trace[1628264852] 'process raft request' (duration: 169.068105ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:15.894064Z","caller":"traceutil/trace.go:171","msg":"trace[1297851011] linearizableReadLoop","detail":"{readStateIndex:26765; appliedIndex:26761; }","duration":"167.007776ms","start":"2026-05-18T19:18:15.727037Z","end":"2026-05-18T19:18:15.894045Z","steps":["trace[1297851011] 'read index received' (duration: 19.322015ms)","trace[1297851011] 'applied index is now lower than readState.Index' (duration: 147.684941ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:15.894149Z","caller":"traceutil/trace.go:171","msg":"trace[390006567] transaction","detail":"{read_only:false; response_revision:25913; number_of_response:1; }","duration":"169.567255ms","start":"2026-05-18T19:18:15.724574Z","end":"2026-05-18T19:18:15.894142Z","steps":["trace[390006567] 'process raft request' (duration: 169.163887ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:15.894405Z","caller":"traceutil/trace.go:171","msg":"trace[1771045438] transaction","detail":"{read_only:false; response_revision:25914; number_of_response:1; }","duration":"169.618735ms","start":"2026-05-18T19:18:15.724782Z","end":"2026-05-18T19:18:15.8944Z","steps":["trace[1771045438] 'process raft request' (duration: 169.168906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:15.894554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.511965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f02beba2-f1e9-49f5-97a6-5b72ec5a1773\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-05-18T19:18:15.894575Z","caller":"traceutil/trace.go:171","msg":"trace[194466764] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f02beba2-f1e9-49f5-97a6-5b72ec5a1773; range_end:; response_count:1; response_revision:25914; }","duration":"167.574687ms","start":"2026-05-18T19:18:15.726991Z","end":"2026-05-18T19:18:15.894566Z","steps":["trace[194466764] 'agreement among raft nodes before linearized reading' (duration: 167.476035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:15.894708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.468405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-18T19:18:15.894721Z","caller":"traceutil/trace.go:171","msg":"trace[1725037927] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25914; }","duration":"166.484195ms","start":"2026-05-18T19:18:15.728232Z","end":"2026-05-18T19:18:15.894717Z","steps":["trace[1725037927] 'agreement among raft nodes before linearized reading' (duration: 166.435534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:16.281851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.542606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T19:18:16.282074Z","caller":"traceutil/trace.go:171","msg":"trace[988154450] transaction","detail":"{read_only:false; response_revision:25915; number_of_response:1; }","duration":"343.659013ms","start":"2026-05-18T19:18:15.938395Z","end":"2026-05-18T19:18:16.282054Z","steps":["trace[988154450] 'process raft request' (duration: 85.738819ms)","trace[988154450] 'compare' (duration: 257.395474ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:16.282176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:15.938381Z","time spent":"343.735235ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:16.282168Z","caller":"traceutil/trace.go:171","msg":"trace[1914332415] linearizableReadLoop","detail":"{readStateIndex:26768; appliedIndex:26765; }","duration":"158.595557ms","start":"2026-05-18T19:18:16.123545Z","end":"2026-05-18T19:18:16.282141Z","steps":["trace[1914332415] 'read index received' (duration: 34.792067ms)","trace[1914332415] 'applied index is now lower than readState.Index' (duration: 123.80202ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:16.282207Z","caller":"traceutil/trace.go:171","msg":"trace[1049758410] transaction","detail":"{read_only:false; response_revision:25917; number_of_response:1; }","duration":"201.557549ms","start":"2026-05-18T19:18:16.080625Z","end":"2026-05-18T19:18:16.282182Z","steps":["trace[1049758410] 'process raft request' (duration: 201.447127ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:16.282198Z","caller":"traceutil/trace.go:171","msg":"trace[1464178016] transaction","detail":"{read_only:false; response_revision:25916; number_of_response:1; }","duration":"297.37819ms","start":"2026-05-18T19:18:15.984787Z","end":"2026-05-18T19:18:16.282165Z","steps":["trace[1464178016] 'process raft request' (duration: 297.183215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:16.28243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.868032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:18:16.282488Z","caller":"traceutil/trace.go:171","msg":"trace[1063603480] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25917; }","duration":"158.957324ms","start":"2026-05-18T19:18:16.123513Z","end":"2026-05-18T19:18:16.28247Z","steps":["trace[1063603480] 'agreement among raft nodes before linearized reading' (duration: 158.802061ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:16.987216Z","caller":"traceutil/trace.go:171","msg":"trace[1511280437] transaction","detail":"{read_only:false; response_revision:25918; number_of_response:1; }","duration":"145.903996ms","start":"2026-05-18T19:18:16.841283Z","end":"2026-05-18T19:18:16.987187Z","steps":["trace[1511280437] 'process raft request' (duration: 145.714613ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:17.043468Z","caller":"traceutil/trace.go:171","msg":"trace[1176445577] transaction","detail":"{read_only:false; response_revision:25919; number_of_response:1; }","duration":"198.554691ms","start":"2026-05-18T19:18:16.84489Z","end":"2026-05-18T19:18:17.043445Z","steps":["trace[1176445577] 'process raft request' (duration: 198.422689ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:18.388385Z","caller":"traceutil/trace.go:171","msg":"trace[422614378] linearizableReadLoop","detail":"{readStateIndex:26785; appliedIndex:26784; }","duration":"100.904566ms","start":"2026-05-18T19:18:18.287469Z","end":"2026-05-18T19:18:18.388374Z","steps":["trace[422614378] 'read index received' (duration: 100.741853ms)","trace[422614378] 'applied index is now lower than readState.Index' (duration: 162.183µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:18.388561Z","caller":"traceutil/trace.go:171","msg":"trace[1794101768] transaction","detail":"{read_only:false; response_revision:25934; number_of_response:1; }","duration":"101.553818ms","start":"2026-05-18T19:18:18.287Z","end":"2026-05-18T19:18:18.388553Z","steps":["trace[1794101768] 'process raft request' (duration: 101.266563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:18.388689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.206973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:18:18.388715Z","caller":"traceutil/trace.go:171","msg":"trace[305489238] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25934; }","duration":"101.244424ms","start":"2026-05-18T19:18:18.287463Z","end":"2026-05-18T19:18:18.388707Z","steps":["trace[305489238] 'agreement among raft nodes before linearized reading' (duration: 101.189652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:18.388845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.572771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-18T19:18:18.388863Z","caller":"traceutil/trace.go:171","msg":"trace[1450482866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25934; }","duration":"100.606111ms","start":"2026-05-18T19:18:18.288253Z","end":"2026-05-18T19:18:18.388859Z","steps":["trace[1450482866] 'agreement among raft nodes before linearized reading' (duration: 100.54278ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:19.517833Z","caller":"traceutil/trace.go:171","msg":"trace[1308940526] transaction","detail":"{read_only:false; response_revision:25940; number_of_response:1; }","duration":"132.683738ms","start":"2026-05-18T19:18:19.385116Z","end":"2026-05-18T19:18:19.5178Z","steps":["trace[1308940526] 'process raft request' (duration: 132.491173ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:19.519084Z","caller":"traceutil/trace.go:171","msg":"trace[1710092016] transaction","detail":"{read_only:false; response_revision:25941; number_of_response:1; }","duration":"131.121137ms","start":"2026-05-18T19:18:19.387932Z","end":"2026-05-18T19:18:19.519053Z","steps":["trace[1710092016] 'process raft request' (duration: 130.71493ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:19.519754Z","caller":"traceutil/trace.go:171","msg":"trace[674127026] transaction","detail":"{read_only:false; response_revision:25943; number_of_response:1; }","duration":"131.407793ms","start":"2026-05-18T19:18:19.388329Z","end":"2026-05-18T19:18:19.519737Z","steps":["trace[674127026] 'process raft request' (duration: 131.314721ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:19.51989Z","caller":"traceutil/trace.go:171","msg":"trace[1453718282] transaction","detail":"{read_only:false; response_revision:25942; number_of_response:1; }","duration":"131.83229ms","start":"2026-05-18T19:18:19.388047Z","end":"2026-05-18T19:18:19.519879Z","steps":["trace[1453718282] 'process raft request' (duration: 131.464314ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:19.519999Z","caller":"traceutil/trace.go:171","msg":"trace[181013589] linearizableReadLoop","detail":"{readStateIndex:26796; appliedIndex:26791; }","duration":"125.525881ms","start":"2026-05-18T19:18:19.394463Z","end":"2026-05-18T19:18:19.519989Z","steps":["trace[181013589] 'read index received' (duration: 123.173607ms)","trace[181013589] 'applied index is now lower than readState.Index' (duration: 2.350974ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:19.520177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.696124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2cb161dc-cfc1-4ac2-bd71-a25db458e82e\" ","response":"range_response_count:1 size:3952"} {"level":"info","ts":"2026-05-18T19:18:19.520216Z","caller":"traceutil/trace.go:171","msg":"trace[514431559] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2cb161dc-cfc1-4ac2-bd71-a25db458e82e; range_end:; response_count:1; response_revision:25944; }","duration":"125.822106ms","start":"2026-05-18T19:18:19.394382Z","end":"2026-05-18T19:18:19.520204Z","steps":["trace[514431559] 'agreement among raft nodes before linearized reading' (duration: 125.646193ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:19.520267Z","caller":"traceutil/trace.go:171","msg":"trace[1341377842] transaction","detail":"{read_only:false; response_revision:25944; number_of_response:1; }","duration":"131.337191ms","start":"2026-05-18T19:18:19.388896Z","end":"2026-05-18T19:18:19.520234Z","steps":["trace[1341377842] 'process raft request' (duration: 130.797951ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:21.623453Z","caller":"traceutil/trace.go:171","msg":"trace[1556683344] linearizableReadLoop","detail":"{readStateIndex:26822; appliedIndex:26821; }","duration":"259.264417ms","start":"2026-05-18T19:18:21.364161Z","end":"2026-05-18T19:18:21.623425Z","steps":["trace[1556683344] 'read index received' (duration: 259.075354ms)","trace[1556683344] 'applied index is now lower than readState.Index' (duration: 188.093µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:21.623521Z","caller":"traceutil/trace.go:171","msg":"trace[560594710] transaction","detail":"{read_only:false; response_revision:25969; number_of_response:1; }","duration":"331.86014ms","start":"2026-05-18T19:18:21.291643Z","end":"2026-05-18T19:18:21.623504Z","steps":["trace[560594710] 'process raft request' (duration: 331.604825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:21.62361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.430781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:18:21.623653Z","caller":"traceutil/trace.go:171","msg":"trace[245127007] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25969; }","duration":"259.490332ms","start":"2026-05-18T19:18:21.364152Z","end":"2026-05-18T19:18:21.623642Z","steps":["trace[245127007] 'agreement among raft nodes before linearized reading' (duration: 259.402441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:21.623657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:21.291605Z","time spent":"331.962221ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:21.627607Z","caller":"traceutil/trace.go:171","msg":"trace[202918469] transaction","detail":"{read_only:false; response_revision:25971; number_of_response:1; }","duration":"103.968104ms","start":"2026-05-18T19:18:21.523603Z","end":"2026-05-18T19:18:21.627571Z","steps":["trace[202918469] 'process raft request' (duration: 103.856792ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:21.627868Z","caller":"traceutil/trace.go:171","msg":"trace[99939302] transaction","detail":"{read_only:false; response_revision:25972; number_of_response:1; }","duration":"102.819303ms","start":"2026-05-18T19:18:21.525031Z","end":"2026-05-18T19:18:21.62785Z","steps":["trace[99939302] 'process raft request' (duration: 102.490526ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:21.628109Z","caller":"traceutil/trace.go:171","msg":"trace[1388644862] transaction","detail":"{read_only:false; response_revision:25970; number_of_response:1; }","duration":"142.231918ms","start":"2026-05-18T19:18:21.485855Z","end":"2026-05-18T19:18:21.628087Z","steps":["trace[1388644862] 'process raft request' (duration: 141.410453ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:21.628377Z","caller":"traceutil/trace.go:171","msg":"trace[1930985574] transaction","detail":"{read_only:false; response_revision:25974; number_of_response:1; }","duration":"100.059771ms","start":"2026-05-18T19:18:21.528305Z","end":"2026-05-18T19:18:21.628365Z","steps":["trace[1930985574] 'process raft request' (duration: 99.741205ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:21.628755Z","caller":"traceutil/trace.go:171","msg":"trace[277263240] transaction","detail":"{read_only:false; response_revision:25975; number_of_response:1; }","duration":"100.415787ms","start":"2026-05-18T19:18:21.528326Z","end":"2026-05-18T19:18:21.628742Z","steps":["trace[277263240] 'process raft request' (duration: 99.978659ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:40.56314Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006976.wal"} {"level":"info","ts":"2026-05-18T19:18:45.318318Z","caller":"traceutil/trace.go:171","msg":"trace[1932924416] transaction","detail":"{read_only:false; response_revision:26180; number_of_response:1; }","duration":"181.865436ms","start":"2026-05-18T19:18:45.136417Z","end":"2026-05-18T19:18:45.318283Z","steps":["trace[1932924416] 'process raft request' (duration: 129.100489ms)","trace[1932924416] 'compare' (duration: 52.377909ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:45.844458Z","caller":"traceutil/trace.go:171","msg":"trace[276395188] transaction","detail":"{read_only:false; response_revision:26181; number_of_response:1; }","duration":"131.144617ms","start":"2026-05-18T19:18:45.713205Z","end":"2026-05-18T19:18:45.844349Z","steps":["trace[276395188] 'process raft request' (duration: 130.915442ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:46.173954Z","caller":"traceutil/trace.go:171","msg":"trace[2014047410] transaction","detail":"{read_only:false; response_revision:26182; number_of_response:1; }","duration":"456.161479ms","start":"2026-05-18T19:18:45.71776Z","end":"2026-05-18T19:18:46.173921Z","steps":["trace[2014047410] 'process raft request' (duration: 455.878354ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:46.17397Z","caller":"traceutil/trace.go:171","msg":"trace[142030543] transaction","detail":"{read_only:false; response_revision:26184; number_of_response:1; }","duration":"446.019067ms","start":"2026-05-18T19:18:45.727913Z","end":"2026-05-18T19:18:46.173932Z","steps":["trace[142030543] 'process raft request' (duration: 445.955366ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:46.174Z","caller":"traceutil/trace.go:171","msg":"trace[1355861834] transaction","detail":"{read_only:false; response_revision:26183; number_of_response:1; }","duration":"451.274087ms","start":"2026-05-18T19:18:45.722695Z","end":"2026-05-18T19:18:46.17397Z","steps":["trace[1355861834] 'process raft request' (duration: 451.132894ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:46.174104Z","caller":"traceutil/trace.go:171","msg":"trace[1574968930] linearizableReadLoop","detail":"{readStateIndex:27043; appliedIndex:27039; }","duration":"287.094194ms","start":"2026-05-18T19:18:45.886988Z","end":"2026-05-18T19:18:46.174082Z","steps":["trace[1574968930] 'read index received' (duration: 286.771328ms)","trace[1574968930] 'applied index is now lower than readState.Index' (duration: 319.806µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:46.17412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:45.722685Z","time spent":"451.368208ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:46.174138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:45.727904Z","time spent":"446.140869ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:46.174171Z","caller":"traceutil/trace.go:171","msg":"trace[1279467621] transaction","detail":"{read_only:false; response_revision:26185; number_of_response:1; }","duration":"442.925068ms","start":"2026-05-18T19:18:45.731238Z","end":"2026-05-18T19:18:46.174163Z","steps":["trace[1279467621] 'process raft request' (duration: 442.660553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:46.174121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:45.717753Z","time spent":"456.283141ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:46.174208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:45.731232Z","time spent":"442.956129ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:46.174414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.400399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-18T19:18:46.174522Z","caller":"traceutil/trace.go:171","msg":"trace[1203402388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26185; }","duration":"287.549263ms","start":"2026-05-18T19:18:45.886948Z","end":"2026-05-18T19:18:46.174497Z","steps":["trace[1203402388] 'agreement among raft nodes before linearized reading' (duration: 287.223177ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:46.422435Z","caller":"traceutil/trace.go:171","msg":"trace[1135042408] linearizableReadLoop","detail":"{readStateIndex:27044; appliedIndex:27043; }","duration":"248.262771ms","start":"2026-05-18T19:18:46.17415Z","end":"2026-05-18T19:18:46.422413Z","steps":["trace[1135042408] 'read index received' (duration: 235.139893ms)","trace[1135042408] 'applied index is now lower than readState.Index' (duration: 13.122078ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:46.422768Z","caller":"traceutil/trace.go:171","msg":"trace[988177847] transaction","detail":"{read_only:false; response_revision:26186; number_of_response:1; }","duration":"298.089763ms","start":"2026-05-18T19:18:46.124648Z","end":"2026-05-18T19:18:46.422738Z","steps":["trace[988177847] 'process raft request' (duration: 284.426805ms)","trace[988177847] 'compare' (duration: 13.24187ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:46.422857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.33844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-18T19:18:46.42289Z","caller":"traceutil/trace.go:171","msg":"trace[2093969838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26186; }","duration":"367.421642ms","start":"2026-05-18T19:18:46.055455Z","end":"2026-05-18T19:18:46.422877Z","steps":["trace[2093969838] 'agreement among raft nodes before linearized reading' (duration: 367.291489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:46.422923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.055432Z","time spent":"367.484453ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T19:18:46.423179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.052796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-18T19:18:46.423302Z","caller":"traceutil/trace.go:171","msg":"trace[1968964533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26186; }","duration":"384.242189ms","start":"2026-05-18T19:18:46.039038Z","end":"2026-05-18T19:18:46.42328Z","steps":["trace[1968964533] 'agreement among raft nodes before linearized reading' (duration: 383.915633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:46.423279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.750248ms","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-05-18T19:18:46.423388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.039013Z","time spent":"384.362752ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-18T19:18:46.423441Z","caller":"traceutil/trace.go:171","msg":"trace[1517915670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26186; }","duration":"367.954262ms","start":"2026-05-18T19:18:46.05547Z","end":"2026-05-18T19:18:46.423424Z","steps":["trace[1517915670] 'agreement among raft nodes before linearized reading' (duration: 367.255659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:46.423501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.055453Z","time spent":"368.038993ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-18T19:18:46.423993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.708797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-18T19:18:46.424044Z","caller":"traceutil/trace.go:171","msg":"trace[191474721] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26186; }","duration":"304.823389ms","start":"2026-05-18T19:18:46.119211Z","end":"2026-05-18T19:18:46.424034Z","steps":["trace[191474721] 'agreement among raft nodes before linearized reading' (duration: 303.523045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:46.424071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.11919Z","time spent":"304.871501ms","remote":"127.0.0.1:41538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2665,"response size":33,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-18T19:18:46.966142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.918738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T19:18:46.966344Z","caller":"traceutil/trace.go:171","msg":"trace[1675943746] linearizableReadLoop","detail":"{readStateIndex:27046; appliedIndex:27044; }","duration":"543.837205ms","start":"2026-05-18T19:18:46.42249Z","end":"2026-05-18T19:18:46.966328Z","steps":["trace[1675943746] 'read index received' (duration: 254.824164ms)","trace[1675943746] 'applied index is now lower than readState.Index' (duration: 289.011911ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:46.966366Z","caller":"traceutil/trace.go:171","msg":"trace[167167588] transaction","detail":"{read_only:false; response_revision:26187; number_of_response:1; }","duration":"787.907006ms","start":"2026-05-18T19:18:46.178403Z","end":"2026-05-18T19:18:46.96631Z","steps":["trace[167167588] 'process raft request' (duration: 498.749593ms)","trace[167167588] 'compare' (duration: 288.339328ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:46.966376Z","caller":"traceutil/trace.go:171","msg":"trace[660882920] transaction","detail":"{read_only:false; response_revision:26188; number_of_response:1; }","duration":"677.49935ms","start":"2026-05-18T19:18:46.28882Z","end":"2026-05-18T19:18:46.966319Z","steps":["trace[660882920] 'process raft request' (duration: 677.411769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:46.966568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.178385Z","time spent":"788.08368ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:46.966598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.288796Z","time spent":"677.688654ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:46.966799Z","caller":"traceutil/trace.go:171","msg":"trace[1296385480] transaction","detail":"{read_only:false; response_revision:26189; number_of_response:1; }","duration":"539.2843ms","start":"2026-05-18T19:18:46.427458Z","end":"2026-05-18T19:18:46.966742Z","steps":["trace[1296385480] 'process raft request' (duration: 538.845102ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:46.966858Z","caller":"traceutil/trace.go:171","msg":"trace[1125959198] transaction","detail":"{read_only:false; response_revision:26190; number_of_response:1; }","duration":"539.375642ms","start":"2026-05-18T19:18:46.427455Z","end":"2026-05-18T19:18:46.966831Z","steps":["trace[1125959198] 'process raft request' (duration: 539.103117ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:46.966883Z","caller":"traceutil/trace.go:171","msg":"trace[287413123] transaction","detail":"{read_only:false; response_revision:26191; number_of_response:1; }","duration":"538.127408ms","start":"2026-05-18T19:18:46.42874Z","end":"2026-05-18T19:18:46.966867Z","steps":["trace[287413123] 'process raft request' (duration: 538.001095ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:46.966899Z","caller":"traceutil/trace.go:171","msg":"trace[1335153250] transaction","detail":"{read_only:false; response_revision:26192; number_of_response:1; }","duration":"486.828858ms","start":"2026-05-18T19:18:46.480061Z","end":"2026-05-18T19:18:46.966889Z","steps":["trace[1335153250] 'process raft request' (duration: 486.723476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:46.966945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.480042Z","time spent":"486.8798ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:46.966943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.42743Z","time spent":"539.477503ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:46.966954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.428717Z","time spent":"538.200299ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:46.96695Z","caller":"traceutil/trace.go:171","msg":"trace[1869369265] transaction","detail":"{read_only:false; response_revision:26193; number_of_response:1; }","duration":"307.973049ms","start":"2026-05-18T19:18:46.658946Z","end":"2026-05-18T19:18:46.966919Z","steps":["trace[1869369265] 'process raft request' (duration: 307.895467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:46.967007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.427445Z","time spent":"539.458283ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:46.967074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.647771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T19:18:46.967078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.658918Z","time spent":"308.099401ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:46.967128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.474297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-18T19:18:46.967145Z","caller":"traceutil/trace.go:171","msg":"trace[1329839332] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:26193; }","duration":"254.777033ms","start":"2026-05-18T19:18:46.71235Z","end":"2026-05-18T19:18:46.967127Z","steps":["trace[1329839332] 'agreement among raft nodes before linearized reading' (duration: 254.627011ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:46.967162Z","caller":"traceutil/trace.go:171","msg":"trace[1662360323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26193; }","duration":"381.543599ms","start":"2026-05-18T19:18:46.585608Z","end":"2026-05-18T19:18:46.967152Z","steps":["trace[1662360323] 'agreement among raft nodes before linearized reading' (duration: 381.401936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:46.967172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.995634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-05-18T19:18:46.96712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.051877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18417"} {"level":"info","ts":"2026-05-18T19:18:46.967201Z","caller":"traceutil/trace.go:171","msg":"trace[1517897550] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26193; }","duration":"520.040775ms","start":"2026-05-18T19:18:46.447151Z","end":"2026-05-18T19:18:46.967192Z","steps":["trace[1517897550] 'agreement among raft nodes before linearized reading' (duration: 519.915552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:46.967215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.636234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"warn","ts":"2026-05-18T19:18:46.967232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.447137Z","time spent":"520.089736ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-18T19:18:46.967255Z","caller":"traceutil/trace.go:171","msg":"trace[1723167873] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:26193; }","duration":"224.705635ms","start":"2026-05-18T19:18:46.742531Z","end":"2026-05-18T19:18:46.967236Z","steps":["trace[1723167873] 'agreement among raft nodes before linearized reading' (duration: 224.539952ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:46.96725Z","caller":"traceutil/trace.go:171","msg":"trace[1724373526] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:26193; }","duration":"536.237571ms","start":"2026-05-18T19:18:46.430986Z","end":"2026-05-18T19:18:46.967223Z","steps":["trace[1724373526] 'agreement among raft nodes before linearized reading' (duration: 535.928525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:46.967312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.430974Z","time spent":"536.325613ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":18441,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-05-18T19:18:46.967273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.672556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:18:46.967433Z","caller":"traceutil/trace.go:171","msg":"trace[551372540] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26189; }","duration":"729.89297ms","start":"2026-05-18T19:18:46.237489Z","end":"2026-05-18T19:18:46.967382Z","steps":["trace[551372540] 'agreement among raft nodes before linearized reading' (duration: 728.922702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:46.967195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.585585Z","time spent":"381.60531ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-18T19:18:46.967531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.237465Z","time spent":"730.008322ms","remote":"127.0.0.1:36648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-05-18T19:18:47.52375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.027225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T19:18:47.523882Z","caller":"traceutil/trace.go:171","msg":"trace[294717725] linearizableReadLoop","detail":"{readStateIndex:27052; appliedIndex:27051; }","duration":"551.205755ms","start":"2026-05-18T19:18:46.972657Z","end":"2026-05-18T19:18:47.523863Z","steps":["trace[294717725] 'read index received' (duration: 364.966165ms)","trace[294717725] 'applied index is now lower than readState.Index' (duration: 186.23757ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:47.523966Z","caller":"traceutil/trace.go:171","msg":"trace[750315846] transaction","detail":"{read_only:false; response_revision:26194; number_of_response:1; }","duration":"551.333127ms","start":"2026-05-18T19:18:46.972621Z","end":"2026-05-18T19:18:47.523954Z","steps":["trace[750315846] 'process raft request' (duration: 364.985486ms)","trace[750315846] 'compare' (duration: 185.686969ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:47.524035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.972604Z","time spent":"551.395908ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:47.52467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.991729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18417"} {"level":"info","ts":"2026-05-18T19:18:47.52477Z","caller":"traceutil/trace.go:171","msg":"trace[1963026425] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26194; }","duration":"552.126572ms","start":"2026-05-18T19:18:46.972625Z","end":"2026-05-18T19:18:47.524752Z","steps":["trace[1963026425] 'agreement among raft nodes before linearized reading' (duration: 551.897048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:47.524821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:46.972609Z","time spent":"552.193843ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18441,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-18T19:18:47.570918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.950857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:18:47.570974Z","caller":"traceutil/trace.go:171","msg":"trace[1944740911] transaction","detail":"{read_only:false; response_revision:26195; number_of_response:1; }","duration":"436.522638ms","start":"2026-05-18T19:18:47.134421Z","end":"2026-05-18T19:18:47.570944Z","steps":["trace[1944740911] 'process raft request' (duration: 436.034568ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:47.571034Z","caller":"traceutil/trace.go:171","msg":"trace[1724320922] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:26196; }","duration":"563.090629ms","start":"2026-05-18T19:18:47.007892Z","end":"2026-05-18T19:18:47.570982Z","steps":["trace[1724320922] 'agreement among raft nodes before linearized reading' (duration: 562.890125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:47.571128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:47.134398Z","time spent":"436.65226ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:47.57118Z","caller":"traceutil/trace.go:171","msg":"trace[912402459] transaction","detail":"{read_only:false; response_revision:26196; number_of_response:1; }","duration":"247.350855ms","start":"2026-05-18T19:18:47.323819Z","end":"2026-05-18T19:18:47.57117Z","steps":["trace[912402459] 'process raft request' (duration: 246.779794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:47.571135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:47.007806Z","time spent":"563.312853ms","remote":"127.0.0.1:33044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"warn","ts":"2026-05-18T19:18:47.899862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.678049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:18:47.899926Z","caller":"traceutil/trace.go:171","msg":"trace[1619003124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26196; }","duration":"202.787631ms","start":"2026-05-18T19:18:47.697122Z","end":"2026-05-18T19:18:47.89991Z","steps":["trace[1619003124] 'range keys from in-memory index tree' (duration: 202.584368ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:48.603867Z","caller":"traceutil/trace.go:171","msg":"trace[2061868278] linearizableReadLoop","detail":"{readStateIndex:27059; appliedIndex:27056; }","duration":"265.568487ms","start":"2026-05-18T19:18:48.338274Z","end":"2026-05-18T19:18:48.603842Z","steps":["trace[2061868278] 'read index received' (duration: 265.292282ms)","trace[2061868278] 'applied index is now lower than readState.Index' (duration: 275.075µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:48.603906Z","caller":"traceutil/trace.go:171","msg":"trace[547819145] transaction","detail":"{read_only:false; response_revision:26200; number_of_response:1; }","duration":"423.976841ms","start":"2026-05-18T19:18:48.179896Z","end":"2026-05-18T19:18:48.603872Z","steps":["trace[547819145] 'process raft request' (duration: 423.865108ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:48.604003Z","caller":"traceutil/trace.go:171","msg":"trace[1226446951] transaction","detail":"{read_only:false; response_revision:26201; number_of_response:1; }","duration":"424.084262ms","start":"2026-05-18T19:18:48.17991Z","end":"2026-05-18T19:18:48.603994Z","steps":["trace[1226446951] 'process raft request' (duration: 423.892518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:48.604078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:48.179885Z","time spent":"424.091212ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:48.604114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:48.179892Z","time spent":"424.135243ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:48.604172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.572877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T19:18:48.60425Z","caller":"traceutil/trace.go:171","msg":"trace[1040380106] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:26201; }","duration":"156.710931ms","start":"2026-05-18T19:18:48.447519Z","end":"2026-05-18T19:18:48.60423Z","steps":["trace[1040380106] 'agreement among raft nodes before linearized reading' (duration: 156.511947ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:48.60423Z","caller":"traceutil/trace.go:171","msg":"trace[890894173] transaction","detail":"{read_only:false; response_revision:26199; number_of_response:1; }","duration":"424.387889ms","start":"2026-05-18T19:18:48.17981Z","end":"2026-05-18T19:18:48.604198Z","steps":["trace[890894173] 'process raft request' (duration: 423.773776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:48.604306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.020126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-05-18T19:18:48.604407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:48.17978Z","time spent":"424.542422ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:48.604407Z","caller":"traceutil/trace.go:171","msg":"trace[1049692178] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:26201; }","duration":"266.177359ms","start":"2026-05-18T19:18:48.338207Z","end":"2026-05-18T19:18:48.604384Z","steps":["trace[1049692178] 'agreement among raft nodes before linearized reading' (duration: 265.866882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:49.620555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.802518ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-18T19:18:49.621458Z","caller":"traceutil/trace.go:171","msg":"trace[1022921558] linearizableReadLoop","detail":"{readStateIndex:27061; appliedIndex:27059; }","duration":"662.832454ms","start":"2026-05-18T19:18:48.958599Z","end":"2026-05-18T19:18:49.621431Z","steps":["trace[1022921558] 'read index received' (duration: 51.491µs)","trace[1022921558] 'applied index is now lower than readState.Index' (duration: 662.779433ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:49.621576Z","caller":"traceutil/trace.go:171","msg":"trace[129982142] transaction","detail":"{read_only:false; response_revision:26202; number_of_response:1; }","duration":"870.542878ms","start":"2026-05-18T19:18:48.75102Z","end":"2026-05-18T19:18:49.621563Z","steps":["trace[129982142] 'process raft request' (duration: 869.61665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:49.621683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:48.750994Z","time spent":"870.608929ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:49.723665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.034454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T19:18:49.723759Z","caller":"traceutil/trace.go:171","msg":"trace[343956873] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:26202; }","duration":"765.181197ms","start":"2026-05-18T19:18:48.95855Z","end":"2026-05-18T19:18:49.723731Z","steps":["trace[343956873] 'agreement among raft nodes before linearized reading' (duration: 663.326643ms)","trace[343956873] 'count revisions from in-memory index tree' (duration: 101.724292ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:49.723805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:48.958526Z","time spent":"765.267328ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":75,"response size":32,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-18T19:18:49.724544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.049256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T19:18:49.724607Z","caller":"traceutil/trace.go:171","msg":"trace[443830308] transaction","detail":"{read_only:false; response_revision:26203; number_of_response:1; }","duration":"748.051573ms","start":"2026-05-18T19:18:48.976542Z","end":"2026-05-18T19:18:49.724594Z","steps":["trace[443830308] 'process raft request' (duration: 644.881965ms)","trace[443830308] 'compare' (duration: 101.713261ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:49.724675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:48.976519Z","time spent":"748.113575ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:49.812624Z","caller":"traceutil/trace.go:171","msg":"trace[2135107976] transaction","detail":"{read_only:false; response_revision:26204; number_of_response:1; }","duration":"235.53063ms","start":"2026-05-18T19:18:49.577046Z","end":"2026-05-18T19:18:49.812577Z","steps":["trace[2135107976] 'process raft request' (duration: 235.206374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:50.219371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.831154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T19:18:50.219667Z","caller":"traceutil/trace.go:171","msg":"trace[2045517660] transaction","detail":"{read_only:false; response_revision:26206; number_of_response:1; }","duration":"312.976414ms","start":"2026-05-18T19:18:49.906665Z","end":"2026-05-18T19:18:50.219642Z","steps":["trace[2045517660] 'process raft request' (duration: 312.844461ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:50.219677Z","caller":"traceutil/trace.go:171","msg":"trace[1989242442] linearizableReadLoop","detail":"{readStateIndex:27064; appliedIndex:27062; }","duration":"598.11798ms","start":"2026-05-18T19:18:49.621523Z","end":"2026-05-18T19:18:50.219641Z","steps":["trace[1989242442] 'read index received' (duration: 190.769324ms)","trace[1989242442] 'applied index is now lower than readState.Index' (duration: 407.343365ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:50.219754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:49.906631Z","time spent":"313.076366ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T19:18:50.219828Z","caller":"traceutil/trace.go:171","msg":"trace[595380146] transaction","detail":"{read_only:false; response_revision:26205; number_of_response:1; }","duration":"642.080921ms","start":"2026-05-18T19:18:49.577731Z","end":"2026-05-18T19:18:50.219812Z","steps":["trace[595380146] 'process raft request' (duration: 458.737227ms)","trace[595380146] 'compare' (duration: 182.04365ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:50.219958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:49.577708Z","time spent":"642.150713ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:50.220145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2136245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:18:50.220194Z","caller":"traceutil/trace.go:171","msg":"trace[1761330304] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:26206; }","duration":"1.21370562s","start":"2026-05-18T19:18:49.006469Z","end":"2026-05-18T19:18:50.220175Z","steps":["trace[1761330304] 'agreement among raft nodes before linearized reading' (duration: 1.213613309s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:50.220239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:49.006452Z","time spent":"1.213777493s","remote":"127.0.0.1:33012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-05-18T19:18:50.22023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.751496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T19:18:50.220276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246549622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-18T19:18:50.220278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077280973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:18:50.220313Z","caller":"traceutil/trace.go:171","msg":"trace[1155590225] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26206; }","duration":"893.900479ms","start":"2026-05-18T19:18:49.326389Z","end":"2026-05-18T19:18:50.22029Z","steps":["trace[1155590225] 'agreement among raft nodes before linearized reading' (duration: 893.723595ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:50.220348Z","caller":"traceutil/trace.go:171","msg":"trace[1387076121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26206; }","duration":"1.246637063s","start":"2026-05-18T19:18:48.973692Z","end":"2026-05-18T19:18:50.220329Z","steps":["trace[1387076121] 'agreement among raft nodes before linearized reading' (duration: 1.246396699s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:50.220288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.082232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T19:18:50.220361Z","caller":"traceutil/trace.go:171","msg":"trace[2050884709] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:26206; }","duration":"1.077407716s","start":"2026-05-18T19:18:49.142931Z","end":"2026-05-18T19:18:50.220339Z","steps":["trace[2050884709] 'agreement among raft nodes before linearized reading' (duration: 1.077200842s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:50.22038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:48.973688Z","time spent":"1.246685724s","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-18T19:18:50.220373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:49.326368Z","time spent":"893.992071ms","remote":"127.0.0.1:36930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":30,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"warn","ts":"2026-05-18T19:18:50.220376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246727585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-18T19:18:50.220337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243439473s","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-05-18T19:18:50.220408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:49.142911Z","time spent":"1.077484257s","remote":"127.0.0.1:57480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"warn","ts":"2026-05-18T19:18:50.220417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.003903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-18T19:18:50.220443Z","caller":"traceutil/trace.go:171","msg":"trace[1789377871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26206; }","duration":"1.246820717s","start":"2026-05-18T19:18:48.973601Z","end":"2026-05-18T19:18:50.220422Z","steps":["trace[1789377871] 'agreement among raft nodes before linearized reading' (duration: 1.246588833s)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:50.220422Z","caller":"traceutil/trace.go:171","msg":"trace[16067376] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:26206; }","duration":"849.296287ms","start":"2026-05-18T19:18:49.371094Z","end":"2026-05-18T19:18:50.220391Z","steps":["trace[16067376] 'agreement among raft nodes before linearized reading' (duration: 849.000181ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:50.220456Z","caller":"traceutil/trace.go:171","msg":"trace[2032147143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26206; }","duration":"693.076115ms","start":"2026-05-18T19:18:49.52737Z","end":"2026-05-18T19:18:50.220446Z","steps":["trace[2032147143] 'agreement among raft nodes before linearized reading' (duration: 692.936062ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:50.220456Z","caller":"traceutil/trace.go:171","msg":"trace[1278895747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26206; }","duration":"1.243597285s","start":"2026-05-18T19:18:48.976835Z","end":"2026-05-18T19:18:50.220432Z","steps":["trace[1278895747] 'agreement among raft nodes before linearized reading' (duration: 1.24330075s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:50.22048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:48.973579Z","time spent":"1.246893058s","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-18T19:18:50.22049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:49.527355Z","time spent":"693.128735ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-18T19:18:50.22051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:48.976826Z","time spent":"1.243674337s","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-18T19:18:50.220515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:49.371074Z","time spent":"849.421228ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":32,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-05-18T19:18:50.22075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247026722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-18T19:18:50.220889Z","caller":"traceutil/trace.go:171","msg":"trace[1515350992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26206; }","duration":"1.247195105s","start":"2026-05-18T19:18:48.973653Z","end":"2026-05-18T19:18:50.220848Z","steps":["trace[1515350992] 'agreement among raft nodes before linearized reading' (duration: 1.246783936s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:50.220949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:48.973649Z","time spent":"1.247285687s","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T19:18:50.267917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.718615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T19:18:50.267981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.210735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-5c59cc5cc6-5ljbm.18b0bf00e9c2c7ac\" ","response":"range_response_count:1 size:890"} {"level":"info","ts":"2026-05-18T19:18:50.268035Z","caller":"traceutil/trace.go:171","msg":"trace[1396929960] range","detail":"{range_begin:/registry/events/openstack/magnum-api-5c59cc5cc6-5ljbm.18b0bf00e9c2c7ac; range_end:; response_count:1; response_revision:26207; }","duration":"550.307456ms","start":"2026-05-18T19:18:49.71771Z","end":"2026-05-18T19:18:50.268018Z","steps":["trace[1396929960] 'agreement among raft nodes before linearized reading' (duration: 550.134513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:50.268069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:49.717689Z","time spent":"550.374838ms","remote":"127.0.0.1:41538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":914,"request content":"key:\"/registry/events/openstack/magnum-api-5c59cc5cc6-5ljbm.18b0bf00e9c2c7ac\" "} {"level":"info","ts":"2026-05-18T19:18:50.268062Z","caller":"traceutil/trace.go:171","msg":"trace[1989866379] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26207; }","duration":"531.827848ms","start":"2026-05-18T19:18:49.736148Z","end":"2026-05-18T19:18:50.267976Z","steps":["trace[1989866379] 'agreement among raft nodes before linearized reading' (duration: 531.647564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:50.26805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.534518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T19:18:50.268115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:49.736137Z","time spent":"531.96354ms","remote":"127.0.0.1:37108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":30,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"info","ts":"2026-05-18T19:18:50.268127Z","caller":"traceutil/trace.go:171","msg":"trace[226541947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26207; }","duration":"570.6212ms","start":"2026-05-18T19:18:49.697487Z","end":"2026-05-18T19:18:50.268108Z","steps":["trace[226541947] 'agreement among raft nodes before linearized reading' (duration: 570.492698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:50.268182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:49.697415Z","time spent":"570.751783ms","remote":"127.0.0.1:41452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-18T19:18:50.510241Z","caller":"traceutil/trace.go:171","msg":"trace[2002139499] transaction","detail":"{read_only:false; response_revision:26209; number_of_response:1; }","duration":"283.559128ms","start":"2026-05-18T19:18:50.226647Z","end":"2026-05-18T19:18:50.510207Z","steps":["trace[2002139499] 'process raft request' (duration: 283.399855ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:50.510209Z","caller":"traceutil/trace.go:171","msg":"trace[396221163] transaction","detail":"{read_only:false; response_revision:26208; number_of_response:1; }","duration":"284.926384ms","start":"2026-05-18T19:18:50.225225Z","end":"2026-05-18T19:18:50.510151Z","steps":["trace[396221163] 'process raft request' (duration: 273.955967ms)","trace[396221163] 'compare' (duration: 10.57378ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:50.510474Z","caller":"traceutil/trace.go:171","msg":"trace[1193751545] transaction","detail":"{read_only:false; response_revision:26211; number_of_response:1; }","duration":"283.024527ms","start":"2026-05-18T19:18:50.22742Z","end":"2026-05-18T19:18:50.510445Z","steps":["trace[1193751545] 'process raft request' (duration: 282.783133ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:50.510532Z","caller":"traceutil/trace.go:171","msg":"trace[959808227] transaction","detail":"{read_only:false; response_revision:26210; number_of_response:1; }","duration":"283.809143ms","start":"2026-05-18T19:18:50.226704Z","end":"2026-05-18T19:18:50.510514Z","steps":["trace[959808227] 'process raft request' (duration: 283.436796ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:50.510354Z","caller":"traceutil/trace.go:171","msg":"trace[895424504] linearizableReadLoop","detail":"{readStateIndex:27071; appliedIndex:27066; }","duration":"242.615954ms","start":"2026-05-18T19:18:50.267715Z","end":"2026-05-18T19:18:50.510331Z","steps":["trace[895424504] 'read index received' (duration: 231.492104ms)","trace[895424504] 'applied index is now lower than readState.Index' (duration: 11.12255ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:50.510821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.163597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:18:50.51088Z","caller":"traceutil/trace.go:171","msg":"trace[394828116] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:26212; }","duration":"259.297299ms","start":"2026-05-18T19:18:50.251567Z","end":"2026-05-18T19:18:50.510865Z","steps":["trace[394828116] 'agreement among raft nodes before linearized reading' (duration: 259.181197ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:50.510812Z","caller":"traceutil/trace.go:171","msg":"trace[231426965] transaction","detail":"{read_only:false; response_revision:26212; number_of_response:1; }","duration":"282.098591ms","start":"2026-05-18T19:18:50.228426Z","end":"2026-05-18T19:18:50.510525Z","steps":["trace[231426965] 'process raft request' (duration: 281.854856ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:50.682689Z","caller":"traceutil/trace.go:171","msg":"trace[1304840875] linearizableReadLoop","detail":"{readStateIndex:27072; appliedIndex:27071; }","duration":"171.936269ms","start":"2026-05-18T19:18:50.510719Z","end":"2026-05-18T19:18:50.682655Z","steps":["trace[1304840875] 'read index received' (duration: 120.533098ms)","trace[1304840875] 'applied index is now lower than readState.Index' (duration: 51.401791ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:50.682777Z","caller":"traceutil/trace.go:171","msg":"trace[1376236153] transaction","detail":"{read_only:false; response_revision:26213; number_of_response:1; }","duration":"411.942703ms","start":"2026-05-18T19:18:50.270781Z","end":"2026-05-18T19:18:50.682724Z","steps":["trace[1376236153] 'process raft request' (duration: 360.474581ms)","trace[1376236153] 'compare' (duration: 51.221807ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T19:18:50.682873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.586075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:18:50.682972Z","caller":"traceutil/trace.go:171","msg":"trace[1807362357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26213; }","duration":"413.764268ms","start":"2026-05-18T19:18:50.269194Z","end":"2026-05-18T19:18:50.682958Z","steps":["trace[1807362357] 'agreement among raft nodes before linearized reading' (duration: 413.577014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:18:50.682999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:50.270741Z","time spent":"412.140768ms","remote":"127.0.0.1:41538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":872,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T19:18:50.683032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T19:18:50.26917Z","time spent":"413.839479ms","remote":"127.0.0.1:41464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-18T19:18:50.683242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.825415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T19:18:50.683326Z","caller":"traceutil/trace.go:171","msg":"trace[993546085] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:26213; }","duration":"236.935668ms","start":"2026-05-18T19:18:50.446365Z","end":"2026-05-18T19:18:50.683301Z","steps":["trace[993546085] 'agreement among raft nodes before linearized reading' (duration: 236.803845ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:50.733569Z","caller":"traceutil/trace.go:171","msg":"trace[1284298462] transaction","detail":"{read_only:false; response_revision:26215; number_of_response:1; }","duration":"123.068405ms","start":"2026-05-18T19:18:50.610481Z","end":"2026-05-18T19:18:50.733549Z","steps":["trace[1284298462] 'process raft request' (duration: 123.013374ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:50.733589Z","caller":"traceutil/trace.go:171","msg":"trace[637023186] transaction","detail":"{read_only:false; response_revision:26214; number_of_response:1; }","duration":"124.684146ms","start":"2026-05-18T19:18:50.608869Z","end":"2026-05-18T19:18:50.733554Z","steps":["trace[637023186] 'process raft request' (duration: 124.492502ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:50.733802Z","caller":"traceutil/trace.go:171","msg":"trace[635670606] transaction","detail":"{read_only:false; response_revision:26216; number_of_response:1; }","duration":"123.256719ms","start":"2026-05-18T19:18:50.610514Z","end":"2026-05-18T19:18:50.733771Z","steps":["trace[635670606] 'process raft request' (duration: 122.999614ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:54.341665Z","caller":"traceutil/trace.go:171","msg":"trace[936182719] transaction","detail":"{read_only:false; response_revision:26242; number_of_response:1; }","duration":"104.755619ms","start":"2026-05-18T19:18:54.236877Z","end":"2026-05-18T19:18:54.341632Z","steps":["trace[936182719] 'process raft request' (duration: 77.537945ms)","trace[936182719] 'compare' (duration: 26.876017ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:18:56.916641Z","caller":"traceutil/trace.go:171","msg":"trace[1302787282] transaction","detail":"{read_only:false; response_revision:26265; number_of_response:1; }","duration":"160.312718ms","start":"2026-05-18T19:18:56.756299Z","end":"2026-05-18T19:18:56.916612Z","steps":["trace[1302787282] 'process raft request' (duration: 160.013333ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:56.91668Z","caller":"traceutil/trace.go:171","msg":"trace[1927298099] transaction","detail":"{read_only:false; response_revision:26266; number_of_response:1; }","duration":"159.185218ms","start":"2026-05-18T19:18:56.757459Z","end":"2026-05-18T19:18:56.916644Z","steps":["trace[1927298099] 'process raft request' (duration: 159.007655ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:18:59.994477Z","caller":"traceutil/trace.go:171","msg":"trace[1368798807] transaction","detail":"{read_only:false; response_revision:26288; number_of_response:1; }","duration":"106.770647ms","start":"2026-05-18T19:18:59.887677Z","end":"2026-05-18T19:18:59.994448Z","steps":["trace[1368798807] 'process raft request' (duration: 100.766484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:19:12.948496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.556296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T19:19:12.948681Z","caller":"traceutil/trace.go:171","msg":"trace[1945644894] transaction","detail":"{read_only:false; response_revision:26389; number_of_response:1; }","duration":"182.773934ms","start":"2026-05-18T19:19:12.765888Z","end":"2026-05-18T19:19:12.948662Z","steps":["trace[1945644894] 'process raft request' (duration: 68.963554ms)","trace[1945644894] 'compare' (duration: 113.450264ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:19:13.199188Z","caller":"traceutil/trace.go:171","msg":"trace[2120540332] linearizableReadLoop","detail":"{readStateIndex:27256; appliedIndex:27254; }","duration":"201.788913ms","start":"2026-05-18T19:19:12.997369Z","end":"2026-05-18T19:19:13.199158Z","steps":["trace[2120540332] 'read index received' (duration: 39.173741ms)","trace[2120540332] 'applied index is now lower than readState.Index' (duration: 162.613742ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T19:19:13.199273Z","caller":"traceutil/trace.go:171","msg":"trace[471676440] transaction","detail":"{read_only:false; response_revision:26392; number_of_response:1; }","duration":"219.571988ms","start":"2026-05-18T19:19:12.979657Z","end":"2026-05-18T19:19:13.199229Z","steps":["trace[471676440] 'process raft request' (duration: 219.322244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T19:19:13.199524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.124589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-05-18T19:19:13.199539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.892851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-18T19:19:13.19962Z","caller":"traceutil/trace.go:171","msg":"trace[2012745203] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26392; }","duration":"202.260231ms","start":"2026-05-18T19:19:12.997329Z","end":"2026-05-18T19:19:13.19959Z","steps":["trace[2012745203] 'agreement among raft nodes before linearized reading' (duration: 201.977806ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:19:13.199621Z","caller":"traceutil/trace.go:171","msg":"trace[2123316333] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26392; }","duration":"114.019813ms","start":"2026-05-18T19:19:13.085579Z","end":"2026-05-18T19:19:13.199599Z","steps":["trace[2123316333] 'agreement among raft nodes before linearized reading' (duration: 113.769808ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T19:22:00.038134Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25126} {"level":"info","ts":"2026-05-18T19:22:00.179248Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25126,"took":"136.384496ms","hash":3323710308,"current-db-size-bytes":59654144,"current-db-size":"60 MB","current-db-size-in-use-bytes":38936576,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-05-18T19:22:00.179319Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3323710308,"revision":25126,"compact-revision":21913} {"level":"info","ts":"2026-05-18T19:23:42.091669Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":30004,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-18T19:23:42.097014Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-05-18T19:23:42.09709Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-05-18T19:27:00.046324Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28192} {"level":"info","ts":"2026-05-18T19:27:00.183886Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28192,"took":"132.336761ms","hash":1709795252,"current-db-size-bytes":59654144,"current-db-size":"60 MB","current-db-size-in-use-bytes":32993280,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-18T19:27:00.183941Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1709795252,"revision":28192,"compact-revision":25126} {"level":"info","ts":"2026-05-18T19:32:00.053943Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30567} {"level":"info","ts":"2026-05-18T19:32:00.190726Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30567,"took":"132.126483ms","hash":2414704901,"current-db-size-bytes":59654144,"current-db-size":"60 MB","current-db-size-in-use-bytes":30343168,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-18T19:32:00.190802Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2414704901,"revision":30567,"compact-revision":28192} {"level":"info","ts":"2026-05-18T19:37:00.059972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32836} {"level":"info","ts":"2026-05-18T19:37:00.215791Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32836,"took":"151.193146ms","hash":2260728668,"current-db-size-bytes":60698624,"current-db-size":"61 MB","current-db-size-in-use-bytes":31350784,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-18T19:37:00.215991Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2260728668,"revision":32836,"compact-revision":30567} {"level":"info","ts":"2026-05-18T19:42:00.066464Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35146} {"level":"info","ts":"2026-05-18T19:42:00.204323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35146,"took":"134.299803ms","hash":1436859375,"current-db-size-bytes":60698624,"current-db-size":"61 MB","current-db-size-in-use-bytes":31088640,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-18T19:42:00.204524Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1436859375,"revision":35146,"compact-revision":32836} {"level":"info","ts":"2026-05-18T19:45:02.611414Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-18T19:45:02.617148Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-05-18T19:45:02.617282Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-05-18T19:47:00.073574Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37386} {"level":"info","ts":"2026-05-18T19:47:00.193405Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37386,"took":"115.055853ms","hash":1427540517,"current-db-size-bytes":60698624,"current-db-size":"61 MB","current-db-size-in-use-bytes":31424512,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-18T19:47:00.193489Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1427540517,"revision":37386,"compact-revision":35146}