{"level":"warn","ts":"2026-09-10T17:44:24.826168Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T17:44:24.82636Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.203: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.203:2380","--initial-cluster=instance=https://162.253.55.203:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.203:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.203:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-10T17:44:24.826615Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T17:44:24.826656Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.203:2380"]} {"level":"info","ts":"2026-09-10T17:44:24.826683Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-10T17:44:24.827936Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.203:2379"]} {"level":"info","ts":"2026-09-10T17:44:24.82811Z","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.203:2380"],"listen-peer-urls":["https://162.253.55.203:2380"],"advertise-client-urls":["https://162.253.55.203:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.203:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.203:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-10T17:44:24.832488Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.025505ms"} {"level":"info","ts":"2026-09-10T17:44:24.838819Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a656331365382b34","cluster-id":"84e77d9b2eaf556a"} {"level":"info","ts":"2026-09-10T17:44:24.838971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 switched to configuration voters=()"} {"level":"info","ts":"2026-09-10T17:44:24.838992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 became follower at term 0"} {"level":"info","ts":"2026-09-10T17:44:24.839006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a656331365382b34 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-10T17:44:24.839039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 became follower at term 1"} {"level":"info","ts":"2026-09-10T17:44:24.839083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 switched to configuration voters=(11985823616688728884)"} {"level":"warn","ts":"2026-09-10T17:44:24.847649Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-10T17:44:24.849919Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-10T17:44:24.851427Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-10T17:44:24.854557Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a656331365382b34","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-10T17:44:24.855288Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a656331365382b34","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-10T17:44:24.855603Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-10T17:44:24.855756Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-10T17:44:24.855817Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-10T17:44:24.860241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 switched to configuration voters=(11985823616688728884)"} {"level":"info","ts":"2026-09-10T17:44:24.860629Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"84e77d9b2eaf556a","local-member-id":"a656331365382b34","added-peer-id":"a656331365382b34","added-peer-peer-urls":["https://162.253.55.203:2380"]} {"level":"info","ts":"2026-09-10T17:44:24.86777Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-10T17:44:24.86806Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a656331365382b34","initial-advertise-peer-urls":["https://162.253.55.203:2380"],"listen-peer-urls":["https://162.253.55.203:2380"],"advertise-client-urls":["https://162.253.55.203:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.203:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-10T17:44:24.868106Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-10T17:44:24.868699Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.203:2380"} {"level":"info","ts":"2026-09-10T17:44:24.868781Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.203:2380"} {"level":"info","ts":"2026-09-10T17:44:25.439791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 is starting a new election at term 1"} {"level":"info","ts":"2026-09-10T17:44:25.439916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-10T17:44:25.439963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 received MsgPreVoteResp from a656331365382b34 at term 1"} {"level":"info","ts":"2026-09-10T17:44:25.440014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 became candidate at term 2"} {"level":"info","ts":"2026-09-10T17:44:25.440068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 received MsgVoteResp from a656331365382b34 at term 2"} {"level":"info","ts":"2026-09-10T17:44:25.440108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 became leader at term 2"} {"level":"info","ts":"2026-09-10T17:44:25.440146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a656331365382b34 elected leader a656331365382b34 at term 2"} {"level":"info","ts":"2026-09-10T17:44:25.441347Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T17:44:25.442371Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a656331365382b34","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.203:2379]}","request-path":"/0/members/a656331365382b34/attributes","cluster-id":"84e77d9b2eaf556a","publish-timeout":"7s"} {"level":"info","ts":"2026-09-10T17:44:25.442378Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T17:44:25.442432Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T17:44:25.442766Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-10T17:44:25.442845Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-10T17:44:25.443191Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"84e77d9b2eaf556a","local-member-id":"a656331365382b34","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T17:44:25.443337Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T17:44:25.443405Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T17:44:25.446021Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.203:2379"} {"level":"info","ts":"2026-09-10T17:44:25.446311Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-10T17:44:28.202394Z","caller":"traceutil/trace.go:171","msg":"trace[563377793] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"112.664587ms","start":"2026-09-10T17:44:28.089696Z","end":"2026-09-10T17:44:28.202361Z","steps":["trace[563377793] 'process raft request' (duration: 110.878291ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:44:43.373932Z","caller":"traceutil/trace.go:171","msg":"trace[1331809335] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"120.021468ms","start":"2026-09-10T17:44:43.253882Z","end":"2026-09-10T17:44:43.373903Z","steps":["trace[1331809335] 'process raft request' (duration: 119.821602ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:44:43.741296Z","caller":"traceutil/trace.go:171","msg":"trace[504467045] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"363.045168ms","start":"2026-09-10T17:44:43.378213Z","end":"2026-09-10T17:44:43.741258Z","steps":["trace[504467045] 'process raft request' (duration: 362.749769ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:44:43.742234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T17:44:43.378193Z","time spent":"363.221512ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T17:44:43.743687Z","caller":"traceutil/trace.go:171","msg":"trace[1905483458] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:385; }","duration":"357.938748ms","start":"2026-09-10T17:44:43.385717Z","end":"2026-09-10T17:44:43.743656Z","steps":["trace[1905483458] 'read index received' (duration: 355.201682ms)","trace[1905483458] 'applied index is now lower than readState.Index' (duration: 2.736156ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T17:44:43.744002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.219016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-10T17:44:43.744015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.302015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T17:44:43.744083Z","caller":"traceutil/trace.go:171","msg":"trace[2096472966] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"365.471134ms","start":"2026-09-10T17:44:43.378556Z","end":"2026-09-10T17:44:43.744027Z","steps":["trace[2096472966] 'process raft request' (duration: 364.967948ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:44:43.744139Z","caller":"traceutil/trace.go:171","msg":"trace[909265478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:376; }","duration":"358.478364ms","start":"2026-09-10T17:44:43.385643Z","end":"2026-09-10T17:44:43.744121Z","steps":["trace[909265478] 'agreement among raft nodes before linearized reading' (duration: 358.170985ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:44:43.744163Z","caller":"traceutil/trace.go:171","msg":"trace[476690311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:376; }","duration":"119.585424ms","start":"2026-09-10T17:44:43.624549Z","end":"2026-09-10T17:44:43.744134Z","steps":["trace[476690311] 'agreement among raft nodes before linearized reading' (duration: 119.314066ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:44:43.744177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T17:44:43.3856Z","time spent":"358.567647ms","remote":"127.0.0.1:53086","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":"warn","ts":"2026-09-10T17:44:43.7444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T17:44:43.378546Z","time spent":"365.609158ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T17:45:14.854622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.229259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T17:45:14.854763Z","caller":"traceutil/trace.go:171","msg":"trace[1508033270] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:602; }","duration":"163.428364ms","start":"2026-09-10T17:45:14.691299Z","end":"2026-09-10T17:45:14.854728Z","steps":["trace[1508033270] 'range keys from in-memory index tree' (duration: 163.121546ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:45:15.428317Z","caller":"traceutil/trace.go:171","msg":"trace[1127574102] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"209.475421ms","start":"2026-09-10T17:45:15.218787Z","end":"2026-09-10T17:45:15.428262Z","steps":["trace[1127574102] 'process raft request' (duration: 206.019465ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:45:16.035369Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3113289767382944787,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-10T17:45:16.101773Z","caller":"traceutil/trace.go:171","msg":"trace[1090492663] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"567.347584ms","start":"2026-09-10T17:45:15.534396Z","end":"2026-09-10T17:45:16.101744Z","steps":["trace[1090492663] 'read index received' (duration: 566.251751ms)","trace[1090492663] 'applied index is now lower than readState.Index' (duration: 1.094853ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T17:45:16.101819Z","caller":"traceutil/trace.go:171","msg":"trace[1981827511] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"601.04605ms","start":"2026-09-10T17:45:15.500737Z","end":"2026-09-10T17:45:16.101783Z","steps":["trace[1981827511] 'process raft request' (duration: 599.962998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:45:16.101964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T17:45:15.500711Z","time spent":"601.172995ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T17:45:16.102004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.550537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-10T17:45:16.102034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.620154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-10T17:45:16.102063Z","caller":"traceutil/trace.go:171","msg":"trace[1371298746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"201.65822ms","start":"2026-09-10T17:45:15.900385Z","end":"2026-09-10T17:45:16.102044Z","steps":["trace[1371298746] 'agreement among raft nodes before linearized reading' (duration: 201.535806ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:45:16.102074Z","caller":"traceutil/trace.go:171","msg":"trace[278407409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"567.681746ms","start":"2026-09-10T17:45:15.534381Z","end":"2026-09-10T17:45:16.102063Z","steps":["trace[278407409] 'agreement among raft nodes before linearized reading' (duration: 567.50401ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:45:16.10211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T17:45:15.534327Z","time spent":"567.775708ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-10T17:45:16.102092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.615789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-10T17:45:16.102227Z","caller":"traceutil/trace.go:171","msg":"trace[404435366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"201.782934ms","start":"2026-09-10T17:45:15.900419Z","end":"2026-09-10T17:45:16.102202Z","steps":["trace[404435366] 'agreement among raft nodes before linearized reading' (duration: 201.560097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:45:16.10214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.171907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-10T17:45:16.102416Z","caller":"traceutil/trace.go:171","msg":"trace[643702326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"189.459435ms","start":"2026-09-10T17:45:15.912926Z","end":"2026-09-10T17:45:16.102385Z","steps":["trace[643702326] 'agreement among raft nodes before linearized reading' (duration: 189.031112ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:45:33.274527Z","caller":"traceutil/trace.go:171","msg":"trace[869390816] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"261.49088ms","start":"2026-09-10T17:45:33.013011Z","end":"2026-09-10T17:45:33.274502Z","steps":["trace[869390816] 'process raft request' (duration: 261.333065ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:46:02.232049Z","caller":"traceutil/trace.go:171","msg":"trace[792715674] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"253.758172ms","start":"2026-09-10T17:46:01.978264Z","end":"2026-09-10T17:46:02.232022Z","steps":["trace[792715674] 'process raft request' (duration: 253.588107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:46:50.15569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.781835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T17:46:50.155821Z","caller":"traceutil/trace.go:171","msg":"trace[703380209] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1920; }","duration":"305.087111ms","start":"2026-09-10T17:46:49.850713Z","end":"2026-09-10T17:46:50.1558Z","steps":["trace[703380209] 'read index received' (duration: 47.925784ms)","trace[703380209] 'applied index is now lower than readState.Index' (duration: 257.159917ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T17:46:50.155923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.211044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T17:46:50.155969Z","caller":"traceutil/trace.go:171","msg":"trace[2061958676] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1850; }","duration":"305.304087ms","start":"2026-09-10T17:46:49.850656Z","end":"2026-09-10T17:46:50.15596Z","steps":["trace[2061958676] 'agreement among raft nodes before linearized reading' (duration: 305.218104ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:46:50.156012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T17:46:49.850629Z","time spent":"305.376139ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-09-10T17:46:50.155938Z","caller":"traceutil/trace.go:171","msg":"trace[767878816] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"332.505714ms","start":"2026-09-10T17:46:49.823389Z","end":"2026-09-10T17:46:50.155895Z","steps":["trace[767878816] 'process raft request' (duration: 75.248924ms)","trace[767878816] 'compare' (duration: 256.681771ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T17:46:50.156362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T17:46:49.823361Z","time spent":"332.744181ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T17:46:50.162417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.637665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-10T17:46:50.162526Z","caller":"traceutil/trace.go:171","msg":"trace[1006245334] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1851; }","duration":"303.804169ms","start":"2026-09-10T17:46:49.858707Z","end":"2026-09-10T17:46:50.162512Z","steps":["trace[1006245334] 'agreement among raft nodes before linearized reading' (duration: 303.579343ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:46:50.16253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.668912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1f07593f-280c-4485-9273-f2e407e15b57\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-09-10T17:46:50.162596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T17:46:49.858681Z","time spent":"303.880864ms","remote":"127.0.0.1:53086","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":"info","ts":"2026-09-10T17:46:50.162614Z","caller":"traceutil/trace.go:171","msg":"trace[349057651] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1f07593f-280c-4485-9273-f2e407e15b57; range_end:; response_count:1; response_revision:1851; }","duration":"216.778445ms","start":"2026-09-10T17:46:49.945794Z","end":"2026-09-10T17:46:50.162572Z","steps":["trace[349057651] 'agreement among raft nodes before linearized reading' (duration: 216.543807ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:46:50.67813Z","caller":"traceutil/trace.go:171","msg":"trace[1024773533] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1923; }","duration":"266.745102ms","start":"2026-09-10T17:46:50.411356Z","end":"2026-09-10T17:46:50.678101Z","steps":["trace[1024773533] 'read index received' (duration: 266.428942ms)","trace[1024773533] 'applied index is now lower than readState.Index' (duration: 315.13µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T17:46:50.678185Z","caller":"traceutil/trace.go:171","msg":"trace[1284714502] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"316.206962ms","start":"2026-09-10T17:46:50.361944Z","end":"2026-09-10T17:46:50.678151Z","steps":["trace[1284714502] 'process raft request' (duration: 315.853842ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:46:50.678492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T17:46:50.361928Z","time spent":"316.401249ms","remote":"127.0.0.1:53004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T17:46:50.67856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.173275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-10T17:46:50.678619Z","caller":"traceutil/trace.go:171","msg":"trace[857906055] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1853; }","duration":"267.270998ms","start":"2026-09-10T17:46:50.411331Z","end":"2026-09-10T17:46:50.678602Z","steps":["trace[857906055] 'agreement among raft nodes before linearized reading' (duration: 266.976829ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:46:50.679719Z","caller":"traceutil/trace.go:171","msg":"trace[1163739087] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"154.071977ms","start":"2026-09-10T17:46:50.525599Z","end":"2026-09-10T17:46:50.679671Z","steps":["trace[1163739087] 'process raft request' (duration: 153.860771ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:46:50.679777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.094914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d406c7f86a9579\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-09-10T17:46:50.679855Z","caller":"traceutil/trace.go:171","msg":"trace[2147220066] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d406c7f86a9579; range_end:; response_count:1; response_revision:1854; }","duration":"256.235408ms","start":"2026-09-10T17:46:50.4236Z","end":"2026-09-10T17:46:50.679836Z","steps":["trace[2147220066] 'agreement among raft nodes before linearized reading' (duration: 255.96021ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:47:06.905653Z","caller":"traceutil/trace.go:171","msg":"trace[689633430] linearizableReadLoop","detail":"{readStateIndex:2063; appliedIndex:2062; }","duration":"111.993963ms","start":"2026-09-10T17:47:06.793637Z","end":"2026-09-10T17:47:06.905631Z","steps":["trace[689633430] 'read index received' (duration: 111.837397ms)","trace[689633430] 'applied index is now lower than readState.Index' (duration: 155.906µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T17:47:06.905698Z","caller":"traceutil/trace.go:171","msg":"trace[1291693910] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"128.304115ms","start":"2026-09-10T17:47:06.777374Z","end":"2026-09-10T17:47:06.905678Z","steps":["trace[1291693910] 'process raft request' (duration: 128.116719ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:47:06.905864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.742265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-10T17:47:06.90589Z","caller":"traceutil/trace.go:171","msg":"trace[185487836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1989; }","duration":"109.827047ms","start":"2026-09-10T17:47:06.796055Z","end":"2026-09-10T17:47:06.905882Z","steps":["trace[185487836] 'agreement among raft nodes before linearized reading' (duration: 109.698894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:47:06.905897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.98638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-10T17:47:06.905929Z","caller":"traceutil/trace.go:171","msg":"trace[1953017693] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1989; }","duration":"107.039592ms","start":"2026-09-10T17:47:06.798881Z","end":"2026-09-10T17:47:06.90592Z","steps":["trace[1953017693] 'agreement among raft nodes before linearized reading' (duration: 106.941959ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:47:06.905925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.267622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-10T17:47:06.906547Z","caller":"traceutil/trace.go:171","msg":"trace[659255001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1989; }","duration":"112.897101ms","start":"2026-09-10T17:47:06.793622Z","end":"2026-09-10T17:47:06.906519Z","steps":["trace[659255001] 'agreement among raft nodes before linearized reading' (duration: 112.113427ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:47:06.90599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.137246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-10T17:47:06.906634Z","caller":"traceutil/trace.go:171","msg":"trace[35101810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1989; }","duration":"109.779865ms","start":"2026-09-10T17:47:06.796831Z","end":"2026-09-10T17:47:06.906611Z","steps":["trace[35101810] 'agreement among raft nodes before linearized reading' (duration: 109.112665ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:47:39.704487Z","caller":"traceutil/trace.go:171","msg":"trace[1215698508] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"103.808152ms","start":"2026-09-10T17:47:39.600627Z","end":"2026-09-10T17:47:39.704435Z","steps":["trace[1215698508] 'process raft request' (duration: 103.571174ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:47:45.80263Z","caller":"traceutil/trace.go:171","msg":"trace[264579679] linearizableReadLoop","detail":"{readStateIndex:2287; appliedIndex:2286; }","duration":"176.95788ms","start":"2026-09-10T17:47:45.62564Z","end":"2026-09-10T17:47:45.802598Z","steps":["trace[264579679] 'read index received' (duration: 176.765454ms)","trace[264579679] 'applied index is now lower than readState.Index' (duration: 191.106µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T17:47:45.802718Z","caller":"traceutil/trace.go:171","msg":"trace[1838370697] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"329.492721ms","start":"2026-09-10T17:47:45.473173Z","end":"2026-09-10T17:47:45.802666Z","steps":["trace[1838370697] 'process raft request' (duration: 329.206172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:47:45.802776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.117795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T17:47:45.802813Z","caller":"traceutil/trace.go:171","msg":"trace[945064331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2204; }","duration":"177.235669ms","start":"2026-09-10T17:47:45.625567Z","end":"2026-09-10T17:47:45.802803Z","steps":["trace[945064331] 'agreement among raft nodes before linearized reading' (duration: 177.150127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:47:45.802928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T17:47:45.47314Z","time spent":"329.670736ms","remote":"127.0.0.1:53086","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-09-10T17:48:55.709974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.372444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T17:48:55.710403Z","caller":"traceutil/trace.go:171","msg":"trace[426661899] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"277.992091ms","start":"2026-09-10T17:48:55.432361Z","end":"2026-09-10T17:48:55.710353Z","steps":["trace[426661899] 'process raft request' (duration: 63.150763ms)","trace[426661899] 'compare' (duration: 214.138006ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T17:49:00.998816Z","caller":"traceutil/trace.go:171","msg":"trace[718652926] transaction","detail":"{read_only:false; response_revision:2809; number_of_response:1; }","duration":"119.676163ms","start":"2026-09-10T17:49:00.879112Z","end":"2026-09-10T17:49:00.998788Z","steps":["trace[718652926] 'process raft request' (duration: 119.496878ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:53:25.972514Z","caller":"traceutil/trace.go:171","msg":"trace[987726942] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"358.411939ms","start":"2026-09-10T17:53:25.614079Z","end":"2026-09-10T17:53:25.972491Z","steps":["trace[987726942] 'process raft request' (duration: 358.249714ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:53:25.972692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T17:53:25.614065Z","time spent":"358.555483ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T17:53:25.979582Z","caller":"traceutil/trace.go:171","msg":"trace[468704847] linearizableReadLoop","detail":"{readStateIndex:5308; appliedIndex:5306; }","duration":"356.413319ms","start":"2026-09-10T17:53:25.62311Z","end":"2026-09-10T17:53:25.979523Z","steps":["trace[468704847] 'read index received' (duration: 349.314754ms)","trace[468704847] 'applied index is now lower than readState.Index' (duration: 7.097445ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T17:53:25.979886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.761089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T17:53:25.979922Z","caller":"traceutil/trace.go:171","msg":"trace[389833847] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"363.69005ms","start":"2026-09-10T17:53:25.616214Z","end":"2026-09-10T17:53:25.979904Z","steps":["trace[389833847] 'process raft request' (duration: 363.180135ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:53:25.979973Z","caller":"traceutil/trace.go:171","msg":"trace[928268938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5117; }","duration":"356.894394ms","start":"2026-09-10T17:53:25.623056Z","end":"2026-09-10T17:53:25.97995Z","steps":["trace[928268938] 'agreement among raft nodes before linearized reading' (duration: 356.74337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:53:25.980028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T17:53:25.623035Z","time spent":"356.981666ms","remote":"127.0.0.1:54762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-10T17:53:25.980042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T17:53:25.616197Z","time spent":"363.768012ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T17:53:25.98003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.147971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9bcde822-3117-4750-9e86-88ece7d0c8f0\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-09-10T17:53:25.980118Z","caller":"traceutil/trace.go:171","msg":"trace[1776416863] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9bcde822-3117-4750-9e86-88ece7d0c8f0; range_end:; response_count:1; response_revision:5117; }","duration":"294.276614ms","start":"2026-09-10T17:53:25.685819Z","end":"2026-09-10T17:53:25.980096Z","steps":["trace[1776416863] 'agreement among raft nodes before linearized reading' (duration: 294.067578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:53:25.980411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.347704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T17:53:25.980579Z","caller":"traceutil/trace.go:171","msg":"trace[1256270435] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:5117; }","duration":"227.705956ms","start":"2026-09-10T17:53:25.752853Z","end":"2026-09-10T17:53:25.980559Z","steps":["trace[1256270435] 'agreement among raft nodes before linearized reading' (duration: 227.348775ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:53:40.591281Z","caller":"traceutil/trace.go:171","msg":"trace[1255963571] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"182.076522ms","start":"2026-09-10T17:53:40.409185Z","end":"2026-09-10T17:53:40.591261Z","steps":["trace[1255963571] 'process raft request' (duration: 181.638428ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:53:48.637689Z","caller":"traceutil/trace.go:171","msg":"trace[1747659940] linearizableReadLoop","detail":"{readStateIndex:5788; appliedIndex:5787; }","duration":"102.813918ms","start":"2026-09-10T17:53:48.534853Z","end":"2026-09-10T17:53:48.637667Z","steps":["trace[1747659940] 'read index received' (duration: 102.582271ms)","trace[1747659940] 'applied index is now lower than readState.Index' (duration: 230.947µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T17:53:48.637782Z","caller":"traceutil/trace.go:171","msg":"trace[687024299] transaction","detail":"{read_only:false; response_revision:5584; number_of_response:1; }","duration":"143.385368ms","start":"2026-09-10T17:53:48.494358Z","end":"2026-09-10T17:53:48.637744Z","steps":["trace[687024299] 'process raft request' (duration: 143.135161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:53:48.637897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.025875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-09-10T17:53:48.63797Z","caller":"traceutil/trace.go:171","msg":"trace[1838354319] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5584; }","duration":"103.133919ms","start":"2026-09-10T17:53:48.534819Z","end":"2026-09-10T17:53:48.637953Z","steps":["trace[1838354319] 'agreement among raft nodes before linearized reading' (duration: 102.958393ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:54:00.743106Z","caller":"traceutil/trace.go:171","msg":"trace[513579511] linearizableReadLoop","detail":"{readStateIndex:5918; appliedIndex:5917; }","duration":"119.909275ms","start":"2026-09-10T17:54:00.623171Z","end":"2026-09-10T17:54:00.743081Z","steps":["trace[513579511] 'read index received' (duration: 119.707809ms)","trace[513579511] 'applied index is now lower than readState.Index' (duration: 200.466µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T17:54:00.743177Z","caller":"traceutil/trace.go:171","msg":"trace[1034362088] transaction","detail":"{read_only:false; response_revision:5710; number_of_response:1; }","duration":"122.505364ms","start":"2026-09-10T17:54:00.62064Z","end":"2026-09-10T17:54:00.743145Z","steps":["trace[1034362088] 'process raft request' (duration: 122.309008ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T17:54:00.743285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.105522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T17:54:00.743383Z","caller":"traceutil/trace.go:171","msg":"trace[928946413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5710; }","duration":"120.238356ms","start":"2026-09-10T17:54:00.623123Z","end":"2026-09-10T17:54:00.743362Z","steps":["trace[928946413] 'agreement among raft nodes before linearized reading' (duration: 120.101892ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:54:00.901245Z","caller":"traceutil/trace.go:171","msg":"trace[2139867466] transaction","detail":"{read_only:false; response_revision:5712; number_of_response:1; }","duration":"151.813854ms","start":"2026-09-10T17:54:00.749393Z","end":"2026-09-10T17:54:00.901207Z","steps":["trace[2139867466] 'process raft request' (duration: 91.049032ms)","trace[2139867466] 'compare' (duration: 60.397661ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T17:54:00.901232Z","caller":"traceutil/trace.go:171","msg":"trace[172030558] transaction","detail":"{read_only:false; response_revision:5713; number_of_response:1; }","duration":"151.708701ms","start":"2026-09-10T17:54:00.749485Z","end":"2026-09-10T17:54:00.901194Z","steps":["trace[172030558] 'process raft request' (duration: 151.532495ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:54:00.901408Z","caller":"traceutil/trace.go:171","msg":"trace[434170217] transaction","detail":"{read_only:false; response_revision:5714; number_of_response:1; }","duration":"151.629408ms","start":"2026-09-10T17:54:00.749754Z","end":"2026-09-10T17:54:00.901384Z","steps":["trace[434170217] 'process raft request' (duration: 151.35114ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:54:00.902169Z","caller":"traceutil/trace.go:171","msg":"trace[1316178067] transaction","detail":"{read_only:false; response_revision:5715; number_of_response:1; }","duration":"151.885817ms","start":"2026-09-10T17:54:00.750267Z","end":"2026-09-10T17:54:00.902153Z","steps":["trace[1316178067] 'process raft request' (duration: 150.886386ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T17:54:25.48439Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2943} {"level":"info","ts":"2026-09-10T17:54:25.556146Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2943,"took":"70.711679ms","hash":307330659,"current-db-size-bytes":56537088,"current-db-size":"56 MB","current-db-size-in-use-bytes":32747520,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-10T17:54:25.5562Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":307330659,"revision":2943,"compact-revision":-1} {"level":"info","ts":"2026-09-10T17:54:55.154738Z","caller":"traceutil/trace.go:171","msg":"trace[2060598373] transaction","detail":"{read_only:false; response_revision:6169; number_of_response:1; }","duration":"121.700992ms","start":"2026-09-10T17:54:55.033005Z","end":"2026-09-10T17:54:55.154706Z","steps":["trace[2060598373] 'process raft request' (duration: 79.852006ms)","trace[2060598373] 'compare' (duration: 41.610428ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T17:59:25.496094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5879} {"level":"info","ts":"2026-09-10T17:59:25.58858Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5879,"took":"90.596584ms","hash":2693162759,"current-db-size-bytes":56537088,"current-db-size":"56 MB","current-db-size-in-use-bytes":26677248,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-10T17:59:25.588661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2693162759,"revision":5879,"compact-revision":2943} {"level":"info","ts":"2026-09-10T18:00:09.712814Z","caller":"traceutil/trace.go:171","msg":"trace[383551091] transaction","detail":"{read_only:false; response_revision:8467; number_of_response:1; }","duration":"186.748176ms","start":"2026-09-10T18:00:09.526043Z","end":"2026-09-10T18:00:09.712791Z","steps":["trace[383551091] 'process raft request' (duration: 186.589451ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:00:09.732497Z","caller":"traceutil/trace.go:171","msg":"trace[1078200163] linearizableReadLoop","detail":"{readStateIndex:8766; appliedIndex:8764; }","duration":"191.728729ms","start":"2026-09-10T18:00:09.540753Z","end":"2026-09-10T18:00:09.732481Z","steps":["trace[1078200163] 'read index received' (duration: 171.923945ms)","trace[1078200163] 'applied index is now lower than readState.Index' (duration: 19.804174ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T18:00:09.732592Z","caller":"traceutil/trace.go:171","msg":"trace[900932157] transaction","detail":"{read_only:false; response_revision:8468; number_of_response:1; }","duration":"206.493898ms","start":"2026-09-10T18:00:09.526073Z","end":"2026-09-10T18:00:09.732567Z","steps":["trace[900932157] 'process raft request' (duration: 206.315053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:09.732627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.856542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T18:00:09.732648Z","caller":"traceutil/trace.go:171","msg":"trace[376578235] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8468; }","duration":"191.924924ms","start":"2026-09-10T18:00:09.540717Z","end":"2026-09-10T18:00:09.732642Z","steps":["trace[376578235] 'agreement among raft nodes before linearized reading' (duration: 191.861392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:09.732808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.444291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t5zjq\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-09-10T18:00:09.732895Z","caller":"traceutil/trace.go:171","msg":"trace[1830827015] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t5zjq; range_end:; response_count:1; response_revision:8468; }","duration":"175.537744ms","start":"2026-09-10T18:00:09.557327Z","end":"2026-09-10T18:00:09.732865Z","steps":["trace[1830827015] 'agreement among raft nodes before linearized reading' (duration: 175.285796ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:00:20.939915Z","caller":"traceutil/trace.go:171","msg":"trace[2011477996] linearizableReadLoop","detail":"{readStateIndex:8823; appliedIndex:8822; }","duration":"159.402133ms","start":"2026-09-10T18:00:20.780489Z","end":"2026-09-10T18:00:20.939891Z","steps":["trace[2011477996] 'read index received' (duration: 159.189115ms)","trace[2011477996] 'applied index is now lower than readState.Index' (duration: 212.078µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T18:00:20.939967Z","caller":"traceutil/trace.go:171","msg":"trace[1470426137] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"161.677352ms","start":"2026-09-10T18:00:20.778262Z","end":"2026-09-10T18:00:20.939939Z","steps":["trace[1470426137] 'process raft request' (duration: 161.485166ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:20.940144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.63683ms","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-09-10T18:00:20.940237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.829081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-10T18:00:20.94028Z","caller":"traceutil/trace.go:171","msg":"trace[1935002194] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8523; }","duration":"159.803435ms","start":"2026-09-10T18:00:20.780457Z","end":"2026-09-10T18:00:20.940261Z","steps":["trace[1935002194] 'agreement among raft nodes before linearized reading' (duration: 159.563998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:20.940145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.926285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-10T18:00:20.940335Z","caller":"traceutil/trace.go:171","msg":"trace[400799087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8523; }","duration":"109.955934ms","start":"2026-09-10T18:00:20.830358Z","end":"2026-09-10T18:00:20.940314Z","steps":["trace[400799087] 'agreement among raft nodes before linearized reading' (duration: 109.750238ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:00:20.940357Z","caller":"traceutil/trace.go:171","msg":"trace[218180835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8523; }","duration":"111.147521ms","start":"2026-09-10T18:00:20.829196Z","end":"2026-09-10T18:00:20.940343Z","steps":["trace[218180835] 'agreement among raft nodes before linearized reading' (duration: 110.854023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:21.159933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.69861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-1f07593f-280c-4485-9273-f2e407e15b57\" ","response":"range_response_count:1 size:1303"} {"level":"info","ts":"2026-09-10T18:00:21.160029Z","caller":"traceutil/trace.go:171","msg":"trace[1554959909] range","detail":"{range_begin:/registry/persistentvolumes/pvc-1f07593f-280c-4485-9273-f2e407e15b57; range_end:; response_count:1; response_revision:8523; }","duration":"215.848674ms","start":"2026-09-10T18:00:20.944154Z","end":"2026-09-10T18:00:21.160002Z","steps":["trace[1554959909] 'range keys from in-memory index tree' (duration: 215.529545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:21.160098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.40541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T18:00:21.160296Z","caller":"traceutil/trace.go:171","msg":"trace[1475825285] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"215.106592ms","start":"2026-09-10T18:00:20.945174Z","end":"2026-09-10T18:00:21.160281Z","steps":["trace[1475825285] 'process raft request' (duration: 215.01178ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:00:21.160336Z","caller":"traceutil/trace.go:171","msg":"trace[654219361] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"215.869695ms","start":"2026-09-10T18:00:20.944432Z","end":"2026-09-10T18:00:21.160301Z","steps":["trace[654219361] 'process raft request' (duration: 23.151316ms)","trace[654219361] 'compare' (duration: 192.217164ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T18:00:21.160528Z","caller":"traceutil/trace.go:171","msg":"trace[1691097635] transaction","detail":"{read_only:false; response_revision:8526; number_of_response:1; }","duration":"215.249806ms","start":"2026-09-10T18:00:20.945254Z","end":"2026-09-10T18:00:21.160504Z","steps":["trace[1691097635] 'process raft request' (duration: 214.983258ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:00:21.160498Z","caller":"traceutil/trace.go:171","msg":"trace[1883024128] linearizableReadLoop","detail":"{readStateIndex:8826; appliedIndex:8823; }","duration":"128.949173ms","start":"2026-09-10T18:00:21.031528Z","end":"2026-09-10T18:00:21.160478Z","steps":["trace[1883024128] 'read index received' (duration: 50.481µs)","trace[1883024128] 'applied index is now lower than readState.Index' (duration: 128.863911ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T18:00:21.160715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.188011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-10T18:00:21.160903Z","caller":"traceutil/trace.go:171","msg":"trace[1950326705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8526; }","duration":"129.376036ms","start":"2026-09-10T18:00:21.031471Z","end":"2026-09-10T18:00:21.160847Z","steps":["trace[1950326705] 'agreement among raft nodes before linearized reading' (duration: 129.101978ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:00:22.007945Z","caller":"traceutil/trace.go:171","msg":"trace[1071518885] transaction","detail":"{read_only:false; response_revision:8528; number_of_response:1; }","duration":"248.798808ms","start":"2026-09-10T18:00:21.759127Z","end":"2026-09-10T18:00:22.007926Z","steps":["trace[1071518885] 'process raft request' (duration: 248.694235ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:00:22.064558Z","caller":"traceutil/trace.go:171","msg":"trace[782606382] transaction","detail":"{read_only:false; response_revision:8529; number_of_response:1; }","duration":"296.730941ms","start":"2026-09-10T18:00:21.767804Z","end":"2026-09-10T18:00:22.064535Z","steps":["trace[782606382] 'process raft request' (duration: 296.576267ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:00:22.064848Z","caller":"traceutil/trace.go:171","msg":"trace[775745612] linearizableReadLoop","detail":"{readStateIndex:8829; appliedIndex:8827; }","duration":"265.101666ms","start":"2026-09-10T18:00:21.799726Z","end":"2026-09-10T18:00:22.064828Z","steps":["trace[775745612] 'read index received' (duration: 208.168559ms)","trace[775745612] 'applied index is now lower than readState.Index' (duration: 56.932247ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T18:00:22.064966Z","caller":"traceutil/trace.go:171","msg":"trace[1122805763] transaction","detail":"{read_only:false; response_revision:8531; number_of_response:1; }","duration":"171.331156ms","start":"2026-09-10T18:00:21.893599Z","end":"2026-09-10T18:00:22.06493Z","steps":["trace[1122805763] 'process raft request' (duration: 171.12156ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:00:22.064998Z","caller":"traceutil/trace.go:171","msg":"trace[612238999] transaction","detail":"{read_only:false; response_revision:8530; number_of_response:1; }","duration":"171.717708ms","start":"2026-09-10T18:00:21.893259Z","end":"2026-09-10T18:00:22.064977Z","steps":["trace[612238999] 'process raft request' (duration: 171.240184ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:22.065048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.307172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t5zjq\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-09-10T18:00:22.065085Z","caller":"traceutil/trace.go:171","msg":"trace[19299388] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t5zjq; range_end:; response_count:1; response_revision:8531; }","duration":"265.390894ms","start":"2026-09-10T18:00:21.799682Z","end":"2026-09-10T18:00:22.065073Z","steps":["trace[19299388] 'agreement among raft nodes before linearized reading' (duration: 265.26112ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:22.065207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.835251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-10T18:00:22.065265Z","caller":"traceutil/trace.go:171","msg":"trace[662198752] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:8531; }","duration":"192.935305ms","start":"2026-09-10T18:00:21.872312Z","end":"2026-09-10T18:00:22.065247Z","steps":["trace[662198752] 'agreement among raft nodes before linearized reading' (duration: 192.74684ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:00:22.773798Z","caller":"traceutil/trace.go:171","msg":"trace[241400093] transaction","detail":"{read_only:false; response_revision:8532; number_of_response:1; }","duration":"266.738636ms","start":"2026-09-10T18:00:22.50703Z","end":"2026-09-10T18:00:22.773768Z","steps":["trace[241400093] 'process raft request' (duration: 266.271962ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:00:22.8519Z","caller":"traceutil/trace.go:171","msg":"trace[1635801388] linearizableReadLoop","detail":"{readStateIndex:8833; appliedIndex:8831; }","duration":"225.834018ms","start":"2026-09-10T18:00:22.626047Z","end":"2026-09-10T18:00:22.851881Z","steps":["trace[1635801388] 'read index received' (duration: 147.097577ms)","trace[1635801388] 'applied index is now lower than readState.Index' (duration: 78.735741ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T18:00:22.852009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.949022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T18:00:22.851995Z","caller":"traceutil/trace.go:171","msg":"trace[1220012717] transaction","detail":"{read_only:false; response_revision:8533; number_of_response:1; }","duration":"343.993673ms","start":"2026-09-10T18:00:22.50796Z","end":"2026-09-10T18:00:22.851954Z","steps":["trace[1220012717] 'process raft request' (duration: 343.812837ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:00:22.852053Z","caller":"traceutil/trace.go:171","msg":"trace[2055413878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8533; }","duration":"226.016714ms","start":"2026-09-10T18:00:22.626012Z","end":"2026-09-10T18:00:22.852028Z","steps":["trace[2055413878] 'agreement among raft nodes before linearized reading' (duration: 225.959802ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:22.852183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:00:22.507934Z","time spent":"344.139187ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T18:00:46.235633Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002314.wal"} {"level":"info","ts":"2026-09-10T18:00:50.482166Z","caller":"traceutil/trace.go:171","msg":"trace[666136177] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"383.169919ms","start":"2026-09-10T18:00:50.09897Z","end":"2026-09-10T18:00:50.48214Z","steps":["trace[666136177] 'process raft request' (duration: 382.990193ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:50.482331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:00:50.098947Z","time spent":"383.295352ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T18:00:50.744542Z","caller":"traceutil/trace.go:171","msg":"trace[281058470] linearizableReadLoop","detail":"{readStateIndex:9046; appliedIndex:9043; }","duration":"497.707532ms","start":"2026-09-10T18:00:50.246811Z","end":"2026-09-10T18:00:50.744519Z","steps":["trace[281058470] 'read index received' (duration: 235.250596ms)","trace[281058470] 'applied index is now lower than readState.Index' (duration: 262.455675ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T18:00:50.744721Z","caller":"traceutil/trace.go:171","msg":"trace[694909357] transaction","detail":"{read_only:false; response_revision:8736; number_of_response:1; }","duration":"576.03289ms","start":"2026-09-10T18:00:50.168673Z","end":"2026-09-10T18:00:50.744706Z","steps":["trace[694909357] 'process raft request' (duration: 573.36199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:50.744839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:00:50.168653Z","time spent":"576.099803ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T18:00:50.745069Z","caller":"traceutil/trace.go:171","msg":"trace[2075669016] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"575.515145ms","start":"2026-09-10T18:00:50.169541Z","end":"2026-09-10T18:00:50.745056Z","steps":["trace[2075669016] 'process raft request' (duration: 574.922696ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:50.745128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:00:50.169498Z","time spent":"575.599618ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T18:00:50.745247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.39975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T18:00:50.745334Z","caller":"traceutil/trace.go:171","msg":"trace[1709126476] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8737; }","duration":"453.552495ms","start":"2026-09-10T18:00:50.29176Z","end":"2026-09-10T18:00:50.745312Z","steps":["trace[1709126476] 'agreement among raft nodes before linearized reading' (duration: 453.37784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:50.745389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:00:50.291739Z","time spent":"453.635977ms","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-10T18:00:50.745388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.301054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-10T18:00:50.745255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.166536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T18:00:50.745441Z","caller":"traceutil/trace.go:171","msg":"trace[1881784040] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:8737; }","duration":"249.379716ms","start":"2026-09-10T18:00:50.496043Z","end":"2026-09-10T18:00:50.745423Z","steps":["trace[1881784040] 'agreement among raft nodes before linearized reading' (duration: 249.267903ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:00:50.745471Z","caller":"traceutil/trace.go:171","msg":"trace[800512460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8737; }","duration":"120.450135ms","start":"2026-09-10T18:00:50.625008Z","end":"2026-09-10T18:00:50.745458Z","steps":["trace[800512460] 'agreement among raft nodes before linearized reading' (duration: 120.195017ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:50.745474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.63395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t5zjq\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-09-10T18:00:50.745573Z","caller":"traceutil/trace.go:171","msg":"trace[1305785155] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t5zjq; range_end:; response_count:1; response_revision:8737; }","duration":"498.802295ms","start":"2026-09-10T18:00:50.246749Z","end":"2026-09-10T18:00:50.745551Z","steps":["trace[1305785155] 'agreement among raft nodes before linearized reading' (duration: 498.506216ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:50.745634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:00:50.246723Z","time spent":"498.896587ms","remote":"127.0.0.1:53004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6469,"request content":"key:\"/registry/pods/openstack/keepalived-t5zjq\" "} {"level":"info","ts":"2026-09-10T18:00:53.452246Z","caller":"traceutil/trace.go:171","msg":"trace[1836644172] transaction","detail":"{read_only:false; response_revision:8751; number_of_response:1; }","duration":"141.876487ms","start":"2026-09-10T18:00:53.31035Z","end":"2026-09-10T18:00:53.452227Z","steps":["trace[1836644172] 'process raft request' (duration: 141.262588ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:00:53.667855Z","caller":"traceutil/trace.go:171","msg":"trace[714638343] transaction","detail":"{read_only:false; response_revision:8752; number_of_response:1; }","duration":"351.274205ms","start":"2026-09-10T18:00:53.31652Z","end":"2026-09-10T18:00:53.667794Z","steps":["trace[714638343] 'process raft request' (duration: 301.758605ms)","trace[714638343] 'compare' (duration: 49.060977ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T18:00:53.668031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:00:53.316494Z","time spent":"351.44505ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T18:00:53.668321Z","caller":"traceutil/trace.go:171","msg":"trace[473607509] linearizableReadLoop","detail":"{readStateIndex:9061; appliedIndex:9059; }","duration":"342.549919ms","start":"2026-09-10T18:00:53.325733Z","end":"2026-09-10T18:00:53.668282Z","steps":["trace[473607509] 'read index received' (duration: 126.034605ms)","trace[473607509] 'applied index is now lower than readState.Index' (duration: 216.509603ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T18:00:53.66895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.19817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-10T18:00:53.669017Z","caller":"traceutil/trace.go:171","msg":"trace[403621804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8752; }","duration":"328.328675ms","start":"2026-09-10T18:00:53.340673Z","end":"2026-09-10T18:00:53.669001Z","steps":["trace[403621804] 'agreement among raft nodes before linearized reading' (duration: 328.063687ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:53.669177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.429916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-10T18:00:53.669233Z","caller":"traceutil/trace.go:171","msg":"trace[184097016] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8752; }","duration":"343.520238ms","start":"2026-09-10T18:00:53.325702Z","end":"2026-09-10T18:00:53.669222Z","steps":["trace[184097016] 'agreement among raft nodes before linearized reading' (duration: 342.692443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:00:53.669261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:00:53.32569Z","time spent":"343.56131ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-10T18:00:53.669734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:00:53.340665Z","time spent":"329.046397ms","remote":"127.0.0.1:53086","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":"info","ts":"2026-09-10T18:00:53.788805Z","caller":"traceutil/trace.go:171","msg":"trace[634539433] transaction","detail":"{read_only:false; response_revision:8754; number_of_response:1; }","duration":"112.484042ms","start":"2026-09-10T18:00:53.676288Z","end":"2026-09-10T18:00:53.788772Z","steps":["trace[634539433] 'process raft request' (duration: 93.698408ms)","trace[634539433] 'compare' (duration: 18.567136ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T18:01:01.611127Z","caller":"traceutil/trace.go:171","msg":"trace[888920988] linearizableReadLoop","detail":"{readStateIndex:9106; appliedIndex:9105; }","duration":"110.460469ms","start":"2026-09-10T18:01:01.500644Z","end":"2026-09-10T18:01:01.611104Z","steps":["trace[888920988] 'read index received' (duration: 110.215552ms)","trace[888920988] 'applied index is now lower than readState.Index' (duration: 241.887µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T18:01:01.611294Z","caller":"traceutil/trace.go:171","msg":"trace[615633610] transaction","detail":"{read_only:false; response_revision:8795; number_of_response:1; }","duration":"171.288624ms","start":"2026-09-10T18:01:01.439963Z","end":"2026-09-10T18:01:01.611252Z","steps":["trace[615633610] 'process raft request' (duration: 170.992636ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:01:01.611357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.709497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-10T18:01:01.611392Z","caller":"traceutil/trace.go:171","msg":"trace[1637157429] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8795; }","duration":"110.801199ms","start":"2026-09-10T18:01:01.500579Z","end":"2026-09-10T18:01:01.61138Z","steps":["trace[1637157429] 'agreement among raft nodes before linearized reading' (duration: 110.609744ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:01:03.714642Z","caller":"traceutil/trace.go:171","msg":"trace[712435438] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8815; }","duration":"104.273591ms","start":"2026-09-10T18:01:03.610346Z","end":"2026-09-10T18:01:03.71462Z","steps":["trace[712435438] 'process raft request' (duration: 103.792046ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:02:32.912681Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a656331365382b34","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-10T18:02:32.918971Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-10T18:02:32.919176Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-10T18:02:33.957118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.494307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T18:02:33.957401Z","caller":"traceutil/trace.go:171","msg":"trace[820710067] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:9669; }","duration":"102.694193ms","start":"2026-09-10T18:02:33.854539Z","end":"2026-09-10T18:02:33.957233Z","steps":["trace[820710067] 'count revisions from in-memory index tree' (duration: 102.408264ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:04:25.502256Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8059} {"level":"info","ts":"2026-09-10T18:04:25.567394Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8059,"took":"63.029962ms","hash":2515305213,"current-db-size-bytes":56537088,"current-db-size":"56 MB","current-db-size-in-use-bytes":28209152,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-10T18:04:25.567457Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2515305213,"revision":8059,"compact-revision":5879} {"level":"warn","ts":"2026-09-10T18:08:13.585783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.902308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T18:08:13.585901Z","caller":"traceutil/trace.go:171","msg":"trace[403794259] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12107; }","duration":"191.053582ms","start":"2026-09-10T18:08:13.394824Z","end":"2026-09-10T18:08:13.585877Z","steps":["trace[403794259] 'range keys from in-memory index tree' (duration: 190.816585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:08:13.585906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.492824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-09-10T18:08:13.586004Z","caller":"traceutil/trace.go:171","msg":"trace[378804736] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12107; }","duration":"188.612448ms","start":"2026-09-10T18:08:13.39737Z","end":"2026-09-10T18:08:13.585982Z","steps":["trace[378804736] 'range keys from in-memory index tree' (duration: 188.386411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:08:13.586168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.522286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T18:08:13.586189Z","caller":"traceutil/trace.go:171","msg":"trace[1558544023] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12107; }","duration":"191.604439ms","start":"2026-09-10T18:08:13.394577Z","end":"2026-09-10T18:08:13.586181Z","steps":["trace[1558544023] 'range keys from in-memory index tree' (duration: 191.391692ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:08:13.586258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.195216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T18:08:13.586281Z","caller":"traceutil/trace.go:171","msg":"trace[2057344822] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12107; }","duration":"192.227367ms","start":"2026-09-10T18:08:13.394047Z","end":"2026-09-10T18:08:13.586274Z","steps":["trace[2057344822] 'range keys from in-memory index tree' (duration: 192.168596ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:08:13.586335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.750524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T18:08:13.586555Z","caller":"traceutil/trace.go:171","msg":"trace[1337969347] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12107; }","duration":"193.037892ms","start":"2026-09-10T18:08:13.393486Z","end":"2026-09-10T18:08:13.586524Z","steps":["trace[1337969347] 'range keys from in-memory index tree' (duration: 192.667901ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:08:14.836521Z","caller":"traceutil/trace.go:171","msg":"trace[14673243] linearizableReadLoop","detail":"{readStateIndex:12532; appliedIndex:12531; }","duration":"202.777219ms","start":"2026-09-10T18:08:14.633708Z","end":"2026-09-10T18:08:14.836485Z","steps":["trace[14673243] 'read index received' (duration: 202.397178ms)","trace[14673243] 'applied index is now lower than readState.Index' (duration: 378.451µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T18:08:14.836641Z","caller":"traceutil/trace.go:171","msg":"trace[100734864] transaction","detail":"{read_only:false; response_revision:12116; number_of_response:1; }","duration":"224.15212ms","start":"2026-09-10T18:08:14.612462Z","end":"2026-09-10T18:08:14.836614Z","steps":["trace[100734864] 'process raft request' (duration: 223.788829ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:08:14.836743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.992545ms","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-09-10T18:08:14.83679Z","caller":"traceutil/trace.go:171","msg":"trace[1062554117] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:12116; }","duration":"203.072958ms","start":"2026-09-10T18:08:14.633701Z","end":"2026-09-10T18:08:14.836774Z","steps":["trace[1062554117] 'agreement among raft nodes before linearized reading' (duration: 202.919553ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:09:25.509054Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10463} {"level":"info","ts":"2026-09-10T18:09:25.611126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10463,"took":"99.555203ms","hash":2926491271,"current-db-size-bytes":56537088,"current-db-size":"56 MB","current-db-size-in-use-bytes":26378240,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-10T18:09:25.611212Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2926491271,"revision":10463,"compact-revision":8059} {"level":"info","ts":"2026-09-10T18:14:25.517505Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12597} {"level":"info","ts":"2026-09-10T18:14:25.607165Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12597,"took":"86.058585ms","hash":3978883560,"current-db-size-bytes":56537088,"current-db-size":"56 MB","current-db-size-in-use-bytes":27721728,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-10T18:14:25.607285Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3978883560,"revision":12597,"compact-revision":10463} {"level":"warn","ts":"2026-09-10T18:14:38.159736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.494398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-10T18:14:38.159877Z","caller":"traceutil/trace.go:171","msg":"trace[1572958110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14680; }","duration":"153.704503ms","start":"2026-09-10T18:14:38.006142Z","end":"2026-09-10T18:14:38.159846Z","steps":["trace[1572958110] 'range keys from in-memory index tree' (duration: 153.29589ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:14:38.160106Z","caller":"traceutil/trace.go:171","msg":"trace[1151051655] transaction","detail":"{read_only:false; response_revision:14681; number_of_response:1; }","duration":"137.515595ms","start":"2026-09-10T18:14:38.022555Z","end":"2026-09-10T18:14:38.160071Z","steps":["trace[1151051655] 'process raft request' (duration: 136.254457ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:14:38.161793Z","caller":"traceutil/trace.go:171","msg":"trace[1331909955] transaction","detail":"{read_only:false; response_revision:14682; number_of_response:1; }","duration":"134.350019ms","start":"2026-09-10T18:14:38.027419Z","end":"2026-09-10T18:14:38.161769Z","steps":["trace[1331909955] 'process raft request' (duration: 132.585845ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:15:03.927724Z","caller":"traceutil/trace.go:171","msg":"trace[1819856276] transaction","detail":"{read_only:false; response_revision:14812; number_of_response:1; }","duration":"100.119089ms","start":"2026-09-10T18:15:03.827565Z","end":"2026-09-10T18:15:03.927684Z","steps":["trace[1819856276] 'process raft request' (duration: 99.82729ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:15:12.788517Z","caller":"traceutil/trace.go:171","msg":"trace[138289945] transaction","detail":"{read_only:false; response_revision:14862; number_of_response:1; }","duration":"137.410232ms","start":"2026-09-10T18:15:12.651062Z","end":"2026-09-10T18:15:12.788472Z","steps":["trace[138289945] 'process raft request' (duration: 137.080222ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:15:21.5591Z","caller":"traceutil/trace.go:171","msg":"trace[748338688] transaction","detail":"{read_only:false; response_revision:14918; number_of_response:1; }","duration":"204.0318ms","start":"2026-09-10T18:15:21.355037Z","end":"2026-09-10T18:15:21.559069Z","steps":["trace[748338688] 'process raft request' (duration: 203.861135ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:15:22.411579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.048497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T18:15:22.411689Z","caller":"traceutil/trace.go:171","msg":"trace[615091032] linearizableReadLoop","detail":"{readStateIndex:15437; appliedIndex:15436; }","duration":"393.511441ms","start":"2026-09-10T18:15:22.018158Z","end":"2026-09-10T18:15:22.411669Z","steps":["trace[615091032] 'read index received' (duration: 276.145964ms)","trace[615091032] 'applied index is now lower than readState.Index' (duration: 117.364007ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T18:15:22.411767Z","caller":"traceutil/trace.go:171","msg":"trace[1646565793] transaction","detail":"{read_only:false; response_revision:14920; number_of_response:1; }","duration":"413.210046ms","start":"2026-09-10T18:15:21.998503Z","end":"2026-09-10T18:15:22.411713Z","steps":["trace[1646565793] 'process raft request' (duration: 295.918461ms)","trace[1646565793] 'compare' (duration: 116.395248ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T18:15:22.411911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.745328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T18:15:22.411984Z","caller":"traceutil/trace.go:171","msg":"trace[1313631596] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:14920; }","duration":"393.83703ms","start":"2026-09-10T18:15:22.018104Z","end":"2026-09-10T18:15:22.411941Z","steps":["trace[1313631596] 'agreement among raft nodes before linearized reading' (duration: 393.690526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:15:22.411982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:15:21.998436Z","time spent":"413.425962ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T18:15:22.41206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:15:22.018077Z","time spent":"393.943345ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-09-10T18:15:22.489376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.280481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t5zjq\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-09-10T18:15:22.489452Z","caller":"traceutil/trace.go:171","msg":"trace[1986797375] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t5zjq; range_end:; response_count:1; response_revision:14921; }","duration":"375.418596ms","start":"2026-09-10T18:15:22.114014Z","end":"2026-09-10T18:15:22.489433Z","steps":["trace[1986797375] 'agreement among raft nodes before linearized reading' (duration: 375.23764ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:15:22.48954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:15:22.113992Z","time spent":"375.532279ms","remote":"127.0.0.1:53004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6469,"request content":"key:\"/registry/pods/openstack/keepalived-t5zjq\" "} {"level":"info","ts":"2026-09-10T18:15:22.489501Z","caller":"traceutil/trace.go:171","msg":"trace[880220577] transaction","detail":"{read_only:false; response_revision:14921; number_of_response:1; }","duration":"110.917373ms","start":"2026-09-10T18:15:22.378524Z","end":"2026-09-10T18:15:22.489441Z","steps":["trace[880220577] 'process raft request' (duration: 110.345145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:15:22.489528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.480044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:416190"} {"level":"info","ts":"2026-09-10T18:15:22.489677Z","caller":"traceutil/trace.go:171","msg":"trace[2051421420] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:14921; }","duration":"346.709211ms","start":"2026-09-10T18:15:22.142927Z","end":"2026-09-10T18:15:22.489636Z","steps":["trace[2051421420] 'agreement among raft nodes before linearized reading' (duration: 346.162255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:15:22.489759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:15:22.142895Z","time spent":"346.848375ms","remote":"127.0.0.1:50840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":416214,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-09-10T18:15:22.489811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.710149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T18:15:22.489939Z","caller":"traceutil/trace.go:171","msg":"trace[1523315655] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:14921; }","duration":"131.938747ms","start":"2026-09-10T18:15:22.357968Z","end":"2026-09-10T18:15:22.489906Z","steps":["trace[1523315655] 'agreement among raft nodes before linearized reading' (duration: 131.643788ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:15:22.492499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.979056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-10T18:15:22.492567Z","caller":"traceutil/trace.go:171","msg":"trace[1160651501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14921; }","duration":"469.086149ms","start":"2026-09-10T18:15:22.02346Z","end":"2026-09-10T18:15:22.492547Z","steps":["trace[1160651501] 'agreement among raft nodes before linearized reading' (duration: 466.12953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:15:22.4926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:15:22.023443Z","time spent":"469.148871ms","remote":"127.0.0.1:53086","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-09-10T18:15:33.982751Z","caller":"traceutil/trace.go:171","msg":"trace[1295734552] transaction","detail":"{read_only:false; response_revision:15103; number_of_response:1; }","duration":"108.574452ms","start":"2026-09-10T18:15:33.874143Z","end":"2026-09-10T18:15:33.982717Z","steps":["trace[1295734552] 'process raft request' (duration: 108.395256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:15:34.271589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.0962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-kbj9h.18d4085984c01166\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-09-10T18:15:34.271779Z","caller":"traceutil/trace.go:171","msg":"trace[1781253766] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-kbj9h.18d4085984c01166; range_end:; response_count:1; response_revision:15103; }","duration":"192.345519ms","start":"2026-09-10T18:15:34.07941Z","end":"2026-09-10T18:15:34.271755Z","steps":["trace[1781253766] 'range keys from in-memory index tree' (duration: 191.909745ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:15:34.459832Z","caller":"traceutil/trace.go:171","msg":"trace[616040367] transaction","detail":"{read_only:false; response_revision:15104; number_of_response:1; }","duration":"185.268244ms","start":"2026-09-10T18:15:34.274535Z","end":"2026-09-10T18:15:34.459804Z","steps":["trace[616040367] 'process raft request' (duration: 185.008986ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:17:21.472294Z","caller":"traceutil/trace.go:171","msg":"trace[1609568389] transaction","detail":"{read_only:false; response_revision:16231; number_of_response:1; }","duration":"110.819266ms","start":"2026-09-10T18:17:21.361441Z","end":"2026-09-10T18:17:21.47226Z","steps":["trace[1609568389] 'process raft request' (duration: 110.534176ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:17:21.705419Z","caller":"traceutil/trace.go:171","msg":"trace[1915045621] transaction","detail":"{read_only:false; response_revision:16232; number_of_response:1; }","duration":"153.454683ms","start":"2026-09-10T18:17:21.551936Z","end":"2026-09-10T18:17:21.705391Z","steps":["trace[1915045621] 'process raft request' (duration: 147.157172ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:19:25.529947Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14615} {"level":"info","ts":"2026-09-10T18:19:25.616765Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14615,"took":"83.369099ms","hash":1929629004,"current-db-size-bytes":56537088,"current-db-size":"56 MB","current-db-size-in-use-bytes":31879168,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-10T18:19:25.616866Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1929629004,"revision":14615,"compact-revision":12597} {"level":"info","ts":"2026-09-10T18:22:55.630266Z","caller":"traceutil/trace.go:171","msg":"trace[647747901] transaction","detail":"{read_only:false; response_revision:18665; number_of_response:1; }","duration":"269.416843ms","start":"2026-09-10T18:22:55.360821Z","end":"2026-09-10T18:22:55.630238Z","steps":["trace[647747901] 'process raft request' (duration: 268.770404ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:22:56.526356Z","caller":"traceutil/trace.go:171","msg":"trace[1725284035] transaction","detail":"{read_only:false; response_revision:18678; number_of_response:1; }","duration":"199.975609ms","start":"2026-09-10T18:22:56.326338Z","end":"2026-09-10T18:22:56.526314Z","steps":["trace[1725284035] 'process raft request' (duration: 151.393101ms)","trace[1725284035] 'compare' (duration: 48.380952ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T18:22:56.526729Z","caller":"traceutil/trace.go:171","msg":"trace[537159238] transaction","detail":"{read_only:false; response_revision:18679; number_of_response:1; }","duration":"148.41996ms","start":"2026-09-10T18:22:56.378266Z","end":"2026-09-10T18:22:56.526686Z","steps":["trace[537159238] 'process raft request' (duration: 147.992706ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:22:56.850118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.485053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-cdzc2\" ","response":"range_response_count:1 size:9713"} {"level":"warn","ts":"2026-09-10T18:22:56.850127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.490516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-10T18:22:56.85022Z","caller":"traceutil/trace.go:171","msg":"trace[739548299] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-cdzc2; range_end:; response_count:1; response_revision:18679; }","duration":"172.665798ms","start":"2026-09-10T18:22:56.677525Z","end":"2026-09-10T18:22:56.850191Z","steps":["trace[739548299] 'range keys from in-memory index tree' (duration: 172.233154ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:22:56.850266Z","caller":"traceutil/trace.go:171","msg":"trace[1577027751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18679; }","duration":"223.684401ms","start":"2026-09-10T18:22:56.626549Z","end":"2026-09-10T18:22:56.850234Z","steps":["trace[1577027751] 'range keys from in-memory index tree' (duration: 223.285069ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:22:56.850555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.033423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-10T18:22:56.850592Z","caller":"traceutil/trace.go:171","msg":"trace[851277156] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:18679; }","duration":"162.126547ms","start":"2026-09-10T18:22:56.688453Z","end":"2026-09-10T18:22:56.85058Z","steps":["trace[851277156] 'count revisions from in-memory index tree' (duration: 161.90944ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:23:10.746197Z","caller":"traceutil/trace.go:171","msg":"trace[1197546874] linearizableReadLoop","detail":"{readStateIndex:19494; appliedIndex:19492; }","duration":"153.440611ms","start":"2026-09-10T18:23:10.592722Z","end":"2026-09-10T18:23:10.746163Z","steps":["trace[1197546874] 'read index received' (duration: 6.027873ms)","trace[1197546874] 'applied index is now lower than readState.Index' (duration: 147.411537ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T18:23:10.746406Z","caller":"traceutil/trace.go:171","msg":"trace[1595480559] transaction","detail":"{read_only:false; response_revision:18848; number_of_response:1; }","duration":"157.71585ms","start":"2026-09-10T18:23:10.588673Z","end":"2026-09-10T18:23:10.746389Z","steps":["trace[1595480559] 'process raft request' (duration: 157.236026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:23:10.746785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.883534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/libvirt-libvirt-default-bgsvc-vnc\" ","response":"range_response_count:1 size:4285"} {"level":"warn","ts":"2026-09-10T18:23:10.746765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.052545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-10T18:23:10.74685Z","caller":"traceutil/trace.go:171","msg":"trace[1598307655] range","detail":"{range_begin:/registry/secrets/openstack/libvirt-libvirt-default-bgsvc-vnc; range_end:; response_count:1; response_revision:18848; }","duration":"153.987517ms","start":"2026-09-10T18:23:10.592846Z","end":"2026-09-10T18:23:10.746834Z","steps":["trace[1598307655] 'agreement among raft nodes before linearized reading' (duration: 153.762621ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:23:10.746874Z","caller":"traceutil/trace.go:171","msg":"trace[621702483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18848; }","duration":"121.228781ms","start":"2026-09-10T18:23:10.625617Z","end":"2026-09-10T18:23:10.746846Z","steps":["trace[621702483] 'agreement among raft nodes before linearized reading' (duration: 121.049015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:23:10.746916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.155533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/libvirt-libvirt-default-bgsvc-api\" ","response":"range_response_count:1 size:4285"} {"level":"info","ts":"2026-09-10T18:23:10.747032Z","caller":"traceutil/trace.go:171","msg":"trace[1587898428] range","detail":"{range_begin:/registry/secrets/openstack/libvirt-libvirt-default-bgsvc-api; range_end:; response_count:1; response_revision:18848; }","duration":"154.359998ms","start":"2026-09-10T18:23:10.592642Z","end":"2026-09-10T18:23:10.747002Z","steps":["trace[1587898428] 'agreement among raft nodes before linearized reading' (duration: 154.022078ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:23:57.305658Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a656331365382b34","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-10T18:23:57.315078Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-10T18:23:57.31704Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-10T18:24:25.539057Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17071} {"level":"info","ts":"2026-09-10T18:24:25.640523Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17071,"took":"95.815047ms","hash":4210534440,"current-db-size-bytes":56537088,"current-db-size":"56 MB","current-db-size-in-use-bytes":33697792,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-10T18:24:25.64059Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4210534440,"revision":17071,"compact-revision":14615} {"level":"warn","ts":"2026-09-10T18:29:07.870045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.869025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-10T18:29:07.870318Z","caller":"traceutil/trace.go:171","msg":"trace[743171988] transaction","detail":"{read_only:false; response_revision:21534; number_of_response:1; }","duration":"219.052113ms","start":"2026-09-10T18:29:07.651232Z","end":"2026-09-10T18:29:07.870284Z","steps":["trace[743171988] 'process raft request' (duration: 26.839507ms)","trace[743171988] 'compare' (duration: 191.69771ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T18:29:08.036481Z","caller":"traceutil/trace.go:171","msg":"trace[2007707416] linearizableReadLoop","detail":"{readStateIndex:22267; appliedIndex:22266; }","duration":"160.751976ms","start":"2026-09-10T18:29:07.875711Z","end":"2026-09-10T18:29:08.036463Z","steps":["trace[2007707416] 'read index received' (duration: 154.371093ms)","trace[2007707416] 'applied index is now lower than readState.Index' (duration: 6.379853ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T18:29:08.036715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.991964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-cfn-76dc47dfcb-dhr8r.18d408fb5ca092b6\" ","response":"range_response_count:1 size:905"} {"level":"info","ts":"2026-09-10T18:29:08.036759Z","caller":"traceutil/trace.go:171","msg":"trace[1690108199] range","detail":"{range_begin:/registry/events/openstack/heat-cfn-76dc47dfcb-dhr8r.18d408fb5ca092b6; range_end:; response_count:1; response_revision:21535; }","duration":"161.122809ms","start":"2026-09-10T18:29:07.875621Z","end":"2026-09-10T18:29:08.036744Z","steps":["trace[1690108199] 'agreement among raft nodes before linearized reading' (duration: 160.929542ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:29:08.036581Z","caller":"traceutil/trace.go:171","msg":"trace[110101650] transaction","detail":"{read_only:false; response_revision:21535; number_of_response:1; }","duration":"160.965753ms","start":"2026-09-10T18:29:07.875564Z","end":"2026-09-10T18:29:08.03653Z","steps":["trace[110101650] 'process raft request' (duration: 154.565749ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:29:08.058474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.583188ms","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-09-10T18:29:08.05856Z","caller":"traceutil/trace.go:171","msg":"trace[862595842] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21538; }","duration":"173.697911ms","start":"2026-09-10T18:29:07.884842Z","end":"2026-09-10T18:29:08.05854Z","steps":["trace[862595842] 'agreement among raft nodes before linearized reading' (duration: 173.450184ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:29:24.315028Z","caller":"traceutil/trace.go:171","msg":"trace[288992019] linearizableReadLoop","detail":"{readStateIndex:22353; appliedIndex:22352; }","duration":"203.535251ms","start":"2026-09-10T18:29:24.111464Z","end":"2026-09-10T18:29:24.314999Z","steps":["trace[288992019] 'read index received' (duration: 203.305335ms)","trace[288992019] 'applied index is now lower than readState.Index' (duration: 228.656µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T18:29:24.315072Z","caller":"traceutil/trace.go:171","msg":"trace[475734702] transaction","detail":"{read_only:false; response_revision:21618; number_of_response:1; }","duration":"312.525281ms","start":"2026-09-10T18:29:24.002517Z","end":"2026-09-10T18:29:24.315043Z","steps":["trace[475734702] 'process raft request' (duration: 312.248633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:29:24.315248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:29:24.002488Z","time spent":"312.635905ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T18:29:24.315355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.867231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-10T18:29:24.31545Z","caller":"traceutil/trace.go:171","msg":"trace[243997245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21618; }","duration":"204.037736ms","start":"2026-09-10T18:29:24.11139Z","end":"2026-09-10T18:29:24.315428Z","steps":["trace[243997245] 'agreement among raft nodes before linearized reading' (duration: 203.761128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:29:24.553132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.616901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-10T18:29:24.553408Z","caller":"traceutil/trace.go:171","msg":"trace[2055979017] transaction","detail":"{read_only:false; response_revision:21619; number_of_response:1; }","duration":"419.727606ms","start":"2026-09-10T18:29:24.133623Z","end":"2026-09-10T18:29:24.55335Z","steps":["trace[2055979017] 'process raft request' (duration: 220.789156ms)","trace[2055979017] 'compare' (duration: 198.454905ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T18:29:24.553544Z","caller":"traceutil/trace.go:171","msg":"trace[1956571429] transaction","detail":"{read_only:false; response_revision:21620; number_of_response:1; }","duration":"413.338841ms","start":"2026-09-10T18:29:24.140177Z","end":"2026-09-10T18:29:24.553516Z","steps":["trace[1956571429] 'process raft request' (duration: 413.075053ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:29:24.55358Z","caller":"traceutil/trace.go:171","msg":"trace[1393380085] transaction","detail":"{read_only:false; response_revision:21621; number_of_response:1; }","duration":"413.254639ms","start":"2026-09-10T18:29:24.140299Z","end":"2026-09-10T18:29:24.553554Z","steps":["trace[1393380085] 'process raft request' (duration: 413.050583ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:29:24.553611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:29:24.133587Z","time spent":"419.908711ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T18:29:24.553647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:29:24.140168Z","time spent":"413.427114ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T18:29:24.553794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:29:24.140272Z","time spent":"413.375733ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T18:29:24.714217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.794548ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-09-10T18:29:24.714478Z","caller":"traceutil/trace.go:171","msg":"trace[1999208681] linearizableReadLoop","detail":"{readStateIndex:22357; appliedIndex:22353; }","duration":"399.324964ms","start":"2026-09-10T18:29:24.315104Z","end":"2026-09-10T18:29:24.714429Z","steps":["trace[1999208681] 'read index received' (duration: 39.325457ms)","trace[1999208681] 'applied index is now lower than readState.Index' (duration: 359.996387ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T18:29:24.714724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.701684ms","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-09-10T18:29:24.714753Z","caller":"traceutil/trace.go:171","msg":"trace[1058773897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21621; }","duration":"570.789237ms","start":"2026-09-10T18:29:24.143955Z","end":"2026-09-10T18:29:24.714745Z","steps":["trace[1058773897] 'agreement among raft nodes before linearized reading' (duration: 570.613412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:29:24.714787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:29:24.143943Z","time spent":"570.837018ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-10T18:29:24.714994Z","caller":"traceutil/trace.go:171","msg":"trace[1556025400] transaction","detail":"{read_only:false; response_revision:21622; number_of_response:1; }","duration":"394.897589ms","start":"2026-09-10T18:29:24.320062Z","end":"2026-09-10T18:29:24.714959Z","steps":["trace[1556025400] 'process raft request' (duration: 394.280961ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:29:24.715319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:29:24.320029Z","time spent":"395.019973ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T18:29:24.963802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.794776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-10T18:29:24.96414Z","caller":"traceutil/trace.go:171","msg":"trace[240856405] transaction","detail":"{read_only:false; response_revision:21624; number_of_response:1; }","duration":"244.194889ms","start":"2026-09-10T18:29:24.719924Z","end":"2026-09-10T18:29:24.964118Z","steps":["trace[240856405] 'process raft request' (duration: 244.030124ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:29:24.964181Z","caller":"traceutil/trace.go:171","msg":"trace[150170610] linearizableReadLoop","detail":"{readStateIndex:22359; appliedIndex:22358; }","duration":"249.611324ms","start":"2026-09-10T18:29:24.714542Z","end":"2026-09-10T18:29:24.964153Z","steps":["trace[150170610] 'read index received' (duration: 114.428706ms)","trace[150170610] 'applied index is now lower than readState.Index' (duration: 135.179968ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T18:29:24.964229Z","caller":"traceutil/trace.go:171","msg":"trace[338172469] transaction","detail":"{read_only:false; response_revision:21623; number_of_response:1; }","duration":"581.236216ms","start":"2026-09-10T18:29:24.382962Z","end":"2026-09-10T18:29:24.964199Z","steps":["trace[338172469] 'process raft request' (duration: 445.898504ms)","trace[338172469] 'compare' (duration: 134.637061ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T18:29:24.964392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:29:24.382923Z","time spent":"581.38087ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T18:29:24.964449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.993864ms","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-09-10T18:29:24.964487Z","caller":"traceutil/trace.go:171","msg":"trace[1308340673] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21624; }","duration":"439.080976ms","start":"2026-09-10T18:29:24.525393Z","end":"2026-09-10T18:29:24.964473Z","steps":["trace[1308340673] 'agreement among raft nodes before linearized reading' (duration: 438.836698ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:29:24.964531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:29:24.525365Z","time spent":"439.157199ms","remote":"127.0.0.1:53004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-10T18:29:24.964556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.486733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-10T18:29:24.964593Z","caller":"traceutil/trace.go:171","msg":"trace[1678712767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21624; }","duration":"339.568865ms","start":"2026-09-10T18:29:24.625015Z","end":"2026-09-10T18:29:24.964584Z","steps":["trace[1678712767] 'agreement among raft nodes before linearized reading' (duration: 339.503713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:29:24.964621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T18:29:24.624988Z","time spent":"339.628467ms","remote":"127.0.0.1:54762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-10T18:29:25.316665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.740565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-10T18:29:25.316753Z","caller":"traceutil/trace.go:171","msg":"trace[129546436] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:21625; }","duration":"178.917091ms","start":"2026-09-10T18:29:25.137817Z","end":"2026-09-10T18:29:25.316734Z","steps":["trace[129546436] 'count revisions from in-memory index tree' (duration: 178.617231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:29:25.316896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.786757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-10T18:29:25.316958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.790525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-10T18:29:25.317004Z","caller":"traceutil/trace.go:171","msg":"trace[1904799917] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21625; }","duration":"246.966163ms","start":"2026-09-10T18:29:25.070009Z","end":"2026-09-10T18:29:25.316976Z","steps":["trace[1904799917] 'range keys from in-memory index tree' (duration: 246.621203ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:29:25.317032Z","caller":"traceutil/trace.go:171","msg":"trace[287440739] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:21625; }","duration":"239.916328ms","start":"2026-09-10T18:29:25.077102Z","end":"2026-09-10T18:29:25.317018Z","steps":["trace[287440739] 'count revisions from in-memory index tree' (duration: 239.680171ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:29:25.490833Z","caller":"traceutil/trace.go:171","msg":"trace[100242019] linearizableReadLoop","detail":"{readStateIndex:22362; appliedIndex:22361; }","duration":"140.537931ms","start":"2026-09-10T18:29:25.350261Z","end":"2026-09-10T18:29:25.490799Z","steps":["trace[100242019] 'read index received' (duration: 140.302434ms)","trace[100242019] 'applied index is now lower than readState.Index' (duration: 234.127µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T18:29:25.490951Z","caller":"traceutil/trace.go:171","msg":"trace[1011393254] transaction","detail":"{read_only:false; response_revision:21626; number_of_response:1; }","duration":"143.589095ms","start":"2026-09-10T18:29:25.34731Z","end":"2026-09-10T18:29:25.490899Z","steps":["trace[1011393254] 'process raft request' (duration: 143.248454ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:29:25.491234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.953684ms","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-09-10T18:29:25.491283Z","caller":"traceutil/trace.go:171","msg":"trace[674562939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21626; }","duration":"141.075998ms","start":"2026-09-10T18:29:25.350193Z","end":"2026-09-10T18:29:25.491269Z","steps":["trace[674562939] 'agreement among raft nodes before linearized reading' (duration: 140.869162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T18:29:25.491383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.035872ms","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-09-10T18:29:25.49143Z","caller":"traceutil/trace.go:171","msg":"trace[39119966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21626; }","duration":"131.167856ms","start":"2026-09-10T18:29:25.360251Z","end":"2026-09-10T18:29:25.491418Z","steps":["trace[39119966] 'agreement among raft nodes before linearized reading' (duration: 130.95236ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:29:25.545564Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19615} {"level":"info","ts":"2026-09-10T18:29:25.683513Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19615,"took":"133.246169ms","hash":1281846432,"current-db-size-bytes":56537088,"current-db-size":"56 MB","current-db-size-in-use-bytes":29663232,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-10T18:29:25.683586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1281846432,"revision":19615,"compact-revision":17071} {"level":"info","ts":"2026-09-10T18:33:44.236088Z","caller":"traceutil/trace.go:171","msg":"trace[1714697729] transaction","detail":"{read_only:false; response_revision:24223; number_of_response:1; }","duration":"200.637662ms","start":"2026-09-10T18:33:44.03542Z","end":"2026-09-10T18:33:44.236057Z","steps":["trace[1714697729] 'process raft request' (duration: 200.377414ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T18:34:25.552051Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21629} {"level":"info","ts":"2026-09-10T18:34:25.667314Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21629,"took":"111.26399ms","hash":4251204368,"current-db-size-bytes":56537088,"current-db-size":"56 MB","current-db-size-in-use-bytes":42287104,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-10T18:34:25.667388Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4251204368,"revision":21629,"compact-revision":19615} {"level":"info","ts":"2026-09-10T18:39:04.83243Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006c93.wal"} {"level":"info","ts":"2026-09-10T18:39:25.560934Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24639} {"level":"info","ts":"2026-09-10T18:39:25.721949Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24639,"took":"155.623372ms","hash":2071802633,"current-db-size-bytes":56537088,"current-db-size":"56 MB","current-db-size-in-use-bytes":30859264,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-10T18:39:25.722026Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2071802633,"revision":24639,"compact-revision":21629} {"level":"info","ts":"2026-09-10T18:43:54.45053Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a656331365382b34","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-10T18:43:54.45685Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-10T18:43:54.456947Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-10T18:44:25.573505Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27057} {"level":"info","ts":"2026-09-10T18:44:25.710175Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27057,"took":"130.569319ms","hash":734058983,"current-db-size-bytes":56537088,"current-db-size":"56 MB","current-db-size-in-use-bytes":29368320,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-10T18:44:25.710269Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":734058983,"revision":27057,"compact-revision":24639}