{"level":"warn","ts":"2026-10-15T14:13:30.366813Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T14:13:30.366944Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.10:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.10:2380","--initial-cluster=instance=https://162.253.55.10:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.10:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.10: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-10-15T14:13:30.367042Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T14:13:30.367054Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.10:2380"]} {"level":"info","ts":"2026-10-15T14:13:30.367076Z","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-10-15T14:13:30.36817Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"]} {"level":"info","ts":"2026-10-15T14:13:30.368279Z","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://162.253.55.10:2380"],"listen-peer-urls":["https://162.253.55.10:2380"],"advertise-client-urls":["https://162.253.55.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.10: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-10-15T14:13:30.372691Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.148183ms"} {"level":"info","ts":"2026-10-15T14:13:30.380458Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"373d5d49ce4adaff","cluster-id":"467836e907a15d60"} {"level":"info","ts":"2026-10-15T14:13:30.38063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T14:13:30.380683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became follower at term 0"} {"level":"info","ts":"2026-10-15T14:13:30.380705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 373d5d49ce4adaff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T14:13:30.380722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became follower at term 1"} {"level":"info","ts":"2026-10-15T14:13:30.380801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=(3980440217240394495)"} {"level":"warn","ts":"2026-10-15T14:13:30.386781Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T14:13:30.388825Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T14:13:30.390251Z","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-10-15T14:13:30.392908Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"373d5d49ce4adaff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T14:13:30.393112Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"373d5d49ce4adaff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T14:13:30.393226Z","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-10-15T14:13:30.393312Z","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-10-15T14:13:30.393326Z","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-10-15T14:13:30.397398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=(3980440217240394495)"} {"level":"info","ts":"2026-10-15T14:13:30.397564Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"467836e907a15d60","local-member-id":"373d5d49ce4adaff","added-peer-id":"373d5d49ce4adaff","added-peer-peer-urls":["https://162.253.55.10:2380"]} {"level":"info","ts":"2026-10-15T14:13:30.400585Z","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-10-15T14:13:30.400704Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.10:2380"} {"level":"info","ts":"2026-10-15T14:13:30.400778Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.10:2380"} {"level":"info","ts":"2026-10-15T14:13:30.401063Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"373d5d49ce4adaff","initial-advertise-peer-urls":["https://162.253.55.10:2380"],"listen-peer-urls":["https://162.253.55.10:2380"],"advertise-client-urls":["https://162.253.55.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T14:13:30.401117Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T14:13:30.987737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T14:13:30.987834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T14:13:30.987863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff received MsgPreVoteResp from 373d5d49ce4adaff at term 1"} {"level":"info","ts":"2026-10-15T14:13:30.987886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became candidate at term 2"} {"level":"info","ts":"2026-10-15T14:13:30.987898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff received MsgVoteResp from 373d5d49ce4adaff at term 2"} {"level":"info","ts":"2026-10-15T14:13:30.987916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became leader at term 2"} {"level":"info","ts":"2026-10-15T14:13:30.98793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 373d5d49ce4adaff elected leader 373d5d49ce4adaff at term 2"} {"level":"info","ts":"2026-10-15T14:13:30.995419Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:13:30.997124Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"373d5d49ce4adaff","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.10:2379]}","request-path":"/0/members/373d5d49ce4adaff/attributes","cluster-id":"467836e907a15d60","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T14:13:30.99716Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:13:30.997236Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:13:30.997439Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T14:13:30.997498Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T14:13:30.999028Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"467836e907a15d60","local-member-id":"373d5d49ce4adaff","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:13:30.999243Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:13:30.999386Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:13:31.000554Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.10:2379"} {"level":"info","ts":"2026-10-15T14:13:31.001788Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T14:13:34.44719Z","caller":"traceutil/trace.go:171","msg":"trace[571014213] transaction","detail":"{read_only:false; response_revision:178; number_of_response:1; }","duration":"117.078368ms","start":"2026-10-15T14:13:34.330089Z","end":"2026-10-15T14:13:34.447168Z","steps":["trace[571014213] 'process raft request' (duration: 108.210598ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:13:50.174697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.414847ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-15T14:14:17.894826Z","caller":"traceutil/trace.go:171","msg":"trace[1734405566] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"343.535978ms","start":"2026-10-15T14:14:17.551256Z","end":"2026-10-15T14:14:17.894792Z","steps":["trace[1734405566] 'process raft request' (duration: 337.581683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:17.895664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:17.551229Z","time spent":"343.703012ms","remote":"127.0.0.1:47148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:14:17.898486Z","caller":"traceutil/trace.go:171","msg":"trace[1901722777] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"309.393916ms","start":"2026-10-15T14:14:17.589049Z","end":"2026-10-15T14:14:17.898443Z","steps":["trace[1901722777] 'process raft request' (duration: 308.629538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:17.898647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:17.589031Z","time spent":"309.543409ms","remote":"127.0.0.1:47148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:14:17.899809Z","caller":"traceutil/trace.go:171","msg":"trace[1577221568] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"262.305539ms","start":"2026-10-15T14:14:17.63749Z","end":"2026-10-15T14:14:17.899796Z","steps":["trace[1577221568] 'process raft request' (duration: 261.047411ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:17.899784Z","caller":"traceutil/trace.go:171","msg":"trace[1521105680] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"109.332445ms","start":"2026-10-15T14:14:17.790419Z","end":"2026-10-15T14:14:17.899751Z","steps":["trace[1521105680] 'process raft request' (duration: 109.268014ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:24.325637Z","caller":"traceutil/trace.go:171","msg":"trace[1617419689] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"167.251297ms","start":"2026-10-15T14:14:24.158318Z","end":"2026-10-15T14:14:24.325569Z","steps":["trace[1617419689] 'process raft request' (duration: 123.699451ms)","trace[1617419689] 'compare' (duration: 43.300261ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:14:24.326092Z","caller":"traceutil/trace.go:171","msg":"trace[1016863626] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"114.162385ms","start":"2026-10-15T14:14:24.211904Z","end":"2026-10-15T14:14:24.326067Z","steps":["trace[1016863626] 'process raft request' (duration: 113.575431ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:24.678493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.847882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.10\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-10-15T14:14:24.678617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.615084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ff7hp\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-15T14:14:24.678641Z","caller":"traceutil/trace.go:171","msg":"trace[280287829] range","detail":"{range_begin:/registry/masterleases/162.253.55.10; range_end:; response_count:1; response_revision:671; }","duration":"108.077017ms","start":"2026-10-15T14:14:24.57054Z","end":"2026-10-15T14:14:24.678617Z","steps":["trace[280287829] 'range keys from in-memory index tree' (duration: 107.673588ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:24.678675Z","caller":"traceutil/trace.go:171","msg":"trace[1434902480] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ff7hp; range_end:; response_count:1; response_revision:671; }","duration":"246.706336ms","start":"2026-10-15T14:14:24.431944Z","end":"2026-10-15T14:14:24.678651Z","steps":["trace[1434902480] 'range keys from in-memory index tree' (duration: 246.407649ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:25.227073Z","caller":"traceutil/trace.go:171","msg":"trace[1722428843] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"530.278407ms","start":"2026-10-15T14:14:24.696751Z","end":"2026-10-15T14:14:25.22703Z","steps":["trace[1722428843] 'process raft request' (duration: 529.98765ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:25.227511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:24.696704Z","time spent":"530.489131ms","remote":"127.0.0.1:47302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:14:25.274096Z","caller":"traceutil/trace.go:171","msg":"trace[316119192] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:696; }","duration":"429.958974ms","start":"2026-10-15T14:14:24.844104Z","end":"2026-10-15T14:14:25.274063Z","steps":["trace[316119192] 'read index received' (duration: 382.646644ms)","trace[316119192] 'applied index is now lower than readState.Index' (duration: 47.31064ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:14:25.274171Z","caller":"traceutil/trace.go:171","msg":"trace[1792219766] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"573.473334ms","start":"2026-10-15T14:14:24.700639Z","end":"2026-10-15T14:14:25.274112Z","steps":["trace[1792219766] 'process raft request' (duration: 573.129896ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:25.274472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.353773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-15T14:14:25.274521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:24.700614Z","time spent":"573.73943ms","remote":"127.0.0.1:47140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:14:25.274572Z","caller":"traceutil/trace.go:171","msg":"trace[1923769448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:673; }","duration":"430.518468ms","start":"2026-10-15T14:14:24.844035Z","end":"2026-10-15T14:14:25.274554Z","steps":["trace[1923769448] 'agreement among raft nodes before linearized reading' (duration: 430.17492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:25.274616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:24.844006Z","time spent":"430.596209ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T14:14:25.830075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.058479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T14:14:25.830206Z","caller":"traceutil/trace.go:171","msg":"trace[533086307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:675; }","duration":"374.249333ms","start":"2026-10-15T14:14:25.45593Z","end":"2026-10-15T14:14:25.83018Z","steps":["trace[533086307] 'range keys from in-memory index tree' (duration: 373.813284ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:25.830256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:25.455902Z","time spent":"374.343726ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T14:14:25.972497Z","caller":"traceutil/trace.go:171","msg":"trace[1852488977] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"136.358468ms","start":"2026-10-15T14:14:25.836105Z","end":"2026-10-15T14:14:25.972463Z","steps":["trace[1852488977] 'process raft request' (duration: 136.150053ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:41.60742Z","caller":"traceutil/trace.go:171","msg":"trace[909509555] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"171.546034ms","start":"2026-10-15T14:14:41.435806Z","end":"2026-10-15T14:14:41.607352Z","steps":["trace[909509555] 'process raft request' (duration: 171.297509ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:41.892659Z","caller":"traceutil/trace.go:171","msg":"trace[1270569574] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"212.262846ms","start":"2026-10-15T14:14:41.680361Z","end":"2026-10-15T14:14:41.892624Z","steps":["trace[1270569574] 'read index received' (duration: 211.960489ms)","trace[1270569574] 'applied index is now lower than readState.Index' (duration: 301.077µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:14:41.892943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.551983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:14:41.892968Z","caller":"traceutil/trace.go:171","msg":"trace[1754207882] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"278.715671ms","start":"2026-10-15T14:14:41.614209Z","end":"2026-10-15T14:14:41.892925Z","steps":["trace[1754207882] 'process raft request' (duration: 278.168649ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:41.892991Z","caller":"traceutil/trace.go:171","msg":"trace[1552693418] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:790; }","duration":"212.672886ms","start":"2026-10-15T14:14:41.680305Z","end":"2026-10-15T14:14:41.892978Z","steps":["trace[1552693418] 'agreement among raft nodes before linearized reading' (duration: 212.476491ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:32.0338Z","caller":"traceutil/trace.go:171","msg":"trace[213207735] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"127.164787ms","start":"2026-10-15T14:15:31.906599Z","end":"2026-10-15T14:15:32.033763Z","steps":["trace[213207735] 'process raft request' (duration: 126.992013ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:59.190589Z","caller":"traceutil/trace.go:171","msg":"trace[976311497] linearizableReadLoop","detail":"{readStateIndex:1931; appliedIndex:1930; }","duration":"247.747854ms","start":"2026-10-15T14:15:58.942807Z","end":"2026-10-15T14:15:59.190555Z","steps":["trace[976311497] 'read index received' (duration: 247.500318ms)","trace[976311497] 'applied index is now lower than readState.Index' (duration: 246.015µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:15:59.190648Z","caller":"traceutil/trace.go:171","msg":"trace[192022125] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"319.311958ms","start":"2026-10-15T14:15:58.871309Z","end":"2026-10-15T14:15:59.190621Z","steps":["trace[192022125] 'process raft request' (duration: 318.9786ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:59.19085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:15:58.871286Z","time spent":"319.469381ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:15:59.190975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.137312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-31362dc2-78dd-4d5f-91b5-065e2c7c7513\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-15T14:15:59.191075Z","caller":"traceutil/trace.go:171","msg":"trace[367048699] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-31362dc2-78dd-4d5f-91b5-065e2c7c7513; range_end:; response_count:1; response_revision:1859; }","duration":"248.262175ms","start":"2026-10-15T14:15:58.942791Z","end":"2026-10-15T14:15:59.191053Z","steps":["trace[367048699] 'agreement among raft nodes before linearized reading' (duration: 247.940968ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:59.201613Z","caller":"traceutil/trace.go:171","msg":"trace[533180857] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"156.328488ms","start":"2026-10-15T14:15:59.045267Z","end":"2026-10-15T14:15:59.201595Z","steps":["trace[533180857] 'process raft request' (duration: 156.164175ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:59.201766Z","caller":"traceutil/trace.go:171","msg":"trace[1129618118] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"137.303907ms","start":"2026-10-15T14:15:59.064441Z","end":"2026-10-15T14:15:59.201745Z","steps":["trace[1129618118] 'process raft request' (duration: 137.105902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:59.202015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.985525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T14:15:59.202099Z","caller":"traceutil/trace.go:171","msg":"trace[486711614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1861; }","duration":"217.136808ms","start":"2026-10-15T14:15:58.984945Z","end":"2026-10-15T14:15:59.202082Z","steps":["trace[486711614] 'agreement among raft nodes before linearized reading' (duration: 216.928244ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:59.20201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.378681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-31362dc2-78dd-4d5f-91b5-065e2c7c7513\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-15T14:15:59.202222Z","caller":"traceutil/trace.go:171","msg":"trace[2028610081] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-31362dc2-78dd-4d5f-91b5-065e2c7c7513; range_end:; response_count:1; response_revision:1861; }","duration":"116.583257ms","start":"2026-10-15T14:15:59.085562Z","end":"2026-10-15T14:15:59.202145Z","steps":["trace[2028610081] 'agreement among raft nodes before linearized reading' (duration: 116.269ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:59.365002Z","caller":"traceutil/trace.go:171","msg":"trace[414155719] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1933; }","duration":"163.237245ms","start":"2026-10-15T14:15:59.201723Z","end":"2026-10-15T14:15:59.36496Z","steps":["trace[414155719] 'read index received' (duration: 106.963448ms)","trace[414155719] 'applied index is now lower than readState.Index' (duration: 56.272837ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:15:59.365052Z","caller":"traceutil/trace.go:171","msg":"trace[385022074] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"166.242564ms","start":"2026-10-15T14:15:59.198765Z","end":"2026-10-15T14:15:59.365008Z","steps":["trace[385022074] 'process raft request' (duration: 109.889244ms)","trace[385022074] 'compare' (duration: 56.165785ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:15:59.365217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.099189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:15:59.365252Z","caller":"traceutil/trace.go:171","msg":"trace[86134130] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1862; }","duration":"173.256483ms","start":"2026-10-15T14:15:59.191986Z","end":"2026-10-15T14:15:59.365242Z","steps":["trace[86134130] 'agreement among raft nodes before linearized reading' (duration: 173.12217ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:59.399953Z","caller":"traceutil/trace.go:171","msg":"trace[637703551] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"194.28171ms","start":"2026-10-15T14:15:59.205641Z","end":"2026-10-15T14:15:59.399923Z","steps":["trace[637703551] 'process raft request' (duration: 194.149117ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:59.424941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.296416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:15:59.425011Z","caller":"traceutil/trace.go:171","msg":"trace[718049614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1864; }","duration":"159.426949ms","start":"2026-10-15T14:15:59.265572Z","end":"2026-10-15T14:15:59.424999Z","steps":["trace[718049614] 'agreement among raft nodes before linearized reading' (duration: 159.290706ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:59.424939Z","caller":"traceutil/trace.go:171","msg":"trace[829471954] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"115.232255ms","start":"2026-10-15T14:15:59.309678Z","end":"2026-10-15T14:15:59.42491Z","steps":["trace[829471954] 'process raft request' (duration: 115.078062ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:16:12.473205Z","caller":"traceutil/trace.go:171","msg":"trace[1807194293] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"154.191369ms","start":"2026-10-15T14:16:12.318967Z","end":"2026-10-15T14:16:12.473158Z","steps":["trace[1807194293] 'process raft request' (duration: 142.978175ms)","trace[1807194293] 'compare' (duration: 11.108432ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:16:12.733313Z","caller":"traceutil/trace.go:171","msg":"trace[1457777996] linearizableReadLoop","detail":"{readStateIndex:2052; appliedIndex:2051; }","duration":"145.87231ms","start":"2026-10-15T14:16:12.587413Z","end":"2026-10-15T14:16:12.733286Z","steps":["trace[1457777996] 'read index received' (duration: 145.637235ms)","trace[1457777996] 'applied index is now lower than readState.Index' (duration: 234.285µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:16:12.733427Z","caller":"traceutil/trace.go:171","msg":"trace[827936326] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"149.624565ms","start":"2026-10-15T14:16:12.583761Z","end":"2026-10-15T14:16:12.733386Z","steps":["trace[827936326] 'process raft request' (duration: 149.342609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:12.73372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.28963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T14:16:12.733801Z","caller":"traceutil/trace.go:171","msg":"trace[1837236752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1977; }","duration":"146.427183ms","start":"2026-10-15T14:16:12.587354Z","end":"2026-10-15T14:16:12.733781Z","steps":["trace[1837236752] 'agreement among raft nodes before linearized reading' (duration: 146.080905ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:16:16.988443Z","caller":"traceutil/trace.go:171","msg":"trace[399447973] linearizableReadLoop","detail":"{readStateIndex:2076; appliedIndex:2075; }","duration":"214.931879ms","start":"2026-10-15T14:16:16.773475Z","end":"2026-10-15T14:16:16.988407Z","steps":["trace[399447973] 'read index received' (duration: 214.805346ms)","trace[399447973] 'applied index is now lower than readState.Index' (duration: 125.462µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:16:16.988521Z","caller":"traceutil/trace.go:171","msg":"trace[532424643] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"216.138746ms","start":"2026-10-15T14:16:16.772335Z","end":"2026-10-15T14:16:16.988474Z","steps":["trace[532424643] 'process raft request' (duration: 215.918681ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:16.988605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.123843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:16:16.988791Z","caller":"traceutil/trace.go:171","msg":"trace[422693448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2000; }","duration":"215.368349ms","start":"2026-10-15T14:16:16.77341Z","end":"2026-10-15T14:16:16.988779Z","steps":["trace[422693448] 'agreement among raft nodes before linearized reading' (duration: 215.138484ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:16:17.064685Z","caller":"traceutil/trace.go:171","msg":"trace[731900972] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"266.875628ms","start":"2026-10-15T14:16:16.79775Z","end":"2026-10-15T14:16:17.064626Z","steps":["trace[731900972] 'process raft request' (duration: 250.875615ms)","trace[731900972] 'compare' (duration: 15.684135ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:16:49.314027Z","caller":"traceutil/trace.go:171","msg":"trace[456231680] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"164.134897ms","start":"2026-10-15T14:16:49.149856Z","end":"2026-10-15T14:16:49.313991Z","steps":["trace[456231680] 'process raft request' (duration: 163.952353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:49.891113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.234725ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-15T14:16:49.891379Z","caller":"traceutil/trace.go:171","msg":"trace[1704158600] linearizableReadLoop","detail":"{readStateIndex:2266; appliedIndex:2264; }","duration":"202.74399ms","start":"2026-10-15T14:16:49.688621Z","end":"2026-10-15T14:16:49.891365Z","steps":["trace[1704158600] 'read index received' (duration: 86.243458ms)","trace[1704158600] 'applied index is now lower than readState.Index' (duration: 116.499902ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:16:49.891459Z","caller":"traceutil/trace.go:171","msg":"trace[128127457] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"237.946515ms","start":"2026-10-15T14:16:49.653484Z","end":"2026-10-15T14:16:49.89143Z","steps":["trace[128127457] 'process raft request' (duration: 237.74091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:49.891785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.189479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T14:16:49.892178Z","caller":"traceutil/trace.go:171","msg":"trace[13065849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2183; }","duration":"203.643039ms","start":"2026-10-15T14:16:49.6885Z","end":"2026-10-15T14:16:49.892143Z","steps":["trace[13065849] 'agreement among raft nodes before linearized reading' (duration: 202.909713ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:17:38.912674Z","caller":"traceutil/trace.go:171","msg":"trace[1677114658] linearizableReadLoop","detail":"{readStateIndex:2722; appliedIndex:2721; }","duration":"129.631699ms","start":"2026-10-15T14:17:38.783023Z","end":"2026-10-15T14:17:38.912654Z","steps":["trace[1677114658] 'read index received' (duration: 129.433975ms)","trace[1677114658] 'applied index is now lower than readState.Index' (duration: 197.174µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:17:38.912773Z","caller":"traceutil/trace.go:171","msg":"trace[488429983] transaction","detail":"{read_only:false; response_revision:2620; number_of_response:1; }","duration":"260.917715ms","start":"2026-10-15T14:17:38.651849Z","end":"2026-10-15T14:17:38.912767Z","steps":["trace[488429983] 'process raft request' (duration: 260.663649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:38.912988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.951776ms","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-10-15T14:17:38.913015Z","caller":"traceutil/trace.go:171","msg":"trace[564255963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2620; }","duration":"130.052718ms","start":"2026-10-15T14:17:38.782957Z","end":"2026-10-15T14:17:38.91301Z","steps":["trace[564255963] 'agreement among raft nodes before linearized reading' (duration: 129.933156ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:12.184728Z","caller":"traceutil/trace.go:171","msg":"trace[1394982349] transaction","detail":"{read_only:false; response_revision:2820; number_of_response:1; }","duration":"171.015654ms","start":"2026-10-15T14:18:12.013675Z","end":"2026-10-15T14:18:12.18469Z","steps":["trace[1394982349] 'process raft request' (duration: 170.777169ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:12.211557Z","caller":"traceutil/trace.go:171","msg":"trace[505339165] transaction","detail":"{read_only:false; response_revision:2821; number_of_response:1; }","duration":"195.17232ms","start":"2026-10-15T14:18:12.016354Z","end":"2026-10-15T14:18:12.211527Z","steps":["trace[505339165] 'process raft request' (duration: 194.262009ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:12.468753Z","caller":"traceutil/trace.go:171","msg":"trace[661056554] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"150.160633ms","start":"2026-10-15T14:18:12.318527Z","end":"2026-10-15T14:18:12.468688Z","steps":["trace[661056554] 'process raft request' (duration: 56.408475ms)","trace[661056554] 'compare' (duration: 93.37022ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:18:12.808569Z","caller":"traceutil/trace.go:171","msg":"trace[842189321] linearizableReadLoop","detail":"{readStateIndex:2933; appliedIndex:2932; }","duration":"132.169777ms","start":"2026-10-15T14:18:12.676365Z","end":"2026-10-15T14:18:12.808535Z","steps":["trace[842189321] 'read index received' (duration: 97.80538ms)","trace[842189321] 'applied index is now lower than readState.Index' (duration: 34.362457ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:18:12.808621Z","caller":"traceutil/trace.go:171","msg":"trace[1266634355] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"248.846913ms","start":"2026-10-15T14:18:12.55975Z","end":"2026-10-15T14:18:12.808597Z","steps":["trace[1266634355] 'process raft request' (duration: 214.62316ms)","trace[1266634355] 'compare' (duration: 33.607519ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:18:12.808729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.348251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:18:12.808785Z","caller":"traceutil/trace.go:171","msg":"trace[705485365] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:2824; }","duration":"132.483354ms","start":"2026-10-15T14:18:12.676287Z","end":"2026-10-15T14:18:12.808771Z","steps":["trace[705485365] 'agreement among raft nodes before linearized reading' (duration: 132.340331ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:12.808864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.916222ms","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-10-15T14:18:12.808921Z","caller":"traceutil/trace.go:171","msg":"trace[179559506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2824; }","duration":"117.023304ms","start":"2026-10-15T14:18:12.691884Z","end":"2026-10-15T14:18:12.808907Z","steps":["trace[179559506] 'agreement among raft nodes before linearized reading' (duration: 116.82609ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:21:02.789768Z","caller":"traceutil/trace.go:171","msg":"trace[1591787306] transaction","detail":"{read_only:false; response_revision:3849; number_of_response:1; }","duration":"129.776197ms","start":"2026-10-15T14:21:02.659946Z","end":"2026-10-15T14:21:02.789722Z","steps":["trace[1591787306] 'process raft request' (duration: 129.663615ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:21:02.78984Z","caller":"traceutil/trace.go:171","msg":"trace[21346853] transaction","detail":"{read_only:false; response_revision:3848; number_of_response:1; }","duration":"133.651083ms","start":"2026-10-15T14:21:02.656098Z","end":"2026-10-15T14:21:02.789749Z","steps":["trace[21346853] 'process raft request' (duration: 118.066537ms)","trace[21346853] 'compare' (duration: 15.289369ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:21:34.793984Z","caller":"traceutil/trace.go:171","msg":"trace[2137506808] transaction","detail":"{read_only:false; response_revision:4085; number_of_response:1; }","duration":"114.046907ms","start":"2026-10-15T14:21:34.679907Z","end":"2026-10-15T14:21:34.793954Z","steps":["trace[2137506808] 'process raft request' (duration: 113.641118ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:21:34.951536Z","caller":"traceutil/trace.go:171","msg":"trace[1584357016] linearizableReadLoop","detail":"{readStateIndex:4244; appliedIndex:4243; }","duration":"122.384413ms","start":"2026-10-15T14:21:34.829124Z","end":"2026-10-15T14:21:34.951509Z","steps":["trace[1584357016] 'read index received' (duration: 119.709524ms)","trace[1584357016] 'applied index is now lower than readState.Index' (duration: 2.673689ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:21:34.951556Z","caller":"traceutil/trace.go:171","msg":"trace[1410143856] transaction","detail":"{read_only:false; response_revision:4087; number_of_response:1; }","duration":"196.574428ms","start":"2026-10-15T14:21:34.754947Z","end":"2026-10-15T14:21:34.951522Z","steps":["trace[1410143856] 'process raft request' (duration: 193.92427ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:21:34.951868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.70767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:21:34.951957Z","caller":"traceutil/trace.go:171","msg":"trace[965461026] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4087; }","duration":"122.867804ms","start":"2026-10-15T14:21:34.82907Z","end":"2026-10-15T14:21:34.951938Z","steps":["trace[965461026] 'agreement among raft nodes before linearized reading' (duration: 122.604208ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:22:57.29692Z","caller":"traceutil/trace.go:171","msg":"trace[565262715] linearizableReadLoop","detail":"{readStateIndex:5231; appliedIndex:5230; }","duration":"100.037124ms","start":"2026-10-15T14:22:57.196854Z","end":"2026-10-15T14:22:57.296891Z","steps":["trace[565262715] 'read index received' (duration: 68.642656ms)","trace[565262715] 'applied index is now lower than readState.Index' (duration: 31.390518ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:22:57.297022Z","caller":"traceutil/trace.go:171","msg":"trace[384853372] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"174.618672ms","start":"2026-10-15T14:22:57.122358Z","end":"2026-10-15T14:22:57.296976Z","steps":["trace[384853372] 'process raft request' (duration: 143.170563ms)","trace[384853372] 'compare' (duration: 31.207443ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:22:57.298129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.2527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2210289"} {"level":"info","ts":"2026-10-15T14:22:57.298246Z","caller":"traceutil/trace.go:171","msg":"trace[388566498] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:5038; }","duration":"101.422654ms","start":"2026-10-15T14:22:57.196802Z","end":"2026-10-15T14:22:57.298225Z","steps":["trace[388566498] 'agreement among raft nodes before linearized reading' (duration: 100.274779ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:22:57.468371Z","caller":"traceutil/trace.go:171","msg":"trace[360572594] linearizableReadLoop","detail":"{readStateIndex:5232; appliedIndex:5231; }","duration":"132.516995ms","start":"2026-10-15T14:22:57.335829Z","end":"2026-10-15T14:22:57.468346Z","steps":["trace[360572594] 'read index received' (duration: 118.149146ms)","trace[360572594] 'applied index is now lower than readState.Index' (duration: 14.366889ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:22:57.468493Z","caller":"traceutil/trace.go:171","msg":"trace[1628730154] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"164.328432ms","start":"2026-10-15T14:22:57.304127Z","end":"2026-10-15T14:22:57.468455Z","steps":["trace[1628730154] 'process raft request' (duration: 149.868171ms)","trace[1628730154] 'compare' (duration: 14.240116ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:22:57.468612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.776641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-10-15T14:22:57.468645Z","caller":"traceutil/trace.go:171","msg":"trace[1570950714] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5039; }","duration":"132.877454ms","start":"2026-10-15T14:22:57.335759Z","end":"2026-10-15T14:22:57.468637Z","steps":["trace[1570950714] 'agreement among raft nodes before linearized reading' (duration: 132.725411ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:01.721445Z","caller":"traceutil/trace.go:171","msg":"trace[790234580] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"109.597056ms","start":"2026-10-15T14:23:01.611832Z","end":"2026-10-15T14:23:01.721429Z","steps":["trace[790234580] 'process raft request' (duration: 109.440843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:05.676085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.242635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-15T14:23:05.676181Z","caller":"traceutil/trace.go:171","msg":"trace[1872325241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5296; }","duration":"121.405598ms","start":"2026-10-15T14:23:05.554758Z","end":"2026-10-15T14:23:05.676164Z","steps":["trace[1872325241] 'range keys from in-memory index tree' (duration: 121.018659ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:05.921847Z","caller":"traceutil/trace.go:171","msg":"trace[1660956840] linearizableReadLoop","detail":"{readStateIndex:5499; appliedIndex:5498; }","duration":"240.214159ms","start":"2026-10-15T14:23:05.681607Z","end":"2026-10-15T14:23:05.921821Z","steps":["trace[1660956840] 'read index received' (duration: 240.122177ms)","trace[1660956840] 'applied index is now lower than readState.Index' (duration: 90.682µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:23:05.922526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.908125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-h55ls-tls\" ","response":"range_response_count:1 size:1966"} {"level":"info","ts":"2026-10-15T14:23:05.922601Z","caller":"traceutil/trace.go:171","msg":"trace[1986761247] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-h55ls-tls; range_end:; response_count:1; response_revision:5297; }","duration":"241.040758ms","start":"2026-10-15T14:23:05.681547Z","end":"2026-10-15T14:23:05.922588Z","steps":["trace[1986761247] 'agreement among raft nodes before linearized reading' (duration: 240.832623ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:05.922933Z","caller":"traceutil/trace.go:171","msg":"trace[505360878] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"242.264655ms","start":"2026-10-15T14:23:05.680655Z","end":"2026-10-15T14:23:05.92292Z","steps":["trace[505360878] 'process raft request' (duration: 240.976716ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:05.938971Z","caller":"traceutil/trace.go:171","msg":"trace[1795187589] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"199.848082ms","start":"2026-10-15T14:23:05.7391Z","end":"2026-10-15T14:23:05.938948Z","steps":["trace[1795187589] 'process raft request' (duration: 199.407692ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:09.424442Z","caller":"traceutil/trace.go:171","msg":"trace[1825280398] transaction","detail":"{read_only:false; response_revision:5369; number_of_response:1; }","duration":"120.370136ms","start":"2026-10-15T14:23:09.304052Z","end":"2026-10-15T14:23:09.424423Z","steps":["trace[1825280398] 'process raft request' (duration: 120.283894ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:09.424461Z","caller":"traceutil/trace.go:171","msg":"trace[710356292] transaction","detail":"{read_only:false; response_revision:5368; number_of_response:1; }","duration":"120.364595ms","start":"2026-10-15T14:23:09.304052Z","end":"2026-10-15T14:23:09.424417Z","steps":["trace[710356292] 'process raft request' (duration: 120.12814ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:09.424606Z","caller":"traceutil/trace.go:171","msg":"trace[1072659691] transaction","detail":"{read_only:false; response_revision:5370; number_of_response:1; }","duration":"118.927324ms","start":"2026-10-15T14:23:09.305631Z","end":"2026-10-15T14:23:09.424559Z","steps":["trace[1072659691] 'process raft request' (duration: 118.738939ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:26.145408Z","caller":"traceutil/trace.go:171","msg":"trace[1562217368] linearizableReadLoop","detail":"{readStateIndex:5868; appliedIndex:5867; }","duration":"102.484397ms","start":"2026-10-15T14:23:26.042893Z","end":"2026-10-15T14:23:26.145378Z","steps":["trace[1562217368] 'read index received' (duration: 102.228662ms)","trace[1562217368] 'applied index is now lower than readState.Index' (duration: 254.305µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:26.145566Z","caller":"traceutil/trace.go:171","msg":"trace[323956097] transaction","detail":"{read_only:false; response_revision:5657; number_of_response:1; }","duration":"107.887708ms","start":"2026-10-15T14:23:26.037662Z","end":"2026-10-15T14:23:26.14555Z","steps":["trace[323956097] 'process raft request' (duration: 107.483919ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:26.145704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.788835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:23:26.145762Z","caller":"traceutil/trace.go:171","msg":"trace[1797491380] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:5657; }","duration":"102.896807ms","start":"2026-10-15T14:23:26.042853Z","end":"2026-10-15T14:23:26.14575Z","steps":["trace[1797491380] 'agreement among raft nodes before linearized reading' (duration: 102.789334ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:26.501025Z","caller":"traceutil/trace.go:171","msg":"trace[1185291609] linearizableReadLoop","detail":"{readStateIndex:5869; appliedIndex:5868; }","duration":"355.486671ms","start":"2026-10-15T14:23:26.145509Z","end":"2026-10-15T14:23:26.500995Z","steps":["trace[1185291609] 'read index received' (duration: 274.538572ms)","trace[1185291609] 'applied index is now lower than readState.Index' (duration: 80.946449ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:23:26.501662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.135294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-15T14:23:26.501814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.835923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b76k6\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-10-15T14:23:26.501818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.031881ms","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-10-15T14:23:26.501893Z","caller":"traceutil/trace.go:171","msg":"trace[2018348193] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b76k6; range_end:; response_count:1; response_revision:5658; }","duration":"195.961656ms","start":"2026-10-15T14:23:26.305912Z","end":"2026-10-15T14:23:26.501874Z","steps":["trace[2018348193] 'agreement among raft nodes before linearized reading' (duration: 195.733471ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:26.501904Z","caller":"traceutil/trace.go:171","msg":"trace[128315961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5658; }","duration":"198.148464ms","start":"2026-10-15T14:23:26.303736Z","end":"2026-10-15T14:23:26.501885Z","steps":["trace[128315961] 'agreement among raft nodes before linearized reading' (duration: 197.993771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:26.501692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.086972ms","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-10-15T14:23:26.501791Z","caller":"traceutil/trace.go:171","msg":"trace[366193653] transaction","detail":"{read_only:false; response_revision:5658; number_of_response:1; }","duration":"360.031802ms","start":"2026-10-15T14:23:26.141433Z","end":"2026-10-15T14:23:26.501464Z","steps":["trace[366193653] 'process raft request' (duration: 278.604592ms)","trace[366193653] 'compare' (duration: 80.789806ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:26.502056Z","caller":"traceutil/trace.go:171","msg":"trace[811566597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5658; }","duration":"355.41421ms","start":"2026-10-15T14:23:26.146545Z","end":"2026-10-15T14:23:26.501959Z","steps":["trace[811566597] 'agreement among raft nodes before linearized reading' (duration: 355.027361ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:26.502149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:26.141405Z","time spent":"360.650486ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:23:26.502254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:26.146528Z","time spent":"355.632034ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-15T14:23:26.501825Z","caller":"traceutil/trace.go:171","msg":"trace[240706265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5658; }","duration":"423.350229ms","start":"2026-10-15T14:23:26.078453Z","end":"2026-10-15T14:23:26.501804Z","steps":["trace[240706265] 'agreement among raft nodes before linearized reading' (duration: 423.013052ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:26.502733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:26.078433Z","time spent":"424.230799ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T14:23:26.696Z","caller":"traceutil/trace.go:171","msg":"trace[696535749] transaction","detail":"{read_only:false; response_revision:5659; number_of_response:1; }","duration":"187.892627ms","start":"2026-10-15T14:23:26.508067Z","end":"2026-10-15T14:23:26.695959Z","steps":["trace[696535749] 'process raft request' (duration: 99.819609ms)","trace[696535749] 'compare' (duration: 87.948605ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:26.727896Z","caller":"traceutil/trace.go:171","msg":"trace[940095098] transaction","detail":"{read_only:false; response_revision:5661; number_of_response:1; }","duration":"219.439207ms","start":"2026-10-15T14:23:26.508443Z","end":"2026-10-15T14:23:26.727883Z","steps":["trace[940095098] 'process raft request' (duration: 219.344885ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:26.728026Z","caller":"traceutil/trace.go:171","msg":"trace[419635643] transaction","detail":"{read_only:false; response_revision:5662; number_of_response:1; }","duration":"216.636355ms","start":"2026-10-15T14:23:26.511372Z","end":"2026-10-15T14:23:26.728009Z","steps":["trace[419635643] 'process raft request' (duration: 216.447281ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:26.727898Z","caller":"traceutil/trace.go:171","msg":"trace[825692704] transaction","detail":"{read_only:false; response_revision:5660; number_of_response:1; }","duration":"219.715774ms","start":"2026-10-15T14:23:26.508153Z","end":"2026-10-15T14:23:26.727868Z","steps":["trace[825692704] 'process raft request' (duration: 219.508759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:26.916644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.71773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:23:26.91675Z","caller":"traceutil/trace.go:171","msg":"trace[698875259] linearizableReadLoop","detail":"{readStateIndex:5874; appliedIndex:5870; }","duration":"275.995695ms","start":"2026-10-15T14:23:26.640735Z","end":"2026-10-15T14:23:26.916731Z","steps":["trace[698875259] 'read index received' (duration: 86.916082ms)","trace[698875259] 'applied index is now lower than readState.Index' (duration: 189.078223ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:26.91686Z","caller":"traceutil/trace.go:171","msg":"trace[201209726] transaction","detail":"{read_only:false; response_revision:5663; number_of_response:1; }","duration":"281.64336ms","start":"2026-10-15T14:23:26.6352Z","end":"2026-10-15T14:23:26.916843Z","steps":["trace[201209726] 'process raft request' (duration: 166.571363ms)","trace[201209726] 'compare' (duration: 114.593566ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:23:26.917147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.619508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-15T14:23:26.917277Z","caller":"traceutil/trace.go:171","msg":"trace[321317355] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5663; }","duration":"222.789492ms","start":"2026-10-15T14:23:26.694467Z","end":"2026-10-15T14:23:26.917256Z","steps":["trace[321317355] 'agreement among raft nodes before linearized reading' (duration: 222.461135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:26.917344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.583698ms","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-10-15T14:23:26.91743Z","caller":"traceutil/trace.go:171","msg":"trace[881103000] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5663; }","duration":"276.70095ms","start":"2026-10-15T14:23:26.640709Z","end":"2026-10-15T14:23:26.91741Z","steps":["trace[881103000] 'agreement among raft nodes before linearized reading' (duration: 276.470105ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:28.671795Z","caller":"traceutil/trace.go:171","msg":"trace[997646158] linearizableReadLoop","detail":"{readStateIndex:5890; appliedIndex:5889; }","duration":"158.058303ms","start":"2026-10-15T14:23:28.513711Z","end":"2026-10-15T14:23:28.671769Z","steps":["trace[997646158] 'read index received' (duration: 157.871739ms)","trace[997646158] 'applied index is now lower than readState.Index' (duration: 185.514µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:28.671916Z","caller":"traceutil/trace.go:171","msg":"trace[1000424406] transaction","detail":"{read_only:false; response_revision:5679; number_of_response:1; }","duration":"163.411452ms","start":"2026-10-15T14:23:28.508483Z","end":"2026-10-15T14:23:28.671894Z","steps":["trace[1000424406] 'process raft request' (duration: 163.051094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:28.729805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.060522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b76k6\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-15T14:23:28.729919Z","caller":"traceutil/trace.go:171","msg":"trace[2014000895] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b76k6; range_end:; response_count:1; response_revision:5679; }","duration":"216.222256ms","start":"2026-10-15T14:23:28.513673Z","end":"2026-10-15T14:23:28.729895Z","steps":["trace[2014000895] 'agreement among raft nodes before linearized reading' (duration: 158.195397ms)","trace[2014000895] 'range keys from in-memory index tree' (duration: 57.756864ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:28.730097Z","caller":"traceutil/trace.go:171","msg":"trace[1300321657] transaction","detail":"{read_only:false; response_revision:5680; number_of_response:1; }","duration":"212.042253ms","start":"2026-10-15T14:23:28.518029Z","end":"2026-10-15T14:23:28.730071Z","steps":["trace[1300321657] 'process raft request' (duration: 210.603421ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:28.730156Z","caller":"traceutil/trace.go:171","msg":"trace[901591916] transaction","detail":"{read_only:false; response_revision:5681; number_of_response:1; }","duration":"206.850758ms","start":"2026-10-15T14:23:28.523235Z","end":"2026-10-15T14:23:28.730086Z","steps":["trace[901591916] 'process raft request' (duration: 206.52581ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:31.028499Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2925} {"level":"info","ts":"2026-10-15T14:23:31.126046Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2925,"took":"95.694477ms","hash":389044503,"current-db-size-bytes":56487936,"current-db-size":"56 MB","current-db-size-in-use-bytes":31789056,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T14:23:31.126232Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":389044503,"revision":2925,"compact-revision":-1} {"level":"warn","ts":"2026-10-15T14:23:36.779548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.665148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:23:36.779725Z","caller":"traceutil/trace.go:171","msg":"trace[96473140] transaction","detail":"{read_only:false; response_revision:5835; number_of_response:1; }","duration":"134.779976ms","start":"2026-10-15T14:23:36.644924Z","end":"2026-10-15T14:23:36.779704Z","steps":["trace[96473140] 'compare' (duration: 127.495814ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:36.782195Z","caller":"traceutil/trace.go:171","msg":"trace[2100536777] transaction","detail":"{read_only:false; response_revision:5836; number_of_response:1; }","duration":"136.564395ms","start":"2026-10-15T14:23:36.645604Z","end":"2026-10-15T14:23:36.782168Z","steps":["trace[2100536777] 'process raft request' (duration: 136.274759ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:36.782222Z","caller":"traceutil/trace.go:171","msg":"trace[2142149950] transaction","detail":"{read_only:false; response_revision:5838; number_of_response:1; }","duration":"133.462646ms","start":"2026-10-15T14:23:36.648715Z","end":"2026-10-15T14:23:36.782178Z","steps":["trace[2142149950] 'process raft request' (duration: 133.406825ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:36.782265Z","caller":"traceutil/trace.go:171","msg":"trace[1596096270] transaction","detail":"{read_only:false; response_revision:5837; number_of_response:1; }","duration":"136.510644ms","start":"2026-10-15T14:23:36.645733Z","end":"2026-10-15T14:23:36.782244Z","steps":["trace[1596096270] 'process raft request' (duration: 136.32539ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:36.785922Z","caller":"traceutil/trace.go:171","msg":"trace[1900930700] transaction","detail":"{read_only:false; response_revision:5839; number_of_response:1; }","duration":"131.145785ms","start":"2026-10-15T14:23:36.654756Z","end":"2026-10-15T14:23:36.785902Z","steps":["trace[1900930700] 'process raft request' (duration: 130.90845ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:40.905523Z","caller":"traceutil/trace.go:171","msg":"trace[167933972] transaction","detail":"{read_only:false; response_revision:5868; number_of_response:1; }","duration":"108.305977ms","start":"2026-10-15T14:23:40.797183Z","end":"2026-10-15T14:23:40.905489Z","steps":["trace[167933972] 'process raft request' (duration: 108.075972ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:41.060404Z","caller":"traceutil/trace.go:171","msg":"trace[2063857882] transaction","detail":"{read_only:false; response_revision:5869; number_of_response:1; }","duration":"150.507345ms","start":"2026-10-15T14:23:40.909845Z","end":"2026-10-15T14:23:41.060352Z","steps":["trace[2063857882] 'process raft request' (duration: 76.109682ms)","trace[2063857882] 'compare' (duration: 74.051946ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:41.077367Z","caller":"traceutil/trace.go:171","msg":"trace[862789350] linearizableReadLoop","detail":"{readStateIndex:6089; appliedIndex:6086; }","duration":"166.862999ms","start":"2026-10-15T14:23:40.910469Z","end":"2026-10-15T14:23:41.077332Z","steps":["trace[862789350] 'read index received' (duration: 75.499208ms)","trace[862789350] 'applied index is now lower than readState.Index' (duration: 91.362101ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:41.077434Z","caller":"traceutil/trace.go:171","msg":"trace[1267445458] transaction","detail":"{read_only:false; response_revision:5870; number_of_response:1; }","duration":"167.440241ms","start":"2026-10-15T14:23:40.909919Z","end":"2026-10-15T14:23:41.07736Z","steps":["trace[1267445458] 'process raft request' (duration: 167.139645ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:41.077504Z","caller":"traceutil/trace.go:171","msg":"trace[528561192] transaction","detail":"{read_only:false; response_revision:5871; number_of_response:1; }","duration":"167.457922ms","start":"2026-10-15T14:23:40.910029Z","end":"2026-10-15T14:23:41.077487Z","steps":["trace[528561192] 'process raft request' (duration: 167.245577ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:41.077766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.285608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:81489"} {"level":"info","ts":"2026-10-15T14:23:41.077856Z","caller":"traceutil/trace.go:171","msg":"trace[1498090105] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5871; }","duration":"167.425252ms","start":"2026-10-15T14:23:40.91039Z","end":"2026-10-15T14:23:41.077815Z","steps":["trace[1498090105] 'agreement among raft nodes before linearized reading' (duration: 167.109745ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:41.833558Z","caller":"traceutil/trace.go:171","msg":"trace[2027620435] transaction","detail":"{read_only:false; response_revision:5878; number_of_response:1; }","duration":"112.284646ms","start":"2026-10-15T14:23:41.721235Z","end":"2026-10-15T14:23:41.833519Z","steps":["trace[2027620435] 'process raft request' (duration: 111.937258ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:41.833589Z","caller":"traceutil/trace.go:171","msg":"trace[1832655066] transaction","detail":"{read_only:false; response_revision:5880; number_of_response:1; }","duration":"110.537037ms","start":"2026-10-15T14:23:41.723037Z","end":"2026-10-15T14:23:41.833574Z","steps":["trace[1832655066] 'process raft request' (duration: 110.467606ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:41.833862Z","caller":"traceutil/trace.go:171","msg":"trace[1655707167] transaction","detail":"{read_only:false; response_revision:5879; number_of_response:1; }","duration":"111.034538ms","start":"2026-10-15T14:23:41.722796Z","end":"2026-10-15T14:23:41.833831Z","steps":["trace[1655707167] 'process raft request' (duration: 110.614238ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:24:27.972994Z","caller":"traceutil/trace.go:171","msg":"trace[1586334635] transaction","detail":"{read_only:false; response_revision:6254; number_of_response:1; }","duration":"110.371358ms","start":"2026-10-15T14:24:27.862593Z","end":"2026-10-15T14:24:27.972964Z","steps":["trace[1586334635] 'process raft request' (duration: 110.175894ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:24:41.592249Z","caller":"traceutil/trace.go:171","msg":"trace[2004620904] linearizableReadLoop","detail":"{readStateIndex:6591; appliedIndex:6590; }","duration":"147.720779ms","start":"2026-10-15T14:24:41.444462Z","end":"2026-10-15T14:24:41.592183Z","steps":["trace[2004620904] 'read index received' (duration: 85.353181ms)","trace[2004620904] 'applied index is now lower than readState.Index' (duration: 62.365928ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:24:41.592331Z","caller":"traceutil/trace.go:171","msg":"trace[1690217229] transaction","detail":"{read_only:false; response_revision:6359; number_of_response:1; }","duration":"166.15656ms","start":"2026-10-15T14:24:41.426149Z","end":"2026-10-15T14:24:41.592306Z","steps":["trace[1690217229] 'process raft request' (duration: 103.72283ms)","trace[1690217229] 'compare' (duration: 62.076102ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:24:41.592619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.143529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-15T14:24:41.592663Z","caller":"traceutil/trace.go:171","msg":"trace[1297645079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6359; }","duration":"148.235911ms","start":"2026-10-15T14:24:41.444412Z","end":"2026-10-15T14:24:41.592648Z","steps":["trace[1297645079] 'agreement among raft nodes before linearized reading' (duration: 148.009596ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:24:41.592667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.490165ms","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-10-15T14:24:41.592699Z","caller":"traceutil/trace.go:171","msg":"trace[252672702] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6359; }","duration":"134.580397ms","start":"2026-10-15T14:24:41.458111Z","end":"2026-10-15T14:24:41.592692Z","steps":["trace[252672702] 'agreement among raft nodes before linearized reading' (duration: 134.466694ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:24:41.592694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.923358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-15T14:24:41.592762Z","caller":"traceutil/trace.go:171","msg":"trace[227848658] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6359; }","duration":"141.032461ms","start":"2026-10-15T14:24:41.451707Z","end":"2026-10-15T14:24:41.592739Z","steps":["trace[227848658] 'agreement among raft nodes before linearized reading' (duration: 140.819406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:24:41.59298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.030127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b76k6\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-15T14:24:41.593043Z","caller":"traceutil/trace.go:171","msg":"trace[1663838463] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b76k6; range_end:; response_count:1; response_revision:6359; }","duration":"135.115459ms","start":"2026-10-15T14:24:41.457909Z","end":"2026-10-15T14:24:41.593025Z","steps":["trace[1663838463] 'agreement among raft nodes before linearized reading' (duration: 134.951536ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:28:31.037202Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5738} {"level":"info","ts":"2026-10-15T14:28:31.122005Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5738,"took":"82.662883ms","hash":158964767,"current-db-size-bytes":56487936,"current-db-size":"56 MB","current-db-size-in-use-bytes":25989120,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T14:28:31.122081Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":158964767,"revision":5738,"compact-revision":2925} {"level":"info","ts":"2026-10-15T14:29:40.651561Z","caller":"traceutil/trace.go:171","msg":"trace[1441014157] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"198.743317ms","start":"2026-10-15T14:29:40.452788Z","end":"2026-10-15T14:29:40.651532Z","steps":["trace[1441014157] 'process raft request' (duration: 198.557183ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:53.287791Z","caller":"traceutil/trace.go:171","msg":"trace[1859469983] linearizableReadLoop","detail":"{readStateIndex:8947; appliedIndex:8946; }","duration":"360.920234ms","start":"2026-10-15T14:29:52.926843Z","end":"2026-10-15T14:29:53.287763Z","steps":["trace[1859469983] 'read index received' (duration: 360.642727ms)","trace[1859469983] 'applied index is now lower than readState.Index' (duration: 276.817µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:29:53.28822Z","caller":"traceutil/trace.go:171","msg":"trace[1635819916] transaction","detail":"{read_only:false; response_revision:8639; number_of_response:1; }","duration":"425.033642ms","start":"2026-10-15T14:29:52.863169Z","end":"2026-10-15T14:29:53.288202Z","steps":["trace[1635819916] 'process raft request' (duration: 424.407758ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:53.288406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:29:52.863137Z","time spent":"425.156985ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:29:53.288709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.426142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T14:29:53.288801Z","caller":"traceutil/trace.go:171","msg":"trace[1318760860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8639; }","duration":"199.588395ms","start":"2026-10-15T14:29:53.089191Z","end":"2026-10-15T14:29:53.288779Z","steps":["trace[1318760860] 'agreement among raft nodes before linearized reading' (duration: 199.376861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:53.289041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.197432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:29:53.289115Z","caller":"traceutil/trace.go:171","msg":"trace[987392200] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:8639; }","duration":"362.313315ms","start":"2026-10-15T14:29:52.926782Z","end":"2026-10-15T14:29:53.289096Z","steps":["trace[987392200] 'agreement among raft nodes before linearized reading' (duration: 362.217022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:53.289119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.539596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-15T14:29:53.289158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:29:52.926758Z","time spent":"362.386027ms","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-15T14:29:53.289301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.310797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T14:29:53.289349Z","caller":"traceutil/trace.go:171","msg":"trace[872664012] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8639; }","duration":"198.380089ms","start":"2026-10-15T14:29:53.09096Z","end":"2026-10-15T14:29:53.28934Z","steps":["trace[872664012] 'agreement among raft nodes before linearized reading' (duration: 198.224296ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:53.289164Z","caller":"traceutil/trace.go:171","msg":"trace[52778268] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8639; }","duration":"158.626638ms","start":"2026-10-15T14:29:53.130522Z","end":"2026-10-15T14:29:53.289149Z","steps":["trace[52778268] 'agreement among raft nodes before linearized reading' (duration: 158.553386ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:54.235196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.861947ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:29:54.235354Z","caller":"traceutil/trace.go:171","msg":"trace[1278859574] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8646; }","duration":"260.03472ms","start":"2026-10-15T14:29:53.975301Z","end":"2026-10-15T14:29:54.235336Z","steps":["trace[1278859574] 'range keys from in-memory index tree' (duration: 259.841976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:54.235643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.453096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:29:54.235702Z","caller":"traceutil/trace.go:171","msg":"trace[1512280485] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:8646; }","duration":"246.581949ms","start":"2026-10-15T14:29:53.989106Z","end":"2026-10-15T14:29:54.235688Z","steps":["trace[1512280485] 'count revisions from in-memory index tree' (duration: 246.281113ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:54.870461Z","caller":"traceutil/trace.go:171","msg":"trace[444967773] transaction","detail":"{read_only:false; response_revision:8648; number_of_response:1; }","duration":"156.921329ms","start":"2026-10-15T14:29:54.713515Z","end":"2026-10-15T14:29:54.870436Z","steps":["trace[444967773] 'process raft request' (duration: 156.743625ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:23.604357Z","caller":"traceutil/trace.go:171","msg":"trace[754047785] linearizableReadLoop","detail":"{readStateIndex:9170; appliedIndex:9169; }","duration":"133.366181ms","start":"2026-10-15T14:30:23.47096Z","end":"2026-10-15T14:30:23.604326Z","steps":["trace[754047785] 'read index received' (duration: 133.050124ms)","trace[754047785] 'applied index is now lower than readState.Index' (duration: 314.737µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:30:23.604489Z","caller":"traceutil/trace.go:171","msg":"trace[775295379] transaction","detail":"{read_only:false; response_revision:8854; number_of_response:1; }","duration":"156.832265ms","start":"2026-10-15T14:30:23.447632Z","end":"2026-10-15T14:30:23.604464Z","steps":["trace[775295379] 'process raft request' (duration: 156.499108ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:23.604592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.616136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-15T14:30:23.604632Z","caller":"traceutil/trace.go:171","msg":"trace[444737198] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8854; }","duration":"133.703799ms","start":"2026-10-15T14:30:23.470917Z","end":"2026-10-15T14:30:23.60462Z","steps":["trace[444737198] 'agreement among raft nodes before linearized reading' (duration: 133.539305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:23.931791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.189368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:30:23.9319Z","caller":"traceutil/trace.go:171","msg":"trace[1350511306] transaction","detail":"{read_only:false; response_revision:8855; number_of_response:1; }","duration":"320.358549ms","start":"2026-10-15T14:30:23.611523Z","end":"2026-10-15T14:30:23.931882Z","steps":["trace[1350511306] 'process raft request' (duration: 110.004888ms)","trace[1350511306] 'compare' (duration: 210.069415ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:30:23.93196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:30:23.611508Z","time spent":"320.421171ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:30:24.314696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.675557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:30:24.314982Z","caller":"traceutil/trace.go:171","msg":"trace[395682559] transaction","detail":"{read_only:false; response_revision:8858; number_of_response:1; }","duration":"385.308161ms","start":"2026-10-15T14:30:23.929652Z","end":"2026-10-15T14:30:24.31496Z","steps":["trace[395682559] 'process raft request' (duration: 385.25055ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:24.315013Z","caller":"traceutil/trace.go:171","msg":"trace[243505161] transaction","detail":"{read_only:false; response_revision:8857; number_of_response:1; }","duration":"698.519822ms","start":"2026-10-15T14:30:23.616471Z","end":"2026-10-15T14:30:24.314991Z","steps":["trace[243505161] 'process raft request' (duration: 698.347268ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:24.31501Z","caller":"traceutil/trace.go:171","msg":"trace[1410959296] transaction","detail":"{read_only:false; response_revision:8856; number_of_response:1; }","duration":"699.166986ms","start":"2026-10-15T14:30:23.615816Z","end":"2026-10-15T14:30:24.314983Z","steps":["trace[1410959296] 'process raft request' (duration: 446.10869ms)","trace[1410959296] 'compare' (duration: 252.531364ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:30:24.315087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:30:23.929625Z","time spent":"385.414544ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:30:24.315114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:30:23.61644Z","time spent":"698.628684ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:30:24.315158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:30:23.6158Z","time spent":"699.277089ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:30:24.315009Z","caller":"traceutil/trace.go:171","msg":"trace[714811277] linearizableReadLoop","detail":"{readStateIndex:9173; appliedIndex:9170; }","duration":"659.789906ms","start":"2026-10-15T14:30:23.655194Z","end":"2026-10-15T14:30:24.314984Z","steps":["trace[714811277] 'read index received' (duration: 66.350253ms)","trace[714811277] 'applied index is now lower than readState.Index' (duration: 593.437663ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:30:24.315213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.010641ms","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-10-15T14:30:24.315284Z","caller":"traceutil/trace.go:171","msg":"trace[1526874417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8858; }","duration":"660.125873ms","start":"2026-10-15T14:30:23.655142Z","end":"2026-10-15T14:30:24.315268Z","steps":["trace[1526874417] 'agreement among raft nodes before linearized reading' (duration: 659.916149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:24.315318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:30:23.655118Z","time spent":"660.192275ms","remote":"127.0.0.1:47404","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-10-15T14:30:24.315373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.53031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b76k6\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-15T14:30:24.315401Z","caller":"traceutil/trace.go:171","msg":"trace[240680125] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b76k6; range_end:; response_count:1; response_revision:8858; }","duration":"489.557961ms","start":"2026-10-15T14:30:23.825834Z","end":"2026-10-15T14:30:24.315392Z","steps":["trace[240680125] 'agreement among raft nodes before linearized reading' (duration: 489.457789ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:24.315448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:30:23.825774Z","time spent":"489.644284ms","remote":"127.0.0.1:47302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-b76k6\" "} {"level":"warn","ts":"2026-10-15T14:30:24.315427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.725844ms","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":"warn","ts":"2026-10-15T14:30:24.315445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.929547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:30:24.315501Z","caller":"traceutil/trace.go:171","msg":"trace[1547524038] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8858; }","duration":"642.828687ms","start":"2026-10-15T14:30:23.672644Z","end":"2026-10-15T14:30:24.315472Z","steps":["trace[1547524038] 'agreement among raft nodes before linearized reading' (duration: 642.641723ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:24.315529Z","caller":"traceutil/trace.go:171","msg":"trace[60596935] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8858; }","duration":"506.062581ms","start":"2026-10-15T14:30:23.809444Z","end":"2026-10-15T14:30:24.315507Z","steps":["trace[60596935] 'agreement among raft nodes before linearized reading' (duration: 505.919187ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:24.315546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:30:23.672627Z","time spent":"642.907939ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T14:30:24.315594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:30:23.809422Z","time spent":"506.160802ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"warn","ts":"2026-10-15T14:30:24.315727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.558438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-15T14:30:24.315766Z","caller":"traceutil/trace.go:171","msg":"trace[428511726] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8858; }","duration":"641.6111ms","start":"2026-10-15T14:30:23.67414Z","end":"2026-10-15T14:30:24.315751Z","steps":["trace[428511726] 'agreement among raft nodes before linearized reading' (duration: 641.520298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:24.315796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:30:23.674132Z","time spent":"641.656521ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T14:30:24.722678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.564566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:30:24.722777Z","caller":"traceutil/trace.go:171","msg":"trace[63561737] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:8861; }","duration":"270.719569ms","start":"2026-10-15T14:30:24.452034Z","end":"2026-10-15T14:30:24.722754Z","steps":["trace[63561737] 'count revisions from in-memory index tree' (duration: 270.466943ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:24.722796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.827584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.10\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-15T14:30:24.722886Z","caller":"traceutil/trace.go:171","msg":"trace[1047618262] range","detail":"{range_begin:/registry/masterleases/162.253.55.10; range_end:; response_count:1; response_revision:8861; }","duration":"100.995088ms","start":"2026-10-15T14:30:24.621868Z","end":"2026-10-15T14:30:24.722863Z","steps":["trace[1047618262] 'range keys from in-memory index tree' (duration: 100.677371ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:24.914153Z","caller":"traceutil/trace.go:171","msg":"trace[1750017676] transaction","detail":"{read_only:false; response_revision:8862; number_of_response:1; }","duration":"100.053756ms","start":"2026-10-15T14:30:24.81407Z","end":"2026-10-15T14:30:24.914124Z","steps":["trace[1750017676] 'process raft request' (duration: 39.631316ms)","trace[1750017676] 'compare' (duration: 60.235116ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:30:25.100739Z","caller":"traceutil/trace.go:171","msg":"trace[539817768] transaction","detail":"{read_only:false; response_revision:8863; number_of_response:1; }","duration":"113.131688ms","start":"2026-10-15T14:30:24.987574Z","end":"2026-10-15T14:30:25.100706Z","steps":["trace[539817768] 'process raft request' (duration: 36.858484ms)","trace[539817768] 'compare' (duration: 76.124101ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:30:38.017918Z","caller":"traceutil/trace.go:171","msg":"trace[1954088474] linearizableReadLoop","detail":"{readStateIndex:9291; appliedIndex:9290; }","duration":"138.892774ms","start":"2026-10-15T14:30:37.879006Z","end":"2026-10-15T14:30:38.017899Z","steps":["trace[1954088474] 'read index received' (duration: 68.245405ms)","trace[1954088474] 'applied index is now lower than readState.Index' (duration: 70.646519ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:30:38.01813Z","caller":"traceutil/trace.go:171","msg":"trace[2089280845] transaction","detail":"{read_only:false; response_revision:8969; number_of_response:1; }","duration":"140.596893ms","start":"2026-10-15T14:30:37.877522Z","end":"2026-10-15T14:30:38.018119Z","steps":["trace[2089280845] 'process raft request' (duration: 69.734959ms)","trace[2089280845] 'compare' (duration: 70.558447ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:30:38.018424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.396666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-7gckn\" ","response":"range_response_count:1 size:2801"} {"level":"warn","ts":"2026-10-15T14:30:38.018403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.25722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-10-15T14:30:38.018508Z","caller":"traceutil/trace.go:171","msg":"trace[628430014] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:8969; }","duration":"138.394863ms","start":"2026-10-15T14:30:37.880092Z","end":"2026-10-15T14:30:38.018487Z","steps":["trace[628430014] 'agreement among raft nodes before linearized reading' (duration: 138.169948ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:38.018464Z","caller":"traceutil/trace.go:171","msg":"trace[1136933338] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-7gckn; range_end:; response_count:1; response_revision:8969; }","duration":"139.464487ms","start":"2026-10-15T14:30:37.878988Z","end":"2026-10-15T14:30:38.018452Z","steps":["trace[1136933338] 'agreement among raft nodes before linearized reading' (duration: 139.306034ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:38.559666Z","caller":"traceutil/trace.go:171","msg":"trace[513860854] linearizableReadLoop","detail":"{readStateIndex:9296; appliedIndex:9295; }","duration":"149.367359ms","start":"2026-10-15T14:30:38.410278Z","end":"2026-10-15T14:30:38.559645Z","steps":["trace[513860854] 'read index received' (duration: 106.626124ms)","trace[513860854] 'applied index is now lower than readState.Index' (duration: 42.740235ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:30:38.55974Z","caller":"traceutil/trace.go:171","msg":"trace[845590076] transaction","detail":"{read_only:false; response_revision:8974; number_of_response:1; }","duration":"176.227208ms","start":"2026-10-15T14:30:38.38348Z","end":"2026-10-15T14:30:38.559707Z","steps":["trace[845590076] 'process raft request' (duration: 133.324219ms)","trace[845590076] 'compare' (duration: 42.597282ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:30:38.559885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.785521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-15T14:30:38.559931Z","caller":"traceutil/trace.go:171","msg":"trace[434060002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8974; }","duration":"159.864553ms","start":"2026-10-15T14:30:38.400054Z","end":"2026-10-15T14:30:38.559918Z","steps":["trace[434060002] 'agreement among raft nodes before linearized reading' (duration: 159.701669ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:38.560217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.775921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T14:30:38.560363Z","caller":"traceutil/trace.go:171","msg":"trace[874631568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8974; }","duration":"163.973154ms","start":"2026-10-15T14:30:38.396368Z","end":"2026-10-15T14:30:38.560342Z","steps":["trace[874631568] 'agreement among raft nodes before linearized reading' (duration: 163.629747ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:38.584885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.218753ms","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-10-15T14:30:38.584957Z","caller":"traceutil/trace.go:171","msg":"trace[572814239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8975; }","duration":"127.336696ms","start":"2026-10-15T14:30:38.457601Z","end":"2026-10-15T14:30:38.584938Z","steps":["trace[572814239] 'agreement among raft nodes before linearized reading' (duration: 127.116931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:38.585005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.919134ms","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":"warn","ts":"2026-10-15T14:30:38.585023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.773738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-15T14:30:38.585078Z","caller":"traceutil/trace.go:171","msg":"trace[1268608359] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8975; }","duration":"109.040427ms","start":"2026-10-15T14:30:38.476023Z","end":"2026-10-15T14:30:38.585063Z","steps":["trace[1268608359] 'agreement among raft nodes before linearized reading' (duration: 108.811852ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:38.5851Z","caller":"traceutil/trace.go:171","msg":"trace[1831526820] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8975; }","duration":"111.883031ms","start":"2026-10-15T14:30:38.473194Z","end":"2026-10-15T14:30:38.585077Z","steps":["trace[1831526820] 'agreement among raft nodes before linearized reading' (duration: 111.694297ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:38.585013Z","caller":"traceutil/trace.go:171","msg":"trace[443581636] transaction","detail":"{read_only:false; response_revision:8975; number_of_response:1; }","duration":"166.098493ms","start":"2026-10-15T14:30:38.41887Z","end":"2026-10-15T14:30:38.584969Z","steps":["trace[443581636] 'process raft request' (duration: 165.688503ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:38.585211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.563087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b76k6\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-15T14:30:38.58528Z","caller":"traceutil/trace.go:171","msg":"trace[1451597012] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b76k6; range_end:; response_count:1; response_revision:8975; }","duration":"104.658139ms","start":"2026-10-15T14:30:38.480608Z","end":"2026-10-15T14:30:38.585266Z","steps":["trace[1451597012] 'agreement among raft nodes before linearized reading' (duration: 104.478155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:38.806058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.599344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:30:38.806267Z","caller":"traceutil/trace.go:171","msg":"trace[1991859278] transaction","detail":"{read_only:false; response_revision:8977; number_of_response:1; }","duration":"240.215367ms","start":"2026-10-15T14:30:38.566003Z","end":"2026-10-15T14:30:38.806219Z","steps":["trace[1991859278] 'process raft request' (duration: 240.146976ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:38.806301Z","caller":"traceutil/trace.go:171","msg":"trace[787614616] transaction","detail":"{read_only:false; response_revision:8976; number_of_response:1; }","duration":"242.171961ms","start":"2026-10-15T14:30:38.564088Z","end":"2026-10-15T14:30:38.80626Z","steps":["trace[787614616] 'process raft request' (duration: 104.289211ms)","trace[787614616] 'compare' (duration: 137.476341ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:30:38.81756Z","caller":"traceutil/trace.go:171","msg":"trace[127303755] transaction","detail":"{read_only:false; response_revision:8978; number_of_response:1; }","duration":"227.229127ms","start":"2026-10-15T14:30:38.590308Z","end":"2026-10-15T14:30:38.817537Z","steps":["trace[127303755] 'process raft request' (duration: 227.007253ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:38.817565Z","caller":"traceutil/trace.go:171","msg":"trace[1423826973] linearizableReadLoop","detail":"{readStateIndex:9300; appliedIndex:9297; }","duration":"227.113735ms","start":"2026-10-15T14:30:38.590436Z","end":"2026-10-15T14:30:38.81755Z","steps":["trace[1423826973] 'read index received' (duration: 78.079845ms)","trace[1423826973] 'applied index is now lower than readState.Index' (duration: 149.03297ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:30:38.817631Z","caller":"traceutil/trace.go:171","msg":"trace[249205588] transaction","detail":"{read_only:false; response_revision:8979; number_of_response:1; }","duration":"225.131451ms","start":"2026-10-15T14:30:38.592456Z","end":"2026-10-15T14:30:38.817587Z","steps":["trace[249205588] 'process raft request' (duration: 224.993978ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:38.817732Z","caller":"traceutil/trace.go:171","msg":"trace[271665647] transaction","detail":"{read_only:false; response_revision:8980; number_of_response:1; }","duration":"225.209912ms","start":"2026-10-15T14:30:38.592504Z","end":"2026-10-15T14:30:38.817714Z","steps":["trace[271665647] 'process raft request' (duration: 225.002218ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:38.817794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.774942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3173"} {"level":"info","ts":"2026-10-15T14:30:38.817841Z","caller":"traceutil/trace.go:171","msg":"trace[1861087725] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8980; }","duration":"194.862354ms","start":"2026-10-15T14:30:38.622965Z","end":"2026-10-15T14:30:38.817827Z","steps":["trace[1861087725] 'agreement among raft nodes before linearized reading' (duration: 194.735451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:38.817969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.300747ms","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:7"} {"level":"info","ts":"2026-10-15T14:30:38.818021Z","caller":"traceutil/trace.go:171","msg":"trace[965628221] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:8980; }","duration":"145.401029ms","start":"2026-10-15T14:30:38.672605Z","end":"2026-10-15T14:30:38.818006Z","steps":["trace[965628221] 'agreement among raft nodes before linearized reading' (duration: 145.302027ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:38.817964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.526925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228439"} {"level":"info","ts":"2026-10-15T14:30:38.818098Z","caller":"traceutil/trace.go:171","msg":"trace[1182299966] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8980; }","duration":"227.693658ms","start":"2026-10-15T14:30:38.590383Z","end":"2026-10-15T14:30:38.818076Z","steps":["trace[1182299966] 'agreement among raft nodes before linearized reading' (duration: 227.214147ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:39.013872Z","caller":"traceutil/trace.go:171","msg":"trace[1867154868] transaction","detail":"{read_only:false; response_revision:8981; number_of_response:1; }","duration":"164.687311ms","start":"2026-10-15T14:30:38.849154Z","end":"2026-10-15T14:30:39.013841Z","steps":["trace[1867154868] 'process raft request' (duration: 129.825932ms)","trace[1867154868] 'compare' (duration: 34.557272ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:30:39.107788Z","caller":"traceutil/trace.go:171","msg":"trace[762227950] linearizableReadLoop","detail":"{readStateIndex:9304; appliedIndex:9302; }","duration":"247.968753ms","start":"2026-10-15T14:30:38.859788Z","end":"2026-10-15T14:30:39.107756Z","steps":["trace[762227950] 'read index received' (duration: 119.208135ms)","trace[762227950] 'applied index is now lower than readState.Index' (duration: 128.759208ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:30:39.107887Z","caller":"traceutil/trace.go:171","msg":"trace[936775260] transaction","detail":"{read_only:false; response_revision:8982; number_of_response:1; }","duration":"257.566417ms","start":"2026-10-15T14:30:38.850284Z","end":"2026-10-15T14:30:39.10785Z","steps":["trace[936775260] 'process raft request' (duration: 257.223779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:39.107925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.106506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:30:39.10797Z","caller":"traceutil/trace.go:171","msg":"trace[1720572249] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:8982; }","duration":"248.201547ms","start":"2026-10-15T14:30:38.859758Z","end":"2026-10-15T14:30:39.107959Z","steps":["trace[1720572249] 'agreement among raft nodes before linearized reading' (duration: 248.101465ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:49.297953Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024ad.wal"} {"level":"info","ts":"2026-10-15T14:31:54.63201Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"373d5d49ce4adaff","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T14:31:54.640706Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T14:31:54.640826Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T14:31:59.075216Z","caller":"traceutil/trace.go:171","msg":"trace[2045300128] transaction","detail":"{read_only:false; response_revision:9677; number_of_response:1; }","duration":"201.871452ms","start":"2026-10-15T14:31:58.87332Z","end":"2026-10-15T14:31:59.075192Z","steps":["trace[2045300128] 'process raft request' (duration: 201.734389ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:31:59.144Z","caller":"traceutil/trace.go:171","msg":"trace[886674679] transaction","detail":"{read_only:false; response_revision:9678; number_of_response:1; }","duration":"134.473796ms","start":"2026-10-15T14:31:59.009495Z","end":"2026-10-15T14:31:59.143969Z","steps":["trace[886674679] 'process raft request' (duration: 133.986295ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:32:36.704313Z","caller":"traceutil/trace.go:171","msg":"trace[640827863] transaction","detail":"{read_only:false; response_revision:10025; number_of_response:1; }","duration":"122.438088ms","start":"2026-10-15T14:32:36.58185Z","end":"2026-10-15T14:32:36.704288Z","steps":["trace[640827863] 'process raft request' (duration: 122.196132ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:31.045368Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7978} {"level":"info","ts":"2026-10-15T14:33:31.120363Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7978,"took":"72.951293ms","hash":3883105097,"current-db-size-bytes":56487936,"current-db-size":"56 MB","current-db-size-in-use-bytes":28798976,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-15T14:33:31.120442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3883105097,"revision":7978,"compact-revision":5738} {"level":"warn","ts":"2026-10-15T14:33:54.018142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.69211ms","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-10-15T14:33:54.018269Z","caller":"traceutil/trace.go:171","msg":"trace[281835336] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10596; }","duration":"116.845094ms","start":"2026-10-15T14:33:53.901376Z","end":"2026-10-15T14:33:54.018221Z","steps":["trace[281835336] 'range keys from in-memory index tree' (duration: 116.553687ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:38:31.056474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10432} {"level":"info","ts":"2026-10-15T14:38:31.151096Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10432,"took":"92.264382ms","hash":542544219,"current-db-size-bytes":56487936,"current-db-size":"56 MB","current-db-size-in-use-bytes":27987968,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T14:38:31.151235Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":542544219,"revision":10432,"compact-revision":7978} {"level":"info","ts":"2026-10-15T14:41:53.572424Z","caller":"traceutil/trace.go:171","msg":"trace[482444731] transaction","detail":"{read_only:false; response_revision:14124; number_of_response:1; }","duration":"252.008362ms","start":"2026-10-15T14:41:53.320383Z","end":"2026-10-15T14:41:53.572392Z","steps":["trace[482444731] 'process raft request' (duration: 251.815197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:42:10.107646Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15780508915197090969,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-15T14:42:10.123172Z","caller":"traceutil/trace.go:171","msg":"trace[1981004791] transaction","detail":"{read_only:false; response_revision:14221; number_of_response:1; }","duration":"557.333512ms","start":"2026-10-15T14:42:09.565807Z","end":"2026-10-15T14:42:10.12314Z","steps":["trace[1981004791] 'process raft request' (duration: 557.126087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:42:10.123384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:42:09.565763Z","time spent":"557.500065ms","remote":"127.0.0.1:47302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12479,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:42:10.125526Z","caller":"traceutil/trace.go:171","msg":"trace[139918499] transaction","detail":"{read_only:false; response_revision:14222; number_of_response:1; }","duration":"491.341127ms","start":"2026-10-15T14:42:09.634153Z","end":"2026-10-15T14:42:10.125494Z","steps":["trace[139918499] 'process raft request' (duration: 489.861515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:42:10.125677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:42:09.634125Z","time spent":"491.46538ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:42:10.125702Z","caller":"traceutil/trace.go:171","msg":"trace[1519398632] transaction","detail":"{read_only:false; response_revision:14223; number_of_response:1; }","duration":"188.092891ms","start":"2026-10-15T14:42:09.93757Z","end":"2026-10-15T14:42:10.125663Z","steps":["trace[1519398632] 'process raft request' (duration: 187.896527ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:42:10.136441Z","caller":"traceutil/trace.go:171","msg":"trace[1929943138] linearizableReadLoop","detail":"{readStateIndex:14717; appliedIndex:14713; }","duration":"529.011896ms","start":"2026-10-15T14:42:09.607397Z","end":"2026-10-15T14:42:10.136409Z","steps":["trace[1929943138] 'read index received' (duration: 515.595784ms)","trace[1929943138] 'applied index is now lower than readState.Index' (duration: 13.414602ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:42:10.136636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.210261ms","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-10-15T14:42:10.136677Z","caller":"traceutil/trace.go:171","msg":"trace[1077009786] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:14223; }","duration":"529.305463ms","start":"2026-10-15T14:42:09.607358Z","end":"2026-10-15T14:42:10.136664Z","steps":["trace[1077009786] 'agreement among raft nodes before linearized reading' (duration: 529.2093ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:42:10.13671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:42:09.607338Z","time spent":"529.363604ms","remote":"127.0.0.1:54292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-10-15T14:42:10.136726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.17018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-15T14:42:10.136778Z","caller":"traceutil/trace.go:171","msg":"trace[939223912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14223; }","duration":"466.277852ms","start":"2026-10-15T14:42:09.670484Z","end":"2026-10-15T14:42:10.136762Z","steps":["trace[939223912] 'agreement among raft nodes before linearized reading' (duration: 466.126408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:42:10.136818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:42:09.670466Z","time spent":"466.344283ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-15T14:42:20.434766Z","caller":"traceutil/trace.go:171","msg":"trace[46480096] transaction","detail":"{read_only:false; response_revision:14389; number_of_response:1; }","duration":"256.534189ms","start":"2026-10-15T14:42:20.178206Z","end":"2026-10-15T14:42:20.43474Z","steps":["trace[46480096] 'process raft request' (duration: 256.373065ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:42:20.716078Z","caller":"traceutil/trace.go:171","msg":"trace[1184997930] linearizableReadLoop","detail":"{readStateIndex:14887; appliedIndex:14886; }","duration":"518.034427ms","start":"2026-10-15T14:42:20.198021Z","end":"2026-10-15T14:42:20.716056Z","steps":["trace[1184997930] 'read index received' (duration: 236.702648ms)","trace[1184997930] 'applied index is now lower than readState.Index' (duration: 281.330479ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:42:20.716413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.376104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-15T14:42:20.716414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.310424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:42:20.716471Z","caller":"traceutil/trace.go:171","msg":"trace[1518803753] transaction","detail":"{read_only:false; response_revision:14391; number_of_response:1; }","duration":"418.969772ms","start":"2026-10-15T14:42:20.297486Z","end":"2026-10-15T14:42:20.716456Z","steps":["trace[1518803753] 'process raft request' (duration: 418.522732ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:42:20.716486Z","caller":"traceutil/trace.go:171","msg":"trace[238566781] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14391; }","duration":"518.491387ms","start":"2026-10-15T14:42:20.197977Z","end":"2026-10-15T14:42:20.716468Z","steps":["trace[238566781] 'agreement among raft nodes before linearized reading' (duration: 518.238291ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:42:20.716473Z","caller":"traceutil/trace.go:171","msg":"trace[1772783738] transaction","detail":"{read_only:false; response_revision:14390; number_of_response:1; }","duration":"537.376557ms","start":"2026-10-15T14:42:20.179061Z","end":"2026-10-15T14:42:20.716438Z","steps":["trace[1772783738] 'process raft request' (duration: 493.427531ms)","trace[1772783738] 'compare' (duration: 43.375584ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:42:20.716505Z","caller":"traceutil/trace.go:171","msg":"trace[987870192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14391; }","duration":"450.441887ms","start":"2026-10-15T14:42:20.266037Z","end":"2026-10-15T14:42:20.716478Z","steps":["trace[987870192] 'agreement among raft nodes before linearized reading' (duration: 450.263243ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:42:20.716523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:42:20.197961Z","time spent":"518.554148ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-15T14:42:20.71655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:42:20.266019Z","time spent":"450.523178ms","remote":"127.0.0.1:47112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T14:42:20.716575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:42:20.297453Z","time spent":"419.050904ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:42:20.716566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.721485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b76k6\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-10-15T14:42:20.71663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:42:20.17905Z","time spent":"537.492819ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:42:20.716647Z","caller":"traceutil/trace.go:171","msg":"trace[1001901604] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b76k6; range_end:; response_count:1; response_revision:14391; }","duration":"137.838328ms","start":"2026-10-15T14:42:20.578788Z","end":"2026-10-15T14:42:20.716626Z","steps":["trace[1001901604] 'agreement among raft nodes before linearized reading' (duration: 137.585763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:42:20.716677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.828776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-qttfp.18debb06a1566634\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-10-15T14:42:20.716708Z","caller":"traceutil/trace.go:171","msg":"trace[1249883263] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-qttfp.18debb06a1566634; range_end:; response_count:1; response_revision:14391; }","duration":"296.888408ms","start":"2026-10-15T14:42:20.419808Z","end":"2026-10-15T14:42:20.716696Z","steps":["trace[1249883263] 'agreement among raft nodes before linearized reading' (duration: 296.798216ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:43:26.30819Z","caller":"traceutil/trace.go:171","msg":"trace[1310940217] linearizableReadLoop","detail":"{readStateIndex:15823; appliedIndex:15822; }","duration":"106.946356ms","start":"2026-10-15T14:43:26.201214Z","end":"2026-10-15T14:43:26.30816Z","steps":["trace[1310940217] 'read index received' (duration: 106.520507ms)","trace[1310940217] 'applied index is now lower than readState.Index' (duration: 424.509µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:43:26.308351Z","caller":"traceutil/trace.go:171","msg":"trace[467685273] transaction","detail":"{read_only:false; response_revision:15298; number_of_response:1; }","duration":"156.216467ms","start":"2026-10-15T14:43:26.15209Z","end":"2026-10-15T14:43:26.308307Z","steps":["trace[467685273] 'process raft request' (duration: 155.737527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:43:26.3085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.262253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1565"} {"level":"info","ts":"2026-10-15T14:43:26.30857Z","caller":"traceutil/trace.go:171","msg":"trace[1719847327] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:15298; }","duration":"107.377366ms","start":"2026-10-15T14:43:26.201172Z","end":"2026-10-15T14:43:26.30855Z","steps":["trace[1719847327] 'agreement among raft nodes before linearized reading' (duration: 107.147561ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:43:31.06337Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12752} {"level":"info","ts":"2026-10-15T14:43:31.147373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12752,"took":"81.329329ms","hash":3056721358,"current-db-size-bytes":56487936,"current-db-size":"56 MB","current-db-size-in-use-bytes":30851072,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T14:43:31.147451Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3056721358,"revision":12752,"compact-revision":10432} {"level":"info","ts":"2026-10-15T14:44:00.036048Z","caller":"traceutil/trace.go:171","msg":"trace[1795981248] transaction","detail":"{read_only:false; response_revision:15481; number_of_response:1; }","duration":"119.635322ms","start":"2026-10-15T14:43:59.916391Z","end":"2026-10-15T14:44:00.036027Z","steps":["trace[1795981248] 'process raft request' (duration: 119.280485ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:44:01.347067Z","caller":"traceutil/trace.go:171","msg":"trace[2115068461] transaction","detail":"{read_only:false; response_revision:15483; number_of_response:1; }","duration":"257.363198ms","start":"2026-10-15T14:44:01.08968Z","end":"2026-10-15T14:44:01.347044Z","steps":["trace[2115068461] 'process raft request' (duration: 247.177246ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:44:01.398361Z","caller":"traceutil/trace.go:171","msg":"trace[1087970931] linearizableReadLoop","detail":"{readStateIndex:16017; appliedIndex:16015; }","duration":"288.383912ms","start":"2026-10-15T14:44:01.109947Z","end":"2026-10-15T14:44:01.398331Z","steps":["trace[1087970931] 'read index received' (duration: 226.926086ms)","trace[1087970931] 'applied index is now lower than readState.Index' (duration: 61.456406ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:44:01.398417Z","caller":"traceutil/trace.go:171","msg":"trace[621290814] transaction","detail":"{read_only:false; response_revision:15484; number_of_response:1; }","duration":"308.372268ms","start":"2026-10-15T14:44:01.090007Z","end":"2026-10-15T14:44:01.398379Z","steps":["trace[621290814] 'process raft request' (duration: 308.134772ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:44:01.398585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:44:01.089979Z","time spent":"308.526481ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:44:01.398579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.815403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:44:01.39865Z","caller":"traceutil/trace.go:171","msg":"trace[1281186469] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:15484; }","duration":"283.921286ms","start":"2026-10-15T14:44:01.114711Z","end":"2026-10-15T14:44:01.398632Z","steps":["trace[1281186469] 'agreement among raft nodes before linearized reading' (duration: 283.802343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:44:01.398777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.367982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-15T14:44:01.398824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.057996ms","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-10-15T14:44:01.398854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.892094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-10-15T14:44:01.398992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.759452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-hhx7x\" ","response":"range_response_count:1 size:20648"} {"level":"warn","ts":"2026-10-15T14:44:01.398965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.964651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-15T14:44:01.399095Z","caller":"traceutil/trace.go:171","msg":"trace[1980972123] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15484; }","duration":"115.157385ms","start":"2026-10-15T14:44:01.283918Z","end":"2026-10-15T14:44:01.399075Z","steps":["trace[1980972123] 'agreement among raft nodes before linearized reading' (duration: 114.842938ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:44:01.398883Z","caller":"traceutil/trace.go:171","msg":"trace[1927117012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15484; }","duration":"111.157298ms","start":"2026-10-15T14:44:01.28771Z","end":"2026-10-15T14:44:01.398867Z","steps":["trace[1927117012] 'agreement among raft nodes before linearized reading' (duration: 110.984344ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:44:01.39912Z","caller":"traceutil/trace.go:171","msg":"trace[987165357] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15484; }","duration":"289.111938ms","start":"2026-10-15T14:44:01.109901Z","end":"2026-10-15T14:44:01.399013Z","steps":["trace[987165357] 'agreement among raft nodes before linearized reading' (duration: 288.577157ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:44:01.398967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.057477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:44:01.399581Z","caller":"traceutil/trace.go:171","msg":"trace[134545459] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:15484; }","duration":"140.69295ms","start":"2026-10-15T14:44:01.258871Z","end":"2026-10-15T14:44:01.399564Z","steps":["trace[134545459] 'agreement among raft nodes before linearized reading' (duration: 140.050806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:44:01.398966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.634805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:44:01.399805Z","caller":"traceutil/trace.go:171","msg":"trace[1091142293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15484; }","duration":"133.478354ms","start":"2026-10-15T14:44:01.266287Z","end":"2026-10-15T14:44:01.399765Z","steps":["trace[1091142293] 'agreement among raft nodes before linearized reading' (duration: 132.621835ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:44:01.399021Z","caller":"traceutil/trace.go:171","msg":"trace[1494415718] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-hhx7x; range_end:; response_count:1; response_revision:15484; }","duration":"180.822013ms","start":"2026-10-15T14:44:01.21819Z","end":"2026-10-15T14:44:01.399012Z","steps":["trace[1494415718] 'agreement among raft nodes before linearized reading' (duration: 180.728161ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:44:01.398882Z","caller":"traceutil/trace.go:171","msg":"trace[1392861374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15484; }","duration":"122.479054ms","start":"2026-10-15T14:44:01.276375Z","end":"2026-10-15T14:44:01.398854Z","steps":["trace[1392861374] 'agreement among raft nodes before linearized reading' (duration: 122.212968ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:31.071996Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15326} {"level":"info","ts":"2026-10-15T14:48:31.178711Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15326,"took":"102.778808ms","hash":4230138520,"current-db-size-bytes":56487936,"current-db-size":"56 MB","current-db-size-in-use-bytes":31830016,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T14:48:31.178785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4230138520,"revision":15326,"compact-revision":12752} {"level":"info","ts":"2026-10-15T14:49:23.314829Z","caller":"traceutil/trace.go:171","msg":"trace[1333754865] transaction","detail":"{read_only:false; response_revision:17840; number_of_response:1; }","duration":"116.087814ms","start":"2026-10-15T14:49:23.198696Z","end":"2026-10-15T14:49:23.314783Z","steps":["trace[1333754865] 'process raft request' (duration: 107.675487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:49:29.730311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.562955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T14:49:29.730444Z","caller":"traceutil/trace.go:171","msg":"trace[497804507] linearizableReadLoop","detail":"{readStateIndex:18531; appliedIndex:18529; }","duration":"203.11124ms","start":"2026-10-15T14:49:29.527313Z","end":"2026-10-15T14:49:29.730424Z","steps":["trace[497804507] 'read index received' (duration: 23.112754ms)","trace[497804507] 'applied index is now lower than readState.Index' (duration: 179.997236ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:49:29.730488Z","caller":"traceutil/trace.go:171","msg":"trace[2077356156] transaction","detail":"{read_only:false; response_revision:17917; number_of_response:1; }","duration":"204.588143ms","start":"2026-10-15T14:49:29.525854Z","end":"2026-10-15T14:49:29.730442Z","steps":["trace[2077356156] 'process raft request' (duration: 82.774482ms)","trace[2077356156] 'compare' (duration: 121.422362ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:49:29.73064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.323944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1438"} {"level":"info","ts":"2026-10-15T14:49:29.730684Z","caller":"traceutil/trace.go:171","msg":"trace[1683060540] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:17917; }","duration":"203.396766ms","start":"2026-10-15T14:49:29.52727Z","end":"2026-10-15T14:49:29.730667Z","steps":["trace[1683060540] 'agreement among raft nodes before linearized reading' (duration: 203.246123ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:49:29.73085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.38622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T14:49:29.730912Z","caller":"traceutil/trace.go:171","msg":"trace[1299084818] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:17917; }","duration":"111.465501ms","start":"2026-10-15T14:49:29.619428Z","end":"2026-10-15T14:49:29.730894Z","steps":["trace[1299084818] 'agreement among raft nodes before linearized reading' (duration: 111.324918ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:49:29.730944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.578404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"warn","ts":"2026-10-15T14:49:29.730987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.624874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-heat-server\" ","response":"range_response_count:1 size:9566"} {"level":"info","ts":"2026-10-15T14:49:29.731029Z","caller":"traceutil/trace.go:171","msg":"trace[1783621407] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:17917; }","duration":"187.769869ms","start":"2026-10-15T14:49:29.543237Z","end":"2026-10-15T14:49:29.731007Z","steps":["trace[1783621407] 'agreement among raft nodes before linearized reading' (duration: 187.489473ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:49:29.731089Z","caller":"traceutil/trace.go:171","msg":"trace[1323474621] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-heat-server; range_end:; response_count:1; response_revision:17917; }","duration":"164.810478ms","start":"2026-10-15T14:49:29.566257Z","end":"2026-10-15T14:49:29.731067Z","steps":["trace[1323474621] 'agreement among raft nodes before linearized reading' (duration: 164.539872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:49:44.096998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.440607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-hhx7x\" ","response":"range_response_count:1 size:20751"} {"level":"warn","ts":"2026-10-15T14:49:44.097095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.79635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:3227"} {"level":"info","ts":"2026-10-15T14:49:44.097128Z","caller":"traceutil/trace.go:171","msg":"trace[136236932] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-hhx7x; range_end:; response_count:1; response_revision:18058; }","duration":"243.61266ms","start":"2026-10-15T14:49:43.85349Z","end":"2026-10-15T14:49:44.097103Z","steps":["trace[136236932] 'range keys from in-memory index tree' (duration: 243.278993ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:49:44.097405Z","caller":"traceutil/trace.go:171","msg":"trace[774062032] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:18058; }","duration":"126.964604ms","start":"2026-10-15T14:49:43.970186Z","end":"2026-10-15T14:49:44.09715Z","steps":["trace[774062032] 'range keys from in-memory index tree' (duration: 126.657707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:49:44.097795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.384815ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:49:44.097882Z","caller":"traceutil/trace.go:171","msg":"trace[699231926] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:18058; }","duration":"123.474517ms","start":"2026-10-15T14:49:43.974394Z","end":"2026-10-15T14:49:44.097869Z","steps":["trace[699231926] 'range keys from in-memory index tree' (duration: 123.374915ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:49:44.098387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.703643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:49:44.098459Z","caller":"traceutil/trace.go:171","msg":"trace[1038079581] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:18058; }","duration":"228.824065ms","start":"2026-10-15T14:49:43.869619Z","end":"2026-10-15T14:49:44.098443Z","steps":["trace[1038079581] 'count revisions from in-memory index tree' (duration: 228.619301ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:49:44.858349Z","caller":"traceutil/trace.go:171","msg":"trace[805512211] linearizableReadLoop","detail":"{readStateIndex:18678; appliedIndex:18677; }","duration":"166.112466ms","start":"2026-10-15T14:49:44.692206Z","end":"2026-10-15T14:49:44.858318Z","steps":["trace[805512211] 'read index received' (duration: 117.512786ms)","trace[805512211] 'applied index is now lower than readState.Index' (duration: 48.59854ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:49:44.85844Z","caller":"traceutil/trace.go:171","msg":"trace[2029409549] transaction","detail":"{read_only:false; response_revision:18060; number_of_response:1; }","duration":"475.094064ms","start":"2026-10-15T14:49:44.383312Z","end":"2026-10-15T14:49:44.858406Z","steps":["trace[2029409549] 'process raft request' (duration: 426.380182ms)","trace[2029409549] 'compare' (duration: 47.437563ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:49:44.858589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:49:44.383263Z","time spent":"475.242957ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:49:44.858985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.75267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.10\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-15T14:49:44.859059Z","caller":"traceutil/trace.go:171","msg":"trace[906562434] range","detail":"{range_begin:/registry/masterleases/162.253.55.10; range_end:; response_count:1; response_revision:18060; }","duration":"166.874542ms","start":"2026-10-15T14:49:44.692165Z","end":"2026-10-15T14:49:44.859039Z","steps":["trace[906562434] 'agreement among raft nodes before linearized reading' (duration: 166.302849ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:49:44.876313Z","caller":"traceutil/trace.go:171","msg":"trace[1627702917] transaction","detail":"{read_only:false; response_revision:18062; number_of_response:1; }","duration":"130.258886ms","start":"2026-10-15T14:49:44.745974Z","end":"2026-10-15T14:49:44.876233Z","steps":["trace[1627702917] 'process raft request' (duration: 130.204005ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:49:44.876745Z","caller":"traceutil/trace.go:171","msg":"trace[1744791497] transaction","detail":"{read_only:false; response_revision:18061; number_of_response:1; }","duration":"138.392516ms","start":"2026-10-15T14:49:44.738335Z","end":"2026-10-15T14:49:44.876727Z","steps":["trace[1744791497] 'process raft request' (duration: 137.740791ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:49:45.121539Z","caller":"traceutil/trace.go:171","msg":"trace[1002412491] linearizableReadLoop","detail":"{readStateIndex:18682; appliedIndex:18681; }","duration":"231.113766ms","start":"2026-10-15T14:49:44.890387Z","end":"2026-10-15T14:49:45.121501Z","steps":["trace[1002412491] 'read index received' (duration: 230.704217ms)","trace[1002412491] 'applied index is now lower than readState.Index' (duration: 408.259µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:49:45.121675Z","caller":"traceutil/trace.go:171","msg":"trace[1305390488] transaction","detail":"{read_only:false; response_revision:18063; number_of_response:1; }","duration":"231.468863ms","start":"2026-10-15T14:49:44.890122Z","end":"2026-10-15T14:49:45.121591Z","steps":["trace[1305390488] 'process raft request' (duration: 231.052714ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:49:45.121997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.564046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19581"} {"level":"info","ts":"2026-10-15T14:49:45.122054Z","caller":"traceutil/trace.go:171","msg":"trace[1563429848] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18063; }","duration":"231.676928ms","start":"2026-10-15T14:49:44.890362Z","end":"2026-10-15T14:49:45.122039Z","steps":["trace[1563429848] 'agreement among raft nodes before linearized reading' (duration: 231.269899ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:52:14.330304Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"373d5d49ce4adaff","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T14:52:14.337003Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T14:52:14.337122Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T14:53:31.079168Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17492} {"level":"info","ts":"2026-10-15T14:53:31.181239Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17492,"took":"98.744223ms","hash":1880807916,"current-db-size-bytes":56487936,"current-db-size":"56 MB","current-db-size-in-use-bytes":33583104,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T14:53:31.181327Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1880807916,"revision":17492,"compact-revision":15326} {"level":"warn","ts":"2026-10-15T14:54:32.926348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.72456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T14:54:32.926435Z","caller":"traceutil/trace.go:171","msg":"trace[242970035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20315; }","duration":"117.877734ms","start":"2026-10-15T14:54:32.808538Z","end":"2026-10-15T14:54:32.926416Z","steps":["trace[242970035] 'range keys from in-memory index tree' (duration: 117.474845ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:54:32.92639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.376851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-15T14:54:32.926505Z","caller":"traceutil/trace.go:171","msg":"trace[729291418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20315; }","duration":"116.567585ms","start":"2026-10-15T14:54:32.80992Z","end":"2026-10-15T14:54:32.926487Z","steps":["trace[729291418] 'range keys from in-memory index tree' (duration: 116.118815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:03.856881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.000277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T14:55:03.856983Z","caller":"traceutil/trace.go:171","msg":"trace[1032625811] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:20484; }","duration":"377.16406ms","start":"2026-10-15T14:55:03.479795Z","end":"2026-10-15T14:55:03.856959Z","steps":["trace[1032625811] 'count revisions from in-memory index tree' (duration: 376.898994ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:03.856958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.754627ms","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:6"} {"level":"warn","ts":"2026-10-15T14:55:03.857029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:03.479774Z","time spent":"377.243732ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":43,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-10-15T14:55:03.857057Z","caller":"traceutil/trace.go:171","msg":"trace[758426746] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:20484; }","duration":"198.89794ms","start":"2026-10-15T14:55:03.65812Z","end":"2026-10-15T14:55:03.857018Z","steps":["trace[758426746] 'count revisions from in-memory index tree' (duration: 198.561682ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:03.85738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.59092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T14:55:03.857469Z","caller":"traceutil/trace.go:171","msg":"trace[1042883737] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20484; }","duration":"615.725213ms","start":"2026-10-15T14:55:03.24172Z","end":"2026-10-15T14:55:03.857446Z","steps":["trace[1042883737] 'range keys from in-memory index tree' (duration: 615.332574ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:03.857509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:03.241698Z","time spent":"615.801215ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T14:55:03.857428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.462834ms","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-10-15T14:55:03.857854Z","caller":"traceutil/trace.go:171","msg":"trace[901260995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20484; }","duration":"627.880903ms","start":"2026-10-15T14:55:03.229882Z","end":"2026-10-15T14:55:03.857763Z","steps":["trace[901260995] 'range keys from in-memory index tree' (duration: 626.966863ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:03.857928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.100617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:55:03.85802Z","caller":"traceutil/trace.go:171","msg":"trace[1969232999] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:20484; }","duration":"254.11519ms","start":"2026-10-15T14:55:03.603886Z","end":"2026-10-15T14:55:03.858001Z","steps":["trace[1969232999] 'count revisions from in-memory index tree' (duration: 253.009064ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:03.858121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:03.229853Z","time spent":"628.15249ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T14:55:04.28924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.844112ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:55:04.289409Z","caller":"traceutil/trace.go:171","msg":"trace[248765545] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:20485; }","duration":"315.027656ms","start":"2026-10-15T14:55:03.974361Z","end":"2026-10-15T14:55:04.289388Z","steps":["trace[248765545] 'range keys from in-memory index tree' (duration: 314.802891ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:04.289429Z","caller":"traceutil/trace.go:171","msg":"trace[976357437] linearizableReadLoop","detail":"{readStateIndex:21186; appliedIndex:21185; }","duration":"327.82197ms","start":"2026-10-15T14:55:03.961593Z","end":"2026-10-15T14:55:04.289415Z","steps":["trace[976357437] 'read index received' (duration: 324.966547ms)","trace[976357437] 'applied index is now lower than readState.Index' (duration: 2.854553ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:55:04.289495Z","caller":"traceutil/trace.go:171","msg":"trace[205988243] transaction","detail":"{read_only:false; response_revision:20486; number_of_response:1; }","duration":"426.941088ms","start":"2026-10-15T14:55:03.862544Z","end":"2026-10-15T14:55:04.289485Z","steps":["trace[205988243] 'process raft request' (duration: 423.999322ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:04.289596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:03.862511Z","time spent":"427.01411ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:55:04.289783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.167368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:55:04.28987Z","caller":"traceutil/trace.go:171","msg":"trace[1746562588] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:20486; }","duration":"328.293611ms","start":"2026-10-15T14:55:03.961538Z","end":"2026-10-15T14:55:04.289831Z","steps":["trace[1746562588] 'agreement among raft nodes before linearized reading' (duration: 328.151768ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:04.289917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:03.961514Z","time spent":"328.393043ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"warn","ts":"2026-10-15T14:55:04.79033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15780508915197111557,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-15T14:55:05.045043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.368003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T14:55:05.045277Z","caller":"traceutil/trace.go:171","msg":"trace[999984416] transaction","detail":"{read_only:false; response_revision:20487; number_of_response:1; }","duration":"970.686007ms","start":"2026-10-15T14:55:04.07454Z","end":"2026-10-15T14:55:05.045226Z","steps":["trace[999984416] 'process raft request' (duration: 786.786972ms)","trace[999984416] 'compare' (duration: 183.145708ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:55:05.045388Z","caller":"traceutil/trace.go:171","msg":"trace[1056575979] linearizableReadLoop","detail":"{readStateIndex:21189; appliedIndex:21186; }","duration":"755.907914ms","start":"2026-10-15T14:55:04.289458Z","end":"2026-10-15T14:55:05.045365Z","steps":["trace[1056575979] 'read index received' (duration: 571.644871ms)","trace[1056575979] 'applied index is now lower than readState.Index' (duration: 184.261643ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:55:05.045426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:04.074515Z","time spent":"970.832761ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:55:05.045766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.432258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:55:05.045864Z","caller":"traceutil/trace.go:171","msg":"trace[568293804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20489; }","duration":"779.598402ms","start":"2026-10-15T14:55:04.266242Z","end":"2026-10-15T14:55:05.04584Z","steps":["trace[568293804] 'agreement among raft nodes before linearized reading' (duration: 779.429098ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:05.045871Z","caller":"traceutil/trace.go:171","msg":"trace[179819184] transaction","detail":"{read_only:false; response_revision:20489; number_of_response:1; }","duration":"951.290776ms","start":"2026-10-15T14:55:04.094543Z","end":"2026-10-15T14:55:05.045834Z","steps":["trace[179819184] 'process raft request' (duration: 950.754014ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:05.045849Z","caller":"traceutil/trace.go:171","msg":"trace[478276893] transaction","detail":"{read_only:false; response_revision:20488; number_of_response:1; }","duration":"953.702439ms","start":"2026-10-15T14:55:04.092111Z","end":"2026-10-15T14:55:05.045814Z","steps":["trace[478276893] 'process raft request' (duration: 953.039945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:05.045923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:04.266219Z","time spent":"779.691913ms","remote":"127.0.0.1:47112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T14:55:05.045961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.455075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:560"} {"level":"warn","ts":"2026-10-15T14:55:05.046005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.672457ms","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-10-15T14:55:05.046011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.399824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.10\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-10-15T14:55:05.046025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.878155ms","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-10-15T14:55:05.046036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:04.094527Z","time spent":"951.441159ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:55:05.046065Z","caller":"traceutil/trace.go:171","msg":"trace[1132611082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20489; }","duration":"208.77225ms","start":"2026-10-15T14:55:04.837277Z","end":"2026-10-15T14:55:05.046049Z","steps":["trace[1132611082] 'agreement among raft nodes before linearized reading' (duration: 208.547255ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:05.046086Z","caller":"traceutil/trace.go:171","msg":"trace[1444664619] range","detail":"{range_begin:/registry/masterleases/162.253.55.10; range_end:; response_count:1; response_revision:20489; }","duration":"337.517847ms","start":"2026-10-15T14:55:04.708545Z","end":"2026-10-15T14:55:05.046063Z","steps":["trace[1444664619] 'agreement among raft nodes before linearized reading' (duration: 337.262871ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:05.046088Z","caller":"traceutil/trace.go:171","msg":"trace[640717518] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20489; }","duration":"205.991348ms","start":"2026-10-15T14:55:04.840081Z","end":"2026-10-15T14:55:05.046072Z","steps":["trace[640717518] 'agreement among raft nodes before linearized reading' (duration: 205.784593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:05.046119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.175795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418236"} {"level":"warn","ts":"2026-10-15T14:55:05.046138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:04.708519Z","time spent":"337.607948ms","remote":"127.0.0.1:47140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.10\" "} {"level":"warn","ts":"2026-10-15T14:55:05.046029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:04.092098Z","time spent":"953.856302ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:55:05.046019Z","caller":"traceutil/trace.go:171","msg":"trace[485898376] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:20489; }","duration":"905.534036ms","start":"2026-10-15T14:55:04.140466Z","end":"2026-10-15T14:55:05.046Z","steps":["trace[485898376] 'agreement among raft nodes before linearized reading' (duration: 905.326552ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:05.046192Z","caller":"traceutil/trace.go:171","msg":"trace[223283906] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:20489; }","duration":"234.253016ms","start":"2026-10-15T14:55:04.811919Z","end":"2026-10-15T14:55:05.046172Z","steps":["trace[223283906] 'agreement among raft nodes before linearized reading' (duration: 233.94915ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:05.046225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:04.140454Z","time spent":"905.755831ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-10-15T14:55:05.427676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:05.046999Z","time spent":"380.596986ms","remote":"127.0.0.1:47140","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-15T14:55:05.427842Z","caller":"traceutil/trace.go:171","msg":"trace[1911132838] transaction","detail":"{read_only:false; response_revision:20492; number_of_response:1; }","duration":"376.062035ms","start":"2026-10-15T14:55:05.051758Z","end":"2026-10-15T14:55:05.42782Z","steps":["trace[1911132838] 'process raft request' (duration: 376.026644ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:05.427939Z","caller":"traceutil/trace.go:171","msg":"trace[883781791] linearizableReadLoop","detail":"{readStateIndex:21190; appliedIndex:21189; }","duration":"380.745169ms","start":"2026-10-15T14:55:05.047142Z","end":"2026-10-15T14:55:05.427887Z","steps":["trace[883781791] 'read index received' (duration: 347.54257ms)","trace[883781791] 'applied index is now lower than readState.Index' (duration: 33.198889ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:55:05.428043Z","caller":"traceutil/trace.go:171","msg":"trace[918630441] transaction","detail":"{read_only:false; response_revision:20490; number_of_response:1; }","duration":"376.665128ms","start":"2026-10-15T14:55:05.051369Z","end":"2026-10-15T14:55:05.428034Z","steps":["trace[918630441] 'process raft request' (duration: 376.252409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:05.428005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:05.051736Z","time spent":"376.175048ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:55:05.428101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:05.051348Z","time spent":"376.715759ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:55:05.428216Z","caller":"traceutil/trace.go:171","msg":"trace[1150094323] transaction","detail":"{read_only:false; response_revision:20491; number_of_response:1; }","duration":"376.463433ms","start":"2026-10-15T14:55:05.051744Z","end":"2026-10-15T14:55:05.428208Z","steps":["trace[1150094323] 'process raft request' (duration: 376.000653ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:05.428281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:05.05173Z","time spent":"376.509715ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:55:05.428352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.687469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-15T14:55:05.428367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.105177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:55:05.428408Z","caller":"traceutil/trace.go:171","msg":"trace[2057783019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20492; }","duration":"310.812231ms","start":"2026-10-15T14:55:05.117581Z","end":"2026-10-15T14:55:05.428394Z","steps":["trace[2057783019] 'agreement among raft nodes before linearized reading' (duration: 310.587066ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:05.428446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:05.117568Z","time spent":"310.867203ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-15T14:55:05.428479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.106332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-10-15T14:55:05.428515Z","caller":"traceutil/trace.go:171","msg":"trace[1726301303] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:20492; }","duration":"220.178033ms","start":"2026-10-15T14:55:05.208328Z","end":"2026-10-15T14:55:05.428506Z","steps":["trace[1726301303] 'agreement among raft nodes before linearized reading' (duration: 220.05617ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:05.428618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.289888ms","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-10-15T14:55:05.428666Z","caller":"traceutil/trace.go:171","msg":"trace[1874327393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20492; }","duration":"309.336199ms","start":"2026-10-15T14:55:05.119317Z","end":"2026-10-15T14:55:05.428653Z","steps":["trace[1874327393] 'agreement among raft nodes before linearized reading' (duration: 309.222597ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:05.428708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:05.119232Z","time spent":"309.466001ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-15T14:55:05.429038Z","caller":"traceutil/trace.go:171","msg":"trace[1584766811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20492; }","duration":"381.295322ms","start":"2026-10-15T14:55:05.047113Z","end":"2026-10-15T14:55:05.428409Z","steps":["trace[1584766811] 'agreement among raft nodes before linearized reading' (duration: 381.084637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:05.429267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:05.047092Z","time spent":"382.026478ms","remote":"127.0.0.1:47124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-15T14:58:31.086363Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19969} {"level":"info","ts":"2026-10-15T14:58:31.209523Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19969,"took":"119.745373ms","hash":2445979825,"current-db-size-bytes":56487936,"current-db-size":"56 MB","current-db-size-in-use-bytes":30625792,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T14:58:31.209608Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2445979825,"revision":19969,"compact-revision":17492} {"level":"info","ts":"2026-10-15T14:59:03.632835Z","caller":"traceutil/trace.go:171","msg":"trace[307541839] linearizableReadLoop","detail":"{readStateIndex:23723; appliedIndex:23722; }","duration":"115.506769ms","start":"2026-10-15T14:59:03.517296Z","end":"2026-10-15T14:59:03.632803Z","steps":["trace[307541839] 'read index received' (duration: 115.322625ms)","trace[307541839] 'applied index is now lower than readState.Index' (duration: 182.864µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:59:03.632955Z","caller":"traceutil/trace.go:171","msg":"trace[213743773] transaction","detail":"{read_only:false; response_revision:22946; number_of_response:1; }","duration":"358.79098ms","start":"2026-10-15T14:59:03.274123Z","end":"2026-10-15T14:59:03.632914Z","steps":["trace[213743773] 'process raft request' (duration: 358.489883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:59:03.633149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:59:03.274102Z","time spent":"358.952094ms","remote":"127.0.0.1:47404","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-10-15T14:59:03.633157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.890448ms","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-10-15T14:59:03.63327Z","caller":"traceutil/trace.go:171","msg":"trace[586514849] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22946; }","duration":"116.064072ms","start":"2026-10-15T14:59:03.517184Z","end":"2026-10-15T14:59:03.633248Z","steps":["trace[586514849] 'agreement among raft nodes before linearized reading' (duration: 115.753935ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:59:44.330042Z","caller":"traceutil/trace.go:171","msg":"trace[1808937115] transaction","detail":"{read_only:false; response_revision:23372; number_of_response:1; }","duration":"118.606518ms","start":"2026-10-15T14:59:44.211404Z","end":"2026-10-15T14:59:44.330011Z","steps":["trace[1808937115] 'process raft request' (duration: 115.331055ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:02:30.043715Z","caller":"traceutil/trace.go:171","msg":"trace[2069200144] transaction","detail":"{read_only:false; response_revision:25065; number_of_response:1; }","duration":"369.615149ms","start":"2026-10-15T15:02:29.674075Z","end":"2026-10-15T15:02:30.04369Z","steps":["trace[2069200144] 'process raft request' (duration: 369.459476ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:02:30.043821Z","caller":"traceutil/trace.go:171","msg":"trace[1152739412] linearizableReadLoop","detail":"{readStateIndex:25891; appliedIndex:25891; }","duration":"268.10489ms","start":"2026-10-15T15:02:29.775688Z","end":"2026-10-15T15:02:30.043793Z","steps":["trace[1152739412] 'read index received' (duration: 268.09216ms)","trace[1152739412] 'applied index is now lower than readState.Index' (duration: 10.54µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:02:30.043859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:29.674058Z","time spent":"369.727102ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:02:30.044082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.366416ms","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-10-15T15:02:30.044115Z","caller":"traceutil/trace.go:171","msg":"trace[667113890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25065; }","duration":"268.445868ms","start":"2026-10-15T15:02:29.775658Z","end":"2026-10-15T15:02:30.044104Z","steps":["trace[667113890] 'agreement among raft nodes before linearized reading' (duration: 268.265724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:30.767448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.340614ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-15T15:02:30.767626Z","caller":"traceutil/trace.go:171","msg":"trace[1568558447] linearizableReadLoop","detail":"{readStateIndex:25892; appliedIndex:25891; }","duration":"723.699964ms","start":"2026-10-15T15:02:30.043893Z","end":"2026-10-15T15:02:30.767593Z","steps":["trace[1568558447] 'read index received' (duration: 131.222916ms)","trace[1568558447] 'applied index is now lower than readState.Index' (duration: 592.476218ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:02:30.767924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.16808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:02:30.767948Z","caller":"traceutil/trace.go:171","msg":"trace[168579306] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:25065; }","duration":"898.220612ms","start":"2026-10-15T15:02:29.86972Z","end":"2026-10-15T15:02:30.76794Z","steps":["trace[168579306] 'agreement among raft nodes before linearized reading' (duration: 898.177011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:30.767933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.606743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T15:02:30.767972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:29.869704Z","time spent":"898.262722ms","remote":"127.0.0.1:37568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"info","ts":"2026-10-15T15:02:30.767997Z","caller":"traceutil/trace.go:171","msg":"trace[179445429] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:25065; }","duration":"844.714415ms","start":"2026-10-15T15:02:29.923268Z","end":"2026-10-15T15:02:30.767982Z","steps":["trace[179445429] 'agreement among raft nodes before linearized reading' (duration: 844.621553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:30.768034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:29.923257Z","time spent":"844.767396ms","remote":"127.0.0.1:60338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true "} {"level":"warn","ts":"2026-10-15T15:02:30.76819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.880944ms","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-10-15T15:02:30.768237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.493812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-15T15:02:30.768254Z","caller":"traceutil/trace.go:171","msg":"trace[718969503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25065; }","duration":"840.528213ms","start":"2026-10-15T15:02:29.92772Z","end":"2026-10-15T15:02:30.768249Z","steps":["trace[718969503] 'agreement among raft nodes before linearized reading' (duration: 840.464012ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:02:30.768248Z","caller":"traceutil/trace.go:171","msg":"trace[1324173998] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25065; }","duration":"971.963025ms","start":"2026-10-15T15:02:29.796271Z","end":"2026-10-15T15:02:30.768234Z","steps":["trace[1324173998] 'agreement among raft nodes before linearized reading' (duration: 971.801321ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:30.768244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.458033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-15T15:02:30.768291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:29.796266Z","time spent":"972.004646ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T15:02:30.768314Z","caller":"traceutil/trace.go:171","msg":"trace[1209694710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25065; }","duration":"827.545726ms","start":"2026-10-15T15:02:29.940759Z","end":"2026-10-15T15:02:30.768305Z","steps":["trace[1209694710] 'agreement among raft nodes before linearized reading' (duration: 827.410383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:30.768342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:29.940749Z","time spent":"827.584476ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-15T15:02:30.768267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:29.927711Z","time spent":"840.553324ms","remote":"127.0.0.1:47404","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":"info","ts":"2026-10-15T15:02:30.769096Z","caller":"traceutil/trace.go:171","msg":"trace[869344380] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"720.907732ms","start":"2026-10-15T15:02:30.048175Z","end":"2026-10-15T15:02:30.769083Z","steps":["trace[869344380] 'process raft request' (duration: 719.340177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:30.769196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:30.048159Z","time spent":"720.974314ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:02:30.932835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.430176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:02:30.932892Z","caller":"traceutil/trace.go:171","msg":"trace[801178855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25066; }","duration":"666.530718ms","start":"2026-10-15T15:02:30.266347Z","end":"2026-10-15T15:02:30.932877Z","steps":["trace[801178855] 'agreement among raft nodes before linearized reading' (duration: 502.733139ms)","trace[801178855] 'range keys from in-memory index tree' (duration: 163.713377ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:02:30.932914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:30.266327Z","time spent":"666.581529ms","remote":"127.0.0.1:47112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T15:02:30.932972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.221697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:02:30.93301Z","caller":"traceutil/trace.go:171","msg":"trace[1737776190] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25066; }","duration":"409.287108ms","start":"2026-10-15T15:02:30.523712Z","end":"2026-10-15T15:02:30.932999Z","steps":["trace[1737776190] 'agreement among raft nodes before linearized reading' (duration: 245.383077ms)","trace[1737776190] 'count revisions from in-memory index tree' (duration: 163.84991ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:02:30.933033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:30.523698Z","time spent":"409.32797ms","remote":"127.0.0.1:59790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-15T15:02:30.933192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.322981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:02:30.933213Z","caller":"traceutil/trace.go:171","msg":"trace[260674166] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:25066; }","duration":"294.388833ms","start":"2026-10-15T15:02:30.638817Z","end":"2026-10-15T15:02:30.933206Z","steps":["trace[260674166] 'agreement among raft nodes before linearized reading' (duration: 130.288627ms)","trace[260674166] 'count revisions from in-memory index tree' (duration: 164.063664ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:02:30.933213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.116133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T15:02:30.933234Z","caller":"traceutil/trace.go:171","msg":"trace[1933790766] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:25066; }","duration":"348.188614ms","start":"2026-10-15T15:02:30.585039Z","end":"2026-10-15T15:02:30.933227Z","steps":["trace[1933790766] 'agreement among raft nodes before linearized reading' (duration: 184.039478ms)","trace[1933790766] 'count revisions from in-memory index tree' (duration: 164.105106ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:02:30.933256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:30.585016Z","time spent":"348.233066ms","remote":"127.0.0.1:47474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":125,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-10-15T15:02:30.933475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.234879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-10-15T15:02:30.933502Z","caller":"traceutil/trace.go:171","msg":"trace[1305387955] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25066; }","duration":"637.28189ms","start":"2026-10-15T15:02:30.296211Z","end":"2026-10-15T15:02:30.933492Z","steps":["trace[1305387955] 'agreement among raft nodes before linearized reading' (duration: 472.893738ms)","trace[1305387955] 'range keys from in-memory index tree' (duration: 164.307261ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:02:30.933522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:30.296196Z","time spent":"637.318821ms","remote":"127.0.0.1:47280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1874,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" "} {"level":"info","ts":"2026-10-15T15:02:30.933707Z","caller":"traceutil/trace.go:171","msg":"trace[756950369] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"160.777282ms","start":"2026-10-15T15:02:30.772919Z","end":"2026-10-15T15:02:30.933696Z","steps":["trace[756950369] 'process raft request' (duration: 160.6977ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:02:30.933762Z","caller":"traceutil/trace.go:171","msg":"trace[1347788180] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"161.860376ms","start":"2026-10-15T15:02:30.771893Z","end":"2026-10-15T15:02:30.933753Z","steps":["trace[1347788180] 'process raft request' (duration: 143.131951ms)","trace[1347788180] 'compare' (duration: 18.275075ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:02:30.933842Z","caller":"traceutil/trace.go:171","msg":"trace[125005927] transaction","detail":"{read_only:false; response_revision:25069; number_of_response:1; }","duration":"160.852334ms","start":"2026-10-15T15:02:30.772981Z","end":"2026-10-15T15:02:30.933833Z","steps":["trace[125005927] 'process raft request' (duration: 160.68492ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:02:30.933792Z","caller":"traceutil/trace.go:171","msg":"trace[1027949248] linearizableReadLoop","detail":"{readStateIndex:25896; appliedIndex:25893; }","duration":"130.923711ms","start":"2026-10-15T15:02:30.802852Z","end":"2026-10-15T15:02:30.933776Z","steps":["trace[1027949248] 'read index received' (duration: 112.116834ms)","trace[1027949248] 'applied index is now lower than readState.Index' (duration: 18.806337ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:02:30.934088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.256688ms","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-10-15T15:02:30.934111Z","caller":"traceutil/trace.go:171","msg":"trace[1886256485] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25070; }","duration":"131.32077ms","start":"2026-10-15T15:02:30.802786Z","end":"2026-10-15T15:02:30.934106Z","steps":["trace[1886256485] 'agreement among raft nodes before linearized reading' (duration: 131.252308ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:31.093307Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22146} {"level":"info","ts":"2026-10-15T15:03:31.203985Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22146,"took":"106.819887ms","hash":323640515,"current-db-size-bytes":56487936,"current-db-size":"56 MB","current-db-size-in-use-bytes":44732416,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-15T15:03:31.204118Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":323640515,"revision":22146,"compact-revision":19969} {"level":"info","ts":"2026-10-15T15:03:34.435957Z","caller":"traceutil/trace.go:171","msg":"trace[502123713] linearizableReadLoop","detail":"{readStateIndex:26480; appliedIndex:26479; }","duration":"113.219629ms","start":"2026-10-15T15:03:34.322703Z","end":"2026-10-15T15:03:34.435922Z","steps":["trace[502123713] 'read index received' (duration: 112.851291ms)","trace[502123713] 'applied index is now lower than readState.Index' (duration: 367.008µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:03:34.436072Z","caller":"traceutil/trace.go:171","msg":"trace[1862240947] transaction","detail":"{read_only:false; response_revision:25639; number_of_response:1; }","duration":"133.649681ms","start":"2026-10-15T15:03:34.30238Z","end":"2026-10-15T15:03:34.43603Z","steps":["trace[1862240947] 'process raft request' (duration: 133.293663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:34.436169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.443253ms","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":"info","ts":"2026-10-15T15:03:34.436231Z","caller":"traceutil/trace.go:171","msg":"trace[1182249239] 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:25639; }","duration":"113.564116ms","start":"2026-10-15T15:03:34.322646Z","end":"2026-10-15T15:03:34.43621Z","steps":["trace[1182249239] 'agreement among raft nodes before linearized reading' (duration: 113.449704ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:34.843858Z","caller":"traceutil/trace.go:171","msg":"trace[1243943286] linearizableReadLoop","detail":"{readStateIndex:26481; appliedIndex:26480; }","duration":"108.466823ms","start":"2026-10-15T15:03:34.735362Z","end":"2026-10-15T15:03:34.843829Z","steps":["trace[1243943286] 'read index received' (duration: 23.49999ms)","trace[1243943286] 'applied index is now lower than readState.Index' (duration: 84.965253ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:03:34.843999Z","caller":"traceutil/trace.go:171","msg":"trace[789879306] transaction","detail":"{read_only:false; response_revision:25640; number_of_response:1; }","duration":"289.083165ms","start":"2026-10-15T15:03:34.554879Z","end":"2026-10-15T15:03:34.843962Z","steps":["trace[789879306] 'process raft request' (duration: 204.034841ms)","trace[789879306] 'compare' (duration: 84.471011ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:03:34.844094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.517564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-15T15:03:34.84413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.749489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.10\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-15T15:03:34.844176Z","caller":"traceutil/trace.go:171","msg":"trace[1510081257] range","detail":"{range_begin:/registry/masterleases/162.253.55.10; range_end:; response_count:1; response_revision:25640; }","duration":"108.846551ms","start":"2026-10-15T15:03:34.73532Z","end":"2026-10-15T15:03:34.844166Z","steps":["trace[1510081257] 'agreement among raft nodes before linearized reading' (duration: 108.667177ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:34.844188Z","caller":"traceutil/trace.go:171","msg":"trace[688429278] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25640; }","duration":"108.659897ms","start":"2026-10-15T15:03:34.735504Z","end":"2026-10-15T15:03:34.844164Z","steps":["trace[688429278] 'agreement among raft nodes before linearized reading' (duration: 108.490364ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:35.026101Z","caller":"traceutil/trace.go:171","msg":"trace[1473352843] linearizableReadLoop","detail":"{readStateIndex:26482; appliedIndex:26481; }","duration":"144.456671ms","start":"2026-10-15T15:03:34.881625Z","end":"2026-10-15T15:03:35.026081Z","steps":["trace[1473352843] 'read index received' (duration: 144.358599ms)","trace[1473352843] 'applied index is now lower than readState.Index' (duration: 96.832µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:03:35.026227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.591684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:03:35.026267Z","caller":"traceutil/trace.go:171","msg":"trace[423577610] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25640; }","duration":"144.680556ms","start":"2026-10-15T15:03:34.881578Z","end":"2026-10-15T15:03:35.026258Z","steps":["trace[423577610] 'agreement among raft nodes before linearized reading' (duration: 144.601614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:35.280901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.875499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:03:35.28106Z","caller":"traceutil/trace.go:171","msg":"trace[2100591216] transaction","detail":"{read_only:false; response_revision:25641; number_of_response:1; }","duration":"254.008098ms","start":"2026-10-15T15:03:35.027021Z","end":"2026-10-15T15:03:35.281029Z","steps":["trace[2100591216] 'process raft request' (duration: 150.904314ms)","trace[2100591216] 'compare' (duration: 102.730936ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:03:35.281226Z","caller":"traceutil/trace.go:171","msg":"trace[1481089358] linearizableReadLoop","detail":"{readStateIndex:26483; appliedIndex:26482; }","duration":"153.235815ms","start":"2026-10-15T15:03:35.127961Z","end":"2026-10-15T15:03:35.281197Z","steps":["trace[1481089358] 'read index received' (duration: 49.848744ms)","trace[1481089358] 'applied index is now lower than readState.Index' (duration: 103.382811ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:03:35.28148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.488581ms","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-10-15T15:03:35.281583Z","caller":"traceutil/trace.go:171","msg":"trace[1596224276] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:25641; }","duration":"153.645615ms","start":"2026-10-15T15:03:35.127908Z","end":"2026-10-15T15:03:35.281554Z","steps":["trace[1596224276] 'agreement among raft nodes before linearized reading' (duration: 153.42096ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:35.4348Z","caller":"traceutil/trace.go:171","msg":"trace[2115032508] linearizableReadLoop","detail":"{readStateIndex:26486; appliedIndex:26485; }","duration":"153.43087ms","start":"2026-10-15T15:03:35.281309Z","end":"2026-10-15T15:03:35.43474Z","steps":["trace[2115032508] 'read index received' (duration: 152.392897ms)","trace[2115032508] 'applied index is now lower than readState.Index' (duration: 1.036373ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:03:35.434875Z","caller":"traceutil/trace.go:171","msg":"trace[666625553] transaction","detail":"{read_only:false; response_revision:25644; number_of_response:1; }","duration":"170.61445ms","start":"2026-10-15T15:03:35.264222Z","end":"2026-10-15T15:03:35.434836Z","steps":["trace[666625553] 'process raft request' (duration: 169.387213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:35.434932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.388383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T15:03:35.435068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.882013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-15T15:03:35.435103Z","caller":"traceutil/trace.go:171","msg":"trace[1129914568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25644; }","duration":"169.563547ms","start":"2026-10-15T15:03:35.265481Z","end":"2026-10-15T15:03:35.435045Z","steps":["trace[1129914568] 'agreement among raft nodes before linearized reading' (duration: 169.349392ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:35.435138Z","caller":"traceutil/trace.go:171","msg":"trace[1356426453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25644; }","duration":"202.000955ms","start":"2026-10-15T15:03:35.233121Z","end":"2026-10-15T15:03:35.435122Z","steps":["trace[1356426453] 'agreement among raft nodes before linearized reading' (duration: 201.803311ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:35.436554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.31511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-15T15:03:35.43662Z","caller":"traceutil/trace.go:171","msg":"trace[101732080] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25646; }","duration":"122.395262ms","start":"2026-10-15T15:03:35.314206Z","end":"2026-10-15T15:03:35.436601Z","steps":["trace[101732080] 'agreement among raft nodes before linearized reading' (duration: 122.253389ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:35.436891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.784559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-10-15T15:03:35.436926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.037452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T15:03:35.436951Z","caller":"traceutil/trace.go:171","msg":"trace[803385274] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25646; }","duration":"103.86128ms","start":"2026-10-15T15:03:35.333075Z","end":"2026-10-15T15:03:35.436936Z","steps":["trace[803385274] 'agreement among raft nodes before linearized reading' (duration: 103.713657ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:35.436964Z","caller":"traceutil/trace.go:171","msg":"trace[1795437977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25646; }","duration":"135.102523ms","start":"2026-10-15T15:03:35.301851Z","end":"2026-10-15T15:03:35.436953Z","steps":["trace[1795437977] 'agreement among raft nodes before linearized reading' (duration: 134.917559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:35.437253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.828556ms","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":"warn","ts":"2026-10-15T15:03:35.437305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.18525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-10-15T15:03:35.437354Z","caller":"traceutil/trace.go:171","msg":"trace[576977601] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25646; }","duration":"105.272042ms","start":"2026-10-15T15:03:35.332067Z","end":"2026-10-15T15:03:35.437339Z","steps":["trace[576977601] 'agreement among raft nodes before linearized reading' (duration: 105.089158ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:35.437758Z","caller":"traceutil/trace.go:171","msg":"trace[15681032] transaction","detail":"{read_only:false; response_revision:25645; number_of_response:1; }","duration":"152.233823ms","start":"2026-10-15T15:03:35.285496Z","end":"2026-10-15T15:03:35.43773Z","steps":["trace[15681032] 'process raft request' (duration: 150.641208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:35.438173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.57196ms","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-10-15T15:03:35.438196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.708435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-15T15:03:35.438224Z","caller":"traceutil/trace.go:171","msg":"trace[931880134] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25646; }","duration":"152.636892ms","start":"2026-10-15T15:03:35.285574Z","end":"2026-10-15T15:03:35.438211Z","steps":["trace[931880134] 'agreement among raft nodes before linearized reading' (duration: 152.447528ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:35.438254Z","caller":"traceutil/trace.go:171","msg":"trace[1080301237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25646; }","duration":"139.800467ms","start":"2026-10-15T15:03:35.298438Z","end":"2026-10-15T15:03:35.438238Z","steps":["trace[1080301237] 'agreement among raft nodes before linearized reading' (duration: 139.573032ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:35.43927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.602448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-10-15T15:03:35.43931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.904794ms","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-10-15T15:03:35.439379Z","caller":"traceutil/trace.go:171","msg":"trace[1085684246] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25646; }","duration":"129.162242ms","start":"2026-10-15T15:03:35.310202Z","end":"2026-10-15T15:03:35.439364Z","steps":["trace[1085684246] 'agreement among raft nodes before linearized reading' (duration: 127.833683ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:35.439377Z","caller":"traceutil/trace.go:171","msg":"trace[365391148] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25646; }","duration":"155.790772ms","start":"2026-10-15T15:03:35.283565Z","end":"2026-10-15T15:03:35.439356Z","steps":["trace[365391148] 'agreement among raft nodes before linearized reading' (duration: 152.775385ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:35.437319Z","caller":"traceutil/trace.go:171","msg":"trace[1988745971] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25646; }","duration":"124.913708ms","start":"2026-10-15T15:03:35.312394Z","end":"2026-10-15T15:03:35.437308Z","steps":["trace[1988745971] 'agreement among raft nodes before linearized reading' (duration: 124.788395ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:04:08.780638Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068a4.wal"} {"level":"info","ts":"2026-10-15T15:06:58.561833Z","caller":"traceutil/trace.go:171","msg":"trace[2031373506] transaction","detail":"{read_only:false; response_revision:27700; number_of_response:1; }","duration":"147.532361ms","start":"2026-10-15T15:06:58.414241Z","end":"2026-10-15T15:06:58.561774Z","steps":["trace[2031373506] 'process raft request' (duration: 121.293293ms)","trace[2031373506] 'compare' (duration: 26.036633ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:06:58.638575Z","caller":"traceutil/trace.go:171","msg":"trace[535606866] transaction","detail":"{read_only:false; response_revision:27701; number_of_response:1; }","duration":"123.197454ms","start":"2026-10-15T15:06:58.515348Z","end":"2026-10-15T15:06:58.638546Z","steps":["trace[535606866] 'process raft request' (duration: 123.03743ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:32.810145Z","caller":"traceutil/trace.go:171","msg":"trace[438779489] transaction","detail":"{read_only:false; response_revision:28052; number_of_response:1; }","duration":"513.308874ms","start":"2026-10-15T15:07:32.296818Z","end":"2026-10-15T15:07:32.810127Z","steps":["trace[438779489] 'process raft request' (duration: 513.169691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:32.81033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:07:32.296803Z","time spent":"513.418266ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:07:32.850903Z","caller":"traceutil/trace.go:171","msg":"trace[1064501267] transaction","detail":"{read_only:false; response_revision:28055; number_of_response:1; }","duration":"231.776689ms","start":"2026-10-15T15:07:32.619102Z","end":"2026-10-15T15:07:32.850879Z","steps":["trace[1064501267] 'process raft request' (duration: 231.704147ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:32.850921Z","caller":"traceutil/trace.go:171","msg":"trace[912958846] transaction","detail":"{read_only:false; response_revision:28053; number_of_response:1; }","duration":"524.371647ms","start":"2026-10-15T15:07:32.326508Z","end":"2026-10-15T15:07:32.850879Z","steps":["trace[912958846] 'process raft request' (duration: 523.891406ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:32.850941Z","caller":"traceutil/trace.go:171","msg":"trace[1397987304] transaction","detail":"{read_only:false; response_revision:28054; number_of_response:1; }","duration":"485.223244ms","start":"2026-10-15T15:07:32.365686Z","end":"2026-10-15T15:07:32.850909Z","steps":["trace[1397987304] 'process raft request' (duration: 485.074551ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:32.851096Z","caller":"traceutil/trace.go:171","msg":"trace[1605423224] transaction","detail":"{read_only:false; response_revision:28056; number_of_response:1; }","duration":"141.926038ms","start":"2026-10-15T15:07:32.70916Z","end":"2026-10-15T15:07:32.851086Z","steps":["trace[1605423224] 'process raft request' (duration: 141.684603ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:32.851114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:07:32.326487Z","time spent":"524.521921ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:07:32.851209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:07:32.365662Z","time spent":"485.380297ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:07:32.851711Z","caller":"traceutil/trace.go:171","msg":"trace[1709805466] transaction","detail":"{read_only:false; response_revision:28057; number_of_response:1; }","duration":"122.176093ms","start":"2026-10-15T15:07:32.729523Z","end":"2026-10-15T15:07:32.851699Z","steps":["trace[1709805466] 'process raft request' (duration: 121.502658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:33.222831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.98256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:07:33.222956Z","caller":"traceutil/trace.go:171","msg":"trace[1095707766] transaction","detail":"{read_only:false; response_revision:28064; number_of_response:1; }","duration":"198.887663ms","start":"2026-10-15T15:07:33.024049Z","end":"2026-10-15T15:07:33.222937Z","steps":["trace[1095707766] 'process raft request' (duration: 80.721048ms)","trace[1095707766] 'compare' (duration: 117.833288ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:07:33.575119Z","caller":"traceutil/trace.go:171","msg":"trace[697555402] transaction","detail":"{read_only:false; response_revision:28065; number_of_response:1; }","duration":"180.380466ms","start":"2026-10-15T15:07:33.3947Z","end":"2026-10-15T15:07:33.575081Z","steps":["trace[697555402] 'process raft request' (duration: 116.59743ms)","trace[697555402] 'compare' (duration: 63.645323ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:08:01.237912Z","caller":"traceutil/trace.go:171","msg":"trace[688193092] transaction","detail":"{read_only:false; response_revision:28278; number_of_response:1; }","duration":"124.3694ms","start":"2026-10-15T15:08:01.113512Z","end":"2026-10-15T15:08:01.237882Z","steps":["trace[688193092] 'process raft request' (duration: 118.933741ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:01.238032Z","caller":"traceutil/trace.go:171","msg":"trace[760172539] transaction","detail":"{read_only:false; response_revision:28280; number_of_response:1; }","duration":"121.693581ms","start":"2026-10-15T15:08:01.116321Z","end":"2026-10-15T15:08:01.238015Z","steps":["trace[760172539] 'process raft request' (duration: 121.542748ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:01.237912Z","caller":"traceutil/trace.go:171","msg":"trace[731184440] transaction","detail":"{read_only:false; response_revision:28279; number_of_response:1; }","duration":"122.570532ms","start":"2026-10-15T15:08:01.11532Z","end":"2026-10-15T15:08:01.23789Z","steps":["trace[731184440] 'process raft request' (duration: 122.458359ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:01.238183Z","caller":"traceutil/trace.go:171","msg":"trace[687849306] transaction","detail":"{read_only:false; response_revision:28281; number_of_response:1; }","duration":"114.386911ms","start":"2026-10-15T15:08:01.123788Z","end":"2026-10-15T15:08:01.238175Z","steps":["trace[687849306] 'process raft request' (duration: 114.149276ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:31.101001Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25612} {"level":"info","ts":"2026-10-15T15:08:31.277088Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25612,"took":"171.813927ms","hash":554092941,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":35672064,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-15T15:08:31.277152Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":554092941,"revision":25612,"compact-revision":22146} {"level":"info","ts":"2026-10-15T15:09:49.561544Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"373d5d49ce4adaff","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T15:09:49.571365Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T15:09:49.571487Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"warn","ts":"2026-10-15T15:11:02.564623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.830547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:11:02.565297Z","caller":"traceutil/trace.go:171","msg":"trace[497316180] transaction","detail":"{read_only:false; response_revision:29647; number_of_response:1; }","duration":"188.912803ms","start":"2026-10-15T15:11:02.376333Z","end":"2026-10-15T15:11:02.565245Z","steps":["trace[497316180] 'process raft request' (duration: 60.37229ms)","trace[497316180] 'compare' (duration: 127.703855ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:11:02.565334Z","caller":"traceutil/trace.go:171","msg":"trace[2075936840] transaction","detail":"{read_only:false; response_revision:29648; number_of_response:1; }","duration":"188.968565ms","start":"2026-10-15T15:11:02.376332Z","end":"2026-10-15T15:11:02.5653Z","steps":["trace[2075936840] 'process raft request' (duration: 188.401002ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:11:02.565684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.638556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-15T15:11:02.56573Z","caller":"traceutil/trace.go:171","msg":"trace[981120884] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:29648; }","duration":"143.721228ms","start":"2026-10-15T15:11:02.421995Z","end":"2026-10-15T15:11:02.565717Z","steps":["trace[981120884] 'agreement among raft nodes before linearized reading' (duration: 143.566764ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:02.565516Z","caller":"traceutil/trace.go:171","msg":"trace[2084211106] linearizableReadLoop","detail":"{readStateIndex:30595; appliedIndex:30593; }","duration":"143.160485ms","start":"2026-10-15T15:11:02.42204Z","end":"2026-10-15T15:11:02.5652Z","steps":["trace[2084211106] 'read index received' (duration: 14.682683ms)","trace[2084211106] 'applied index is now lower than readState.Index' (duration: 128.476232ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:13:31.148287Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28500} {"level":"info","ts":"2026-10-15T15:13:31.523183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28500,"took":"372.190773ms","hash":3113244560,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":31461376,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:13:31.523272Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3113244560,"revision":28500,"compact-revision":25612} {"level":"info","ts":"2026-10-15T15:17:37.759912Z","caller":"traceutil/trace.go:171","msg":"trace[653471059] transaction","detail":"{read_only:false; response_revision:32597; number_of_response:1; }","duration":"111.057233ms","start":"2026-10-15T15:17:37.64883Z","end":"2026-10-15T15:17:37.759887Z","steps":["trace[653471059] 'process raft request' (duration: 110.519581ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:18:31.155763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30727} {"level":"info","ts":"2026-10-15T15:18:31.282286Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30727,"took":"122.766018ms","hash":3898798672,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":32268288,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:18:31.282371Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3898798672,"revision":30727,"compact-revision":28500} {"level":"info","ts":"2026-10-15T15:19:51.927163Z","caller":"traceutil/trace.go:171","msg":"trace[1858169713] linearizableReadLoop","detail":"{readStateIndex:34641; appliedIndex:34640; }","duration":"113.612559ms","start":"2026-10-15T15:19:51.813517Z","end":"2026-10-15T15:19:51.92713Z","steps":["trace[1858169713] 'read index received' (duration: 101.336562ms)","trace[1858169713] 'applied index is now lower than readState.Index' (duration: 12.274357ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:19:51.927308Z","caller":"traceutil/trace.go:171","msg":"trace[1333425699] transaction","detail":"{read_only:false; response_revision:33578; number_of_response:1; }","duration":"114.761863ms","start":"2026-10-15T15:19:51.812471Z","end":"2026-10-15T15:19:51.927233Z","steps":["trace[1333425699] 'process raft request' (duration: 102.295972ms)","trace[1333425699] 'compare' (duration: 12.080853ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:19:51.927518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.980377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-10-15T15:19:51.927577Z","caller":"traceutil/trace.go:171","msg":"trace[1958715790] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:33578; }","duration":"114.087449ms","start":"2026-10-15T15:19:51.813469Z","end":"2026-10-15T15:19:51.927557Z","steps":["trace[1958715790] 'agreement among raft nodes before linearized reading' (duration: 113.863404ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:23:31.165093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32982} {"level":"info","ts":"2026-10-15T15:23:31.344484Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32982,"took":"173.740114ms","hash":4084127833,"current-db-size-bytes":59224064,"current-db-size":"59 MB","current-db-size-in-use-bytes":31703040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:23:31.344553Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4084127833,"revision":32982,"compact-revision":30727} {"level":"info","ts":"2026-10-15T15:23:41.572469Z","caller":"traceutil/trace.go:171","msg":"trace[1445554933] transaction","detail":"{read_only:false; response_revision:35359; number_of_response:1; }","duration":"128.242594ms","start":"2026-10-15T15:23:41.444204Z","end":"2026-10-15T15:23:41.572446Z","steps":["trace[1445554933] 'process raft request' (duration: 126.662949ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:23:41.573561Z","caller":"traceutil/trace.go:171","msg":"trace[223042733] linearizableReadLoop","detail":"{readStateIndex:36474; appliedIndex:36471; }","duration":"100.233923ms","start":"2026-10-15T15:23:41.473312Z","end":"2026-10-15T15:23:41.573546Z","steps":["trace[223042733] 'read index received' (duration: 97.660126ms)","trace[223042733] 'applied index is now lower than readState.Index' (duration: 2.573007ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:23:41.573635Z","caller":"traceutil/trace.go:171","msg":"trace[382713348] transaction","detail":"{read_only:false; response_revision:35360; number_of_response:1; }","duration":"124.981001ms","start":"2026-10-15T15:23:41.448618Z","end":"2026-10-15T15:23:41.573599Z","steps":["trace[382713348] 'process raft request' (duration: 124.807457ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:23:41.573875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.392377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:23:41.573904Z","caller":"traceutil/trace.go:171","msg":"trace[698819947] transaction","detail":"{read_only:false; response_revision:35361; number_of_response:1; }","duration":"112.782501ms","start":"2026-10-15T15:23:41.461075Z","end":"2026-10-15T15:23:41.573857Z","steps":["trace[698819947] 'process raft request' (duration: 112.417073ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:23:41.573915Z","caller":"traceutil/trace.go:171","msg":"trace[485063640] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:35362; }","duration":"100.648692ms","start":"2026-10-15T15:23:41.473256Z","end":"2026-10-15T15:23:41.573904Z","steps":["trace[485063640] 'agreement among raft nodes before linearized reading' (duration: 100.337765ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:23:55.806841Z","caller":"traceutil/trace.go:171","msg":"trace[1570164473] linearizableReadLoop","detail":"{readStateIndex:36582; appliedIndex:36581; }","duration":"146.076159ms","start":"2026-10-15T15:23:55.660745Z","end":"2026-10-15T15:23:55.806821Z","steps":["trace[1570164473] 'read index received' (duration: 10.118184ms)","trace[1570164473] 'applied index is now lower than readState.Index' (duration: 135.955585ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:23:55.806999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.233743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T15:23:55.80705Z","caller":"traceutil/trace.go:171","msg":"trace[164057751] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:35466; }","duration":"146.328815ms","start":"2026-10-15T15:23:55.660709Z","end":"2026-10-15T15:23:55.807038Z","steps":["trace[164057751] 'agreement among raft nodes before linearized reading' (duration: 146.231963ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:23:55.806986Z","caller":"traceutil/trace.go:171","msg":"trace[1252472442] transaction","detail":"{read_only:false; response_revision:35466; number_of_response:1; }","duration":"160.162182ms","start":"2026-10-15T15:23:55.64676Z","end":"2026-10-15T15:23:55.806922Z","steps":["trace[1252472442] 'process raft request' (duration: 117.759791ms)","trace[1252472442] 'compare' (duration: 42.201316ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:28:31.173555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35281} {"level":"info","ts":"2026-10-15T15:28:31.310565Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35281,"took":"129.603503ms","hash":2985443184,"current-db-size-bytes":59224064,"current-db-size":"59 MB","current-db-size-in-use-bytes":32157696,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:28:31.31066Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2985443184,"revision":35281,"compact-revision":32982} {"level":"info","ts":"2026-10-15T15:29:38.615415Z","caller":"traceutil/trace.go:171","msg":"trace[213106314] transaction","detail":"{read_only:false; response_revision:38043; number_of_response:1; }","duration":"108.926114ms","start":"2026-10-15T15:29:38.506458Z","end":"2026-10-15T15:29:38.615384Z","steps":["trace[213106314] 'process raft request' (duration: 108.599877ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:29:38.627768Z","caller":"traceutil/trace.go:171","msg":"trace[270743247] transaction","detail":"{read_only:false; response_revision:38044; number_of_response:1; }","duration":"114.845486ms","start":"2026-10-15T15:29:38.512898Z","end":"2026-10-15T15:29:38.627744Z","steps":["trace[270743247] 'process raft request' (duration: 114.638262ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:31:15.523067Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"373d5d49ce4adaff","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T15:31:15.533396Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T15:31:15.533757Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T15:32:59.620736Z","caller":"traceutil/trace.go:171","msg":"trace[1589551944] linearizableReadLoop","detail":"{readStateIndex:40790; appliedIndex:40788; }","duration":"144.37957ms","start":"2026-10-15T15:32:59.47632Z","end":"2026-10-15T15:32:59.620699Z","steps":["trace[1589551944] 'read index received' (duration: 46.131622ms)","trace[1589551944] 'applied index is now lower than readState.Index' (duration: 98.246048ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:32:59.620763Z","caller":"traceutil/trace.go:171","msg":"trace[782783377] transaction","detail":"{read_only:false; response_revision:39557; number_of_response:1; }","duration":"148.951162ms","start":"2026-10-15T15:32:59.471769Z","end":"2026-10-15T15:32:59.62072Z","steps":["trace[782783377] 'process raft request' (duration: 63.264202ms)","trace[782783377] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/capo-system/controller-leader-election-capo; req_size:520; } (duration: 85.343542ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:32:59.620939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.604055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/\" range_end:\"/registry/rolebindings/envoy-gateway-system0\" limit:500 ","response":"range_response_count:3 size:3303"} {"level":"info","ts":"2026-10-15T15:32:59.62099Z","caller":"traceutil/trace.go:171","msg":"trace[725236843] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/; range_end:/registry/rolebindings/envoy-gateway-system0; response_count:3; response_revision:39557; }","duration":"144.700788ms","start":"2026-10-15T15:32:59.476275Z","end":"2026-10-15T15:32:59.620976Z","steps":["trace[725236843] 'agreement among raft nodes before linearized reading' (duration: 144.517963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:32:59.621028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.059063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/cert-manager/cert-manager\" ","response":"range_response_count:1 size:1096"} {"level":"info","ts":"2026-10-15T15:32:59.621082Z","caller":"traceutil/trace.go:171","msg":"trace[343076208] range","detail":"{range_begin:/registry/services/endpoints/cert-manager/cert-manager; range_end:; response_count:1; response_revision:39557; }","duration":"135.183077ms","start":"2026-10-15T15:32:59.485884Z","end":"2026-10-15T15:32:59.621067Z","steps":["trace[343076208] 'agreement among raft nodes before linearized reading' (duration: 135.031093ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:32:59.621096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.486981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:1085"} {"level":"info","ts":"2026-10-15T15:32:59.621165Z","caller":"traceutil/trace.go:171","msg":"trace[2013147736] range","detail":"{range_begin:/registry/services/endpoints/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:39557; }","duration":"129.595212ms","start":"2026-10-15T15:32:59.491552Z","end":"2026-10-15T15:32:59.621148Z","steps":["trace[2013147736] 'agreement among raft nodes before linearized reading' (duration: 129.383728ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:33:24.26421Z","caller":"traceutil/trace.go:171","msg":"trace[1250343996] linearizableReadLoop","detail":"{readStateIndex:40978; appliedIndex:40976; }","duration":"127.943076ms","start":"2026-10-15T15:33:24.136244Z","end":"2026-10-15T15:33:24.264187Z","steps":["trace[1250343996] 'read index received' (duration: 74.955421ms)","trace[1250343996] 'applied index is now lower than readState.Index' (duration: 52.986625ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:33:24.264219Z","caller":"traceutil/trace.go:171","msg":"trace[1336152007] transaction","detail":"{read_only:false; response_revision:39739; number_of_response:1; }","duration":"143.143232ms","start":"2026-10-15T15:33:24.12104Z","end":"2026-10-15T15:33:24.264184Z","steps":["trace[1336152007] 'process raft request' (duration: 143.03852ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:33:24.264204Z","caller":"traceutil/trace.go:171","msg":"trace[835859110] transaction","detail":"{read_only:false; response_revision:39738; number_of_response:1; }","duration":"158.396751ms","start":"2026-10-15T15:33:24.105769Z","end":"2026-10-15T15:33:24.264166Z","steps":["trace[835859110] 'process raft request' (duration: 105.416086ms)","trace[835859110] 'compare' (duration: 52.631797ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:33:24.264623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.310954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/node-feature-discovery-gc\" ","response":"range_response_count:1 size:3559"} {"level":"info","ts":"2026-10-15T15:33:24.264751Z","caller":"traceutil/trace.go:171","msg":"trace[108878998] range","detail":"{range_begin:/registry/deployments/monitoring/node-feature-discovery-gc; range_end:; response_count:1; response_revision:39739; }","duration":"128.534959ms","start":"2026-10-15T15:33:24.13619Z","end":"2026-10-15T15:33:24.264725Z","steps":["trace[108878998] 'agreement among raft nodes before linearized reading' (duration: 128.11582ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:33:31.181491Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37511} {"level":"info","ts":"2026-10-15T15:33:31.32404Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37511,"took":"137.236941ms","hash":374730697,"current-db-size-bytes":59658240,"current-db-size":"60 MB","current-db-size-in-use-bytes":31506432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:33:31.324121Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":374730697,"revision":37511,"compact-revision":35281} {"level":"info","ts":"2026-10-15T15:33:40.450206Z","caller":"traceutil/trace.go:171","msg":"trace[937246776] transaction","detail":"{read_only:false; response_revision:39855; number_of_response:1; }","duration":"128.705783ms","start":"2026-10-15T15:33:40.321476Z","end":"2026-10-15T15:33:40.450182Z","steps":["trace[937246776] 'process raft request' (duration: 128.54875ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:33:40.459831Z","caller":"traceutil/trace.go:171","msg":"trace[1762965902] linearizableReadLoop","detail":"{readStateIndex:41100; appliedIndex:41098; }","duration":"119.97382ms","start":"2026-10-15T15:33:40.339836Z","end":"2026-10-15T15:33:40.45981Z","steps":["trace[1762965902] 'read index received' (duration: 110.219354ms)","trace[1762965902] 'applied index is now lower than readState.Index' (duration: 9.753616ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:33:40.459917Z","caller":"traceutil/trace.go:171","msg":"trace[1798519727] transaction","detail":"{read_only:false; response_revision:39856; number_of_response:1; }","duration":"136.029426ms","start":"2026-10-15T15:33:40.323854Z","end":"2026-10-15T15:33:40.459883Z","steps":["trace[1798519727] 'process raft request' (duration: 135.822381ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:33:40.460136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.284016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/cinder-volume\" ","response":"range_response_count:1 size:14909"} {"level":"info","ts":"2026-10-15T15:33:40.460206Z","caller":"traceutil/trace.go:171","msg":"trace[439941677] range","detail":"{range_begin:/registry/deployments/openstack/cinder-volume; range_end:; response_count:1; response_revision:39856; }","duration":"120.409969ms","start":"2026-10-15T15:33:40.33978Z","end":"2026-10-15T15:33:40.46019Z","steps":["trace[439941677] 'agreement among raft nodes before linearized reading' (duration: 120.114733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:33:40.486839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.948398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-15T15:33:40.486974Z","caller":"traceutil/trace.go:171","msg":"trace[2071857069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:39857; }","duration":"110.124412ms","start":"2026-10-15T15:33:40.37682Z","end":"2026-10-15T15:33:40.486944Z","steps":["trace[2071857069] 'agreement among raft nodes before linearized reading' (duration: 109.58396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:33:40.487419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.856978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-15T15:33:40.487534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.454944ms","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-10-15T15:33:40.487571Z","caller":"traceutil/trace.go:171","msg":"trace[224470160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:39857; }","duration":"130.093484ms","start":"2026-10-15T15:33:40.357447Z","end":"2026-10-15T15:33:40.487541Z","steps":["trace[224470160] 'agreement among raft nodes before linearized reading' (duration: 129.712075ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:33:40.487804Z","caller":"traceutil/trace.go:171","msg":"trace[809493618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:39857; }","duration":"145.551597ms","start":"2026-10-15T15:33:40.342026Z","end":"2026-10-15T15:33:40.487578Z","steps":["trace[809493618] 'agreement among raft nodes before linearized reading' (duration: 145.224409ms)"],"step_count":1}