{"level":"warn","ts":"2026-10-16T14:22:14.806792Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T14:22:14.806932Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209: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.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-16T14:22:14.807025Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T14:22:14.807047Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-10-16T14:22:14.807076Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-16T14:22:14.80799Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]} {"level":"info","ts":"2026-10-16T14:22:14.808097Z","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.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-16T14:22:14.825951Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"16.305709ms"} {"level":"info","ts":"2026-10-16T14:22:14.839998Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-10-16T14:22:14.840131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T14:22:14.840167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-10-16T14:22:14.840186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T14:22:14.840196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-10-16T14:22:14.840254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-10-16T14:22:14.850321Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T14:22:14.852818Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T14:22:14.862503Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-16T14:22:14.869014Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T14:22:14.873569Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T14:22:14.87378Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-16T14:22:14.873938Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-16T14:22:14.873973Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-16T14:22:14.874404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-10-16T14:22:14.875123Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-10-16T14:22:14.876421Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-16T14:22:14.876585Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-10-16T14:22:14.876645Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-10-16T14:22:14.876774Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T14:22:14.876839Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T14:22:15.641527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T14:22:15.641608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T14:22:15.64165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-10-16T14:22:15.641671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-10-16T14:22:15.641681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-10-16T14:22:15.641698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-10-16T14:22:15.641719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-10-16T14:22:15.643136Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T14:22:15.643173Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T14:22:15.643485Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T14:22:15.643965Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T14:22:15.644147Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T14:22:15.644181Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T14:22:15.646907Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-10-16T14:22:15.646924Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T14:22:15.647073Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T14:22:15.647225Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T14:22:15.647281Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T14:22:29.345717Z","caller":"traceutil/trace.go:171","msg":"trace[1058395356] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"188.599445ms","start":"2026-10-16T14:22:29.157071Z","end":"2026-10-16T14:22:29.34567Z","steps":["trace[1058395356] 'process raft request' (duration: 188.212696ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:22:40.413047Z","caller":"traceutil/trace.go:171","msg":"trace[1578901949] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"228.706333ms","start":"2026-10-16T14:22:40.184319Z","end":"2026-10-16T14:22:40.413025Z","steps":["trace[1578901949] 'read index received' (duration: 228.507508ms)","trace[1578901949] 'applied index is now lower than readState.Index' (duration: 197.925µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:22:40.413163Z","caller":"traceutil/trace.go:171","msg":"trace[325753443] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"254.273721ms","start":"2026-10-16T14:22:40.158847Z","end":"2026-10-16T14:22:40.413121Z","steps":["trace[325753443] 'process raft request' (duration: 254.053376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:22:40.413179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.841906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-16T14:22:40.41339Z","caller":"traceutil/trace.go:171","msg":"trace[168185724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:436; }","duration":"229.101552ms","start":"2026-10-16T14:22:40.184275Z","end":"2026-10-16T14:22:40.413377Z","steps":["trace[168185724] 'agreement among raft nodes before linearized reading' (duration: 228.842486ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:22:42.617363Z","caller":"traceutil/trace.go:171","msg":"trace[298439362] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"131.905103ms","start":"2026-10-16T14:22:42.485377Z","end":"2026-10-16T14:22:42.617283Z","steps":["trace[298439362] 'process raft request' (duration: 99.892399ms)","trace[298439362] 'compare' (duration: 31.741688ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:22:42.672037Z","caller":"traceutil/trace.go:171","msg":"trace[1081225237] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"185.930555ms","start":"2026-10-16T14:22:42.486078Z","end":"2026-10-16T14:22:42.672009Z","steps":["trace[1081225237] 'process raft request' (duration: 185.793782ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:23:10.759573Z","caller":"traceutil/trace.go:171","msg":"trace[1252703712] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"154.039662ms","start":"2026-10-16T14:23:10.605463Z","end":"2026-10-16T14:23:10.759502Z","steps":["trace[1252703712] 'read index received' (duration: 148.557268ms)","trace[1252703712] 'applied index is now lower than readState.Index' (duration: 5.481074ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:23:10.759648Z","caller":"traceutil/trace.go:171","msg":"trace[803705333] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"164.933147ms","start":"2026-10-16T14:23:10.594656Z","end":"2026-10-16T14:23:10.759589Z","steps":["trace[803705333] 'process raft request' (duration: 159.75455ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:23:10.759845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.366969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-16T14:23:10.759901Z","caller":"traceutil/trace.go:171","msg":"trace[1422148491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"154.494902ms","start":"2026-10-16T14:23:10.605391Z","end":"2026-10-16T14:23:10.759886Z","steps":["trace[1422148491] 'agreement among raft nodes before linearized reading' (duration: 154.268746ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:23:10.961738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.398232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:23:10.961876Z","caller":"traceutil/trace.go:171","msg":"trace[1037935336] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"126.470879ms","start":"2026-10-16T14:23:10.835393Z","end":"2026-10-16T14:23:10.961864Z","steps":["trace[1037935336] 'process raft request' (duration: 126.429788ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:23:10.961923Z","caller":"traceutil/trace.go:171","msg":"trace[1948190948] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"188.303072ms","start":"2026-10-16T14:23:10.77358Z","end":"2026-10-16T14:23:10.961883Z","steps":["trace[1948190948] 'read index received' (duration: 63.345707ms)","trace[1948190948] 'applied index is now lower than readState.Index' (duration: 124.953985ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:23:10.961952Z","caller":"traceutil/trace.go:171","msg":"trace[815789077] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"197.768756ms","start":"2026-10-16T14:23:10.76415Z","end":"2026-10-16T14:23:10.961919Z","steps":["trace[815789077] 'process raft request' (duration: 72.713569ms)","trace[815789077] 'compare' (duration: 124.264279ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:23:10.962173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.574909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-16T14:23:10.962223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.223389ms","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-10-16T14:23:10.962258Z","caller":"traceutil/trace.go:171","msg":"trace[1809286904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:663; }","duration":"188.698191ms","start":"2026-10-16T14:23:10.773538Z","end":"2026-10-16T14:23:10.962236Z","steps":["trace[1809286904] 'agreement among raft nodes before linearized reading' (duration: 188.456036ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:23:10.962296Z","caller":"traceutil/trace.go:171","msg":"trace[530314672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:663; }","duration":"148.332932ms","start":"2026-10-16T14:23:10.813927Z","end":"2026-10-16T14:23:10.96226Z","steps":["trace[530314672] 'agreement among raft nodes before linearized reading' (duration: 148.131197ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:23:11.208802Z","caller":"traceutil/trace.go:171","msg":"trace[210644274] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"241.567125ms","start":"2026-10-16T14:23:10.967194Z","end":"2026-10-16T14:23:11.208761Z","steps":["trace[210644274] 'process raft request' (duration: 148.951757ms)","trace[210644274] 'compare' (duration: 92.446654ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:23:11.22478Z","caller":"traceutil/trace.go:171","msg":"trace[945835348] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:689; }","duration":"250.943656ms","start":"2026-10-16T14:23:10.973804Z","end":"2026-10-16T14:23:11.224748Z","steps":["trace[945835348] 'read index received' (duration: 142.389419ms)","trace[945835348] 'applied index is now lower than readState.Index' (duration: 108.553047ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:23:11.22497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.085579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:23:11.225121Z","caller":"traceutil/trace.go:171","msg":"trace[252904956] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:665; }","duration":"251.276513ms","start":"2026-10-16T14:23:10.973762Z","end":"2026-10-16T14:23:11.225038Z","steps":["trace[252904956] 'agreement among raft nodes before linearized reading' (duration: 251.097549ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:23:11.225117Z","caller":"traceutil/trace.go:171","msg":"trace[760048200] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"256.976832ms","start":"2026-10-16T14:23:10.967918Z","end":"2026-10-16T14:23:11.224895Z","steps":["trace[760048200] 'process raft request' (duration: 256.686486ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:23:11.883569Z","caller":"traceutil/trace.go:171","msg":"trace[1768128271] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"321.69411ms","start":"2026-10-16T14:23:11.561833Z","end":"2026-10-16T14:23:11.883527Z","steps":["trace[1768128271] 'read index received' (duration: 321.399184ms)","trace[1768128271] 'applied index is now lower than readState.Index' (duration: 292.576µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:23:11.883623Z","caller":"traceutil/trace.go:171","msg":"trace[996913373] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"601.873755ms","start":"2026-10-16T14:23:11.281712Z","end":"2026-10-16T14:23:11.883586Z","steps":["trace[996913373] 'process raft request' (duration: 601.467845ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:23:11.883873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.029698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-16T14:23:11.883879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:23:11.28168Z","time spent":"602.12102ms","remote":"127.0.0.1:35656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:23:11.883947Z","caller":"traceutil/trace.go:171","msg":"trace[1031011749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:666; }","duration":"322.15123ms","start":"2026-10-16T14:23:11.561783Z","end":"2026-10-16T14:23:11.883935Z","steps":["trace[1031011749] 'agreement among raft nodes before linearized reading' (duration: 321.877654ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:23:11.88398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:23:11.561765Z","time spent":"322.207451ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-16T14:23:11.892606Z","caller":"traceutil/trace.go:171","msg":"trace[908352803] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"218.969955ms","start":"2026-10-16T14:23:11.673605Z","end":"2026-10-16T14:23:11.892575Z","steps":["trace[908352803] 'process raft request' (duration: 218.407722ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:23:27.177531Z","caller":"traceutil/trace.go:171","msg":"trace[2073472707] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"118.558482ms","start":"2026-10-16T14:23:27.058916Z","end":"2026-10-16T14:23:27.177475Z","steps":["trace[2073472707] 'process raft request' (duration: 118.202384ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:23:56.77094Z","caller":"traceutil/trace.go:171","msg":"trace[1851492857] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"128.21017ms","start":"2026-10-16T14:23:56.642705Z","end":"2026-10-16T14:23:56.770915Z","steps":["trace[1851492857] 'process raft request' (duration: 128.044866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:45.176037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.493895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-76928d3c-2fd0-404c-8078-d4aee3a2fe83\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-16T14:24:45.176244Z","caller":"traceutil/trace.go:171","msg":"trace[1146258849] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-76928d3c-2fd0-404c-8078-d4aee3a2fe83; range_end:; response_count:1; response_revision:1906; }","duration":"427.707219ms","start":"2026-10-16T14:24:44.748509Z","end":"2026-10-16T14:24:45.176216Z","steps":["trace[1146258849] 'range keys from in-memory index tree' (duration: 427.28728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:45.176346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:24:44.748406Z","time spent":"427.920715ms","remote":"127.0.0.1:35656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-76928d3c-2fd0-404c-8078-d4aee3a2fe83\" "} {"level":"warn","ts":"2026-10-16T14:24:45.176707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.315925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:24:45.176871Z","caller":"traceutil/trace.go:171","msg":"trace[1036556468] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"390.783287ms","start":"2026-10-16T14:24:44.786061Z","end":"2026-10-16T14:24:45.176844Z","steps":["trace[1036556468] 'process raft request' (duration: 245.222016ms)","trace[1036556468] 'compare' (duration: 144.632551ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:24:45.176967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:24:44.786025Z","time spent":"390.89646ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:24:45.192526Z","caller":"traceutil/trace.go:171","msg":"trace[2116826825] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"126.246515ms","start":"2026-10-16T14:24:45.066244Z","end":"2026-10-16T14:24:45.192491Z","steps":["trace[2116826825] 'process raft request' (duration: 125.983959ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:24:45.262448Z","caller":"traceutil/trace.go:171","msg":"trace[490140683] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"176.681422ms","start":"2026-10-16T14:24:45.08574Z","end":"2026-10-16T14:24:45.262422Z","steps":["trace[490140683] 'process raft request' (duration: 176.269703ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:24:45.262595Z","caller":"traceutil/trace.go:171","msg":"trace[1422531464] linearizableReadLoop","detail":"{readStateIndex:1982; appliedIndex:1980; }","duration":"136.545517ms","start":"2026-10-16T14:24:45.126029Z","end":"2026-10-16T14:24:45.262574Z","steps":["trace[1422531464] 'read index received' (duration: 66.221803ms)","trace[1422531464] 'applied index is now lower than readState.Index' (duration: 70.322514ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:24:45.262819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.781242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:24:45.262887Z","caller":"traceutil/trace.go:171","msg":"trace[1927168403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1910; }","duration":"136.907105ms","start":"2026-10-16T14:24:45.125966Z","end":"2026-10-16T14:24:45.262874Z","steps":["trace[1927168403] 'agreement among raft nodes before linearized reading' (duration: 136.798673ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:45.589091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.243758ms","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-10-16T14:24:45.589243Z","caller":"traceutil/trace.go:171","msg":"trace[11769069] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1910; }","duration":"265.401502ms","start":"2026-10-16T14:24:45.323773Z","end":"2026-10-16T14:24:45.589175Z","steps":["trace[11769069] 'range keys from in-memory index tree' (duration: 265.045754ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:24:51.351586Z","caller":"traceutil/trace.go:171","msg":"trace[162596512] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"150.571374ms","start":"2026-10-16T14:24:51.200991Z","end":"2026-10-16T14:24:51.351562Z","steps":["trace[162596512] 'process raft request' (duration: 150.441621ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:24:51.353006Z","caller":"traceutil/trace.go:171","msg":"trace[67052258] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"138.463731ms","start":"2026-10-16T14:24:51.214532Z","end":"2026-10-16T14:24:51.352996Z","steps":["trace[67052258] 'process raft request' (duration: 138.41443ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:24:58.497121Z","caller":"traceutil/trace.go:171","msg":"trace[150022669] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"195.027165ms","start":"2026-10-16T14:24:58.302072Z","end":"2026-10-16T14:24:58.4971Z","steps":["trace[150022669] 'process raft request' (duration: 194.884372ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:24:58.630509Z","caller":"traceutil/trace.go:171","msg":"trace[592874042] linearizableReadLoop","detail":"{readStateIndex:2093; appliedIndex:2092; }","duration":"127.126365ms","start":"2026-10-16T14:24:58.503351Z","end":"2026-10-16T14:24:58.630478Z","steps":["trace[592874042] 'read index received' (duration: 33.627828ms)","trace[592874042] 'applied index is now lower than readState.Index' (duration: 93.497267ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:24:58.63063Z","caller":"traceutil/trace.go:171","msg":"trace[1520403528] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"205.692986ms","start":"2026-10-16T14:24:58.424894Z","end":"2026-10-16T14:24:58.630587Z","steps":["trace[1520403528] 'process raft request' (duration: 112.033185ms)","trace[1520403528] 'compare' (duration: 93.20364ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:24:58.630777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.409901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-wmdlr\" ","response":"range_response_count:1 size:5855"} {"level":"info","ts":"2026-10-16T14:24:58.631014Z","caller":"traceutil/trace.go:171","msg":"trace[457537812] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-wmdlr; range_end:; response_count:1; response_revision:2018; }","duration":"127.686228ms","start":"2026-10-16T14:24:58.503302Z","end":"2026-10-16T14:24:58.630989Z","steps":["trace[457537812] 'agreement among raft nodes before linearized reading' (duration: 127.308859ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:24:58.715641Z","caller":"traceutil/trace.go:171","msg":"trace[1353324419] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"210.284299ms","start":"2026-10-16T14:24:58.505331Z","end":"2026-10-16T14:24:58.715616Z","steps":["trace[1353324419] 'process raft request' (duration: 210.013133ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:24:58.715747Z","caller":"traceutil/trace.go:171","msg":"trace[1064285193] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"209.467451ms","start":"2026-10-16T14:24:58.506205Z","end":"2026-10-16T14:24:58.715672Z","steps":["trace[1064285193] 'process raft request' (duration: 209.310828ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:24:58.715823Z","caller":"traceutil/trace.go:171","msg":"trace[1499985598] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"209.206435ms","start":"2026-10-16T14:24:58.506608Z","end":"2026-10-16T14:24:58.715815Z","steps":["trace[1499985598] 'process raft request' (duration: 208.96924ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:24:58.715847Z","caller":"traceutil/trace.go:171","msg":"trace[999805263] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"207.480546ms","start":"2026-10-16T14:24:58.508346Z","end":"2026-10-16T14:24:58.715826Z","steps":["trace[999805263] 'process raft request' (duration: 207.400844ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:58.994411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.052996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:24:58.994543Z","caller":"traceutil/trace.go:171","msg":"trace[1408628648] linearizableReadLoop","detail":"{readStateIndex:2098; appliedIndex:2093; }","duration":"328.614936ms","start":"2026-10-16T14:24:58.665909Z","end":"2026-10-16T14:24:58.994524Z","steps":["trace[1408628648] 'read index received' (duration: 49.328171ms)","trace[1408628648] 'applied index is now lower than readState.Index' (duration: 279.285535ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:24:58.994636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.721028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:24:58.994627Z","caller":"traceutil/trace.go:171","msg":"trace[1111817769] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"355.754048ms","start":"2026-10-16T14:24:58.638825Z","end":"2026-10-16T14:24:58.994579Z","steps":["trace[1111817769] 'process raft request' (duration: 239.430677ms)","trace[1111817769] 'compare' (duration: 115.854551ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:24:58.994696Z","caller":"traceutil/trace.go:171","msg":"trace[507009303] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2023; }","duration":"328.78575ms","start":"2026-10-16T14:24:58.665876Z","end":"2026-10-16T14:24:58.994662Z","steps":["trace[507009303] 'agreement among raft nodes before linearized reading' (duration: 328.709718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:58.994729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:24:58.665862Z","time spent":"328.859321ms","remote":"127.0.0.1:35726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":15,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-10-16T14:24:58.994828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:24:58.638802Z","time spent":"355.918241ms","remote":"127.0.0.1:35656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:24:59.021028Z","caller":"traceutil/trace.go:171","msg":"trace[1033772512] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"298.035897ms","start":"2026-10-16T14:24:58.722955Z","end":"2026-10-16T14:24:59.020991Z","steps":["trace[1033772512] 'process raft request' (duration: 297.812782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:59.021107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.298725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-10-16T14:24:59.021143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.214955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-16T14:24:59.021183Z","caller":"traceutil/trace.go:171","msg":"trace[2029419643] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2024; }","duration":"342.422218ms","start":"2026-10-16T14:24:58.678739Z","end":"2026-10-16T14:24:59.021162Z","steps":["trace[2029419643] 'agreement among raft nodes before linearized reading' (duration: 342.183482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:59.021233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:24:58.678719Z","time spent":"342.505219ms","remote":"127.0.0.1:55442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-10-16T14:24:59.021193Z","caller":"traceutil/trace.go:171","msg":"trace[561376122] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2024; }","duration":"327.312557ms","start":"2026-10-16T14:24:58.693868Z","end":"2026-10-16T14:24:59.02118Z","steps":["trace[561376122] 'agreement among raft nodes before linearized reading' (duration: 327.134943ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:59.021397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:24:58.693841Z","time spent":"327.505922ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-16T14:24:59.204097Z","caller":"traceutil/trace.go:171","msg":"trace[470686517] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"203.752912ms","start":"2026-10-16T14:24:59.000317Z","end":"2026-10-16T14:24:59.20407Z","steps":["trace[470686517] 'process raft request' (duration: 107.195426ms)","trace[470686517] 'compare' (duration: 96.329481ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:24:59.204479Z","caller":"traceutil/trace.go:171","msg":"trace[653005969] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"202.127616ms","start":"2026-10-16T14:24:59.002329Z","end":"2026-10-16T14:24:59.204457Z","steps":["trace[653005969] 'process raft request' (duration: 201.676576ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:24:59.204618Z","caller":"traceutil/trace.go:171","msg":"trace[407212466] linearizableReadLoop","detail":"{readStateIndex:2102; appliedIndex:2099; }","duration":"113.229572ms","start":"2026-10-16T14:24:59.091369Z","end":"2026-10-16T14:24:59.204598Z","steps":["trace[407212466] 'read index received' (duration: 16.050831ms)","trace[407212466] 'applied index is now lower than readState.Index' (duration: 97.177881ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:24:59.204718Z","caller":"traceutil/trace.go:171","msg":"trace[1352424951] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"179.017054ms","start":"2026-10-16T14:24:59.025693Z","end":"2026-10-16T14:24:59.20471Z","steps":["trace[1352424951] 'process raft request' (duration: 178.671517ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:59.204889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.537599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-16T14:24:59.204911Z","caller":"traceutil/trace.go:171","msg":"trace[1302375944] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:2027; }","duration":"113.682252ms","start":"2026-10-16T14:24:59.091222Z","end":"2026-10-16T14:24:59.204905Z","steps":["trace[1302375944] 'agreement among raft nodes before linearized reading' (duration: 113.576119ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:25:35.498839Z","caller":"traceutil/trace.go:171","msg":"trace[212675258] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"114.0771ms","start":"2026-10-16T14:25:35.38474Z","end":"2026-10-16T14:25:35.498818Z","steps":["trace[212675258] 'process raft request' (duration: 113.934597ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:25:41.692816Z","caller":"traceutil/trace.go:171","msg":"trace[432409495] linearizableReadLoop","detail":"{readStateIndex:2346; appliedIndex:2345; }","duration":"165.989795ms","start":"2026-10-16T14:25:41.526798Z","end":"2026-10-16T14:25:41.692787Z","steps":["trace[432409495] 'read index received' (duration: 165.812451ms)","trace[432409495] 'applied index is now lower than readState.Index' (duration: 176.154µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:25:41.692871Z","caller":"traceutil/trace.go:171","msg":"trace[526849192] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"166.252601ms","start":"2026-10-16T14:25:41.526583Z","end":"2026-10-16T14:25:41.692836Z","steps":["trace[526849192] 'process raft request' (duration: 166.029106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:25:41.693032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:25:41.693377Z","caller":"traceutil/trace.go:171","msg":"trace[1492462275] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2261; }","duration":"166.575308ms","start":"2026-10-16T14:25:41.526742Z","end":"2026-10-16T14:25:41.693317Z","steps":["trace[1492462275] 'agreement among raft nodes before linearized reading' (duration: 166.157209ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:25:42.097209Z","caller":"traceutil/trace.go:171","msg":"trace[674538392] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"485.440274ms","start":"2026-10-16T14:25:41.611737Z","end":"2026-10-16T14:25:42.097178Z","steps":["trace[674538392] 'process raft request' (duration: 485.22503ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:25:42.0974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:25:41.611718Z","time spent":"485.587767ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:25:42.097515Z","caller":"traceutil/trace.go:171","msg":"trace[890729930] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"481.859053ms","start":"2026-10-16T14:25:41.615629Z","end":"2026-10-16T14:25:42.097488Z","steps":["trace[890729930] 'process raft request' (duration: 481.510546ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:25:42.097505Z","caller":"traceutil/trace.go:171","msg":"trace[1006348216] linearizableReadLoop","detail":"{readStateIndex:2348; appliedIndex:2346; }","duration":"404.405765ms","start":"2026-10-16T14:25:41.693032Z","end":"2026-10-16T14:25:42.097438Z","steps":["trace[1006348216] 'read index received' (duration: 403.925905ms)","trace[1006348216] 'applied index is now lower than readState.Index' (duration: 478.09µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:25:42.097619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:25:41.615603Z","time spent":"481.969606ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:25:42.0978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.635409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:25:42.097862Z","caller":"traceutil/trace.go:171","msg":"trace[909172102] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2263; }","duration":"489.870284ms","start":"2026-10-16T14:25:41.607977Z","end":"2026-10-16T14:25:42.097847Z","steps":["trace[909172102] 'agreement among raft nodes before linearized reading' (duration: 489.639038ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:25:42.097925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:25:41.607951Z","time spent":"489.936455ms","remote":"127.0.0.1:55442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-16T14:25:42.412759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.991209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:25:42.412887Z","caller":"traceutil/trace.go:171","msg":"trace[2082193655] linearizableReadLoop","detail":"{readStateIndex:2349; appliedIndex:2348; }","duration":"315.214794ms","start":"2026-10-16T14:25:42.097633Z","end":"2026-10-16T14:25:42.412848Z","steps":["trace[2082193655] 'read index received' (duration: 134.979619ms)","trace[2082193655] 'applied index is now lower than readState.Index' (duration: 180.233355ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:25:42.413167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.320429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-16T14:25:42.413185Z","caller":"traceutil/trace.go:171","msg":"trace[1575437586] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"526.291011ms","start":"2026-10-16T14:25:41.886868Z","end":"2026-10-16T14:25:42.413159Z","steps":["trace[1575437586] 'process raft request' (duration: 345.80483ms)","trace[1575437586] 'compare' (duration: 179.815026ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:25:42.413275Z","caller":"traceutil/trace.go:171","msg":"trace[1418798826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2264; }","duration":"357.470722ms","start":"2026-10-16T14:25:42.055758Z","end":"2026-10-16T14:25:42.413229Z","steps":["trace[1418798826] 'agreement among raft nodes before linearized reading' (duration: 357.265158ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:25:42.413297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.623059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-16T14:25:42.413331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:25:42.055737Z","time spent":"357.571824ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-16T14:25:42.413346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:25:41.886845Z","time spent":"526.434394ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:25:42.413402Z","caller":"traceutil/trace.go:171","msg":"trace[761564374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2264; }","duration":"383.766382ms","start":"2026-10-16T14:25:42.029603Z","end":"2026-10-16T14:25:42.413369Z","steps":["trace[761564374] 'agreement among raft nodes before linearized reading' (duration: 383.512867ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:25:42.413419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.268779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-10-16T14:25:42.41346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:25:42.02958Z","time spent":"383.868715ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-16T14:25:42.413484Z","caller":"traceutil/trace.go:171","msg":"trace[387530058] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2264; }","duration":"112.382832ms","start":"2026-10-16T14:25:42.301082Z","end":"2026-10-16T14:25:42.413465Z","steps":["trace[387530058] 'agreement among raft nodes before linearized reading' (duration: 112.106565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:25:42.413188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.751798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:25:42.413553Z","caller":"traceutil/trace.go:171","msg":"trace[2108196206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2264; }","duration":"288.157237ms","start":"2026-10-16T14:25:42.125385Z","end":"2026-10-16T14:25:42.413542Z","steps":["trace[2108196206] 'agreement among raft nodes before linearized reading' (duration: 287.741538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:25:42.413693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.090442ms","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-10-16T14:25:42.413768Z","caller":"traceutil/trace.go:171","msg":"trace[888613664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2264; }","duration":"356.222924ms","start":"2026-10-16T14:25:42.057527Z","end":"2026-10-16T14:25:42.413749Z","steps":["trace[888613664] 'agreement among raft nodes before linearized reading' (duration: 355.634131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:25:42.41381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:25:42.057506Z","time spent":"356.295616ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-16T14:25:42.414072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.921545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-10-16T14:25:42.414139Z","caller":"traceutil/trace.go:171","msg":"trace[658925208] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2264; }","duration":"363.013597ms","start":"2026-10-16T14:25:42.051108Z","end":"2026-10-16T14:25:42.414122Z","steps":["trace[658925208] 'agreement among raft nodes before linearized reading' (duration: 361.859331ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:25:42.414191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:25:42.051097Z","time spent":"363.080759ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-16T14:26:16.034492Z","caller":"traceutil/trace.go:171","msg":"trace[1408129036] transaction","detail":"{read_only:false; response_revision:2623; number_of_response:1; }","duration":"139.021938ms","start":"2026-10-16T14:26:15.895445Z","end":"2026-10-16T14:26:16.034467Z","steps":["trace[1408129036] 'process raft request' (duration: 138.865454ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:26:49.26183Z","caller":"traceutil/trace.go:171","msg":"trace[1589044367] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"440.317482ms","start":"2026-10-16T14:26:48.821496Z","end":"2026-10-16T14:26:49.261813Z","steps":["trace[1589044367] 'process raft request' (duration: 440.21069ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:26:49.261932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:26:48.821475Z","time spent":"440.407323ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:26:49.393099Z","caller":"traceutil/trace.go:171","msg":"trace[1129023345] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"567.833931ms","start":"2026-10-16T14:26:48.825247Z","end":"2026-10-16T14:26:49.393081Z","steps":["trace[1129023345] 'process raft request' (duration: 491.629573ms)","trace[1129023345] 'compare' (duration: 76.097056ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:26:49.39329Z","caller":"traceutil/trace.go:171","msg":"trace[1382566929] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"566.951712ms","start":"2026-10-16T14:26:48.82632Z","end":"2026-10-16T14:26:49.393272Z","steps":["trace[1382566929] 'process raft request' (duration: 566.737897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:26:49.393399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:26:48.826306Z","time spent":"567.035833ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:26:49.39343Z","caller":"traceutil/trace.go:171","msg":"trace[257336416] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"300.425005ms","start":"2026-10-16T14:26:49.092998Z","end":"2026-10-16T14:26:49.393423Z","steps":["trace[257336416] 'process raft request' (duration: 300.174269ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:26:49.39329Z","caller":"traceutil/trace.go:171","msg":"trace[643226737] linearizableReadLoop","detail":"{readStateIndex:2931; appliedIndex:2929; }","duration":"566.951962ms","start":"2026-10-16T14:26:48.826322Z","end":"2026-10-16T14:26:49.393274Z","steps":["trace[643226737] 'read index received' (duration: 435.408172ms)","trace[643226737] 'applied index is now lower than readState.Index' (duration: 131.5431ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:26:49.393468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:26:49.092975Z","time spent":"300.470017ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:26:49.393457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:26:48.82522Z","time spent":"567.932604ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:26:49.393406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.090645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-10-16T14:26:49.393542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.40024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:26:49.393564Z","caller":"traceutil/trace.go:171","msg":"trace[63619111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2826; }","duration":"269.452151ms","start":"2026-10-16T14:26:49.124106Z","end":"2026-10-16T14:26:49.393558Z","steps":["trace[63619111] 'agreement among raft nodes before linearized reading' (duration: 269.41766ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:26:49.393584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.726404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-16T14:26:49.39361Z","caller":"traceutil/trace.go:171","msg":"trace[240444439] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:2826; }","duration":"297.785906ms","start":"2026-10-16T14:26:49.095818Z","end":"2026-10-16T14:26:49.393603Z","steps":["trace[240444439] 'agreement among raft nodes before linearized reading' (duration: 297.697074ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:26:49.393589Z","caller":"traceutil/trace.go:171","msg":"trace[1043903901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2826; }","duration":"567.277929ms","start":"2026-10-16T14:26:48.826284Z","end":"2026-10-16T14:26:49.393561Z","steps":["trace[1043903901] 'agreement among raft nodes before linearized reading' (duration: 567.039514ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:26:49.393678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:26:48.826266Z","time spent":"567.380831ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-16T14:26:54.495871Z","caller":"traceutil/trace.go:171","msg":"trace[42675743] transaction","detail":"{read_only:false; response_revision:2855; number_of_response:1; }","duration":"102.398996ms","start":"2026-10-16T14:26:54.393442Z","end":"2026-10-16T14:26:54.495841Z","steps":["trace[42675743] 'process raft request' (duration: 102.166561ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:29:56.368834Z","caller":"traceutil/trace.go:171","msg":"trace[1515242895] linearizableReadLoop","detail":"{readStateIndex:4165; appliedIndex:4164; }","duration":"156.56379ms","start":"2026-10-16T14:29:56.212233Z","end":"2026-10-16T14:29:56.368797Z","steps":["trace[1515242895] 'read index received' (duration: 156.234513ms)","trace[1515242895] 'applied index is now lower than readState.Index' (duration: 327.267µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:29:56.368965Z","caller":"traceutil/trace.go:171","msg":"trace[2008267368] transaction","detail":"{read_only:false; response_revision:4013; number_of_response:1; }","duration":"181.053229ms","start":"2026-10-16T14:29:56.18787Z","end":"2026-10-16T14:29:56.368923Z","steps":["trace[2008267368] 'process raft request' (duration: 180.710271ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:29:56.369128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.825476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:29:56.36918Z","caller":"traceutil/trace.go:171","msg":"trace[1836094520] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:4013; }","duration":"156.980359ms","start":"2026-10-16T14:29:56.212186Z","end":"2026-10-16T14:29:56.369167Z","steps":["trace[1836094520] 'agreement among raft nodes before linearized reading' (duration: 156.823906ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:29:56.369352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.529079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-16T14:29:56.369432Z","caller":"traceutil/trace.go:171","msg":"trace[1534710280] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4013; }","duration":"106.690592ms","start":"2026-10-16T14:29:56.262726Z","end":"2026-10-16T14:29:56.369416Z","steps":["trace[1534710280] 'agreement among raft nodes before linearized reading' (duration: 106.455687ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:21.092526Z","caller":"traceutil/trace.go:171","msg":"trace[363191023] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"147.409953ms","start":"2026-10-16T14:31:20.945069Z","end":"2026-10-16T14:31:21.092479Z","steps":["trace[363191023] 'process raft request' (duration: 147.27179ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:21.135796Z","caller":"traceutil/trace.go:171","msg":"trace[1742319454] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"190.392406ms","start":"2026-10-16T14:31:20.945374Z","end":"2026-10-16T14:31:21.135766Z","steps":["trace[1742319454] 'process raft request' (duration: 190.175671ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:21.135891Z","caller":"traceutil/trace.go:171","msg":"trace[1367585629] linearizableReadLoop","detail":"{readStateIndex:5241; appliedIndex:5235; }","duration":"187.830908ms","start":"2026-10-16T14:31:20.948037Z","end":"2026-10-16T14:31:21.135868Z","steps":["trace[1367585629] 'read index received' (duration: 144.346784ms)","trace[1367585629] 'applied index is now lower than readState.Index' (duration: 43.483324ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:31:21.135907Z","caller":"traceutil/trace.go:171","msg":"trace[136670332] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"189.220029ms","start":"2026-10-16T14:31:20.946638Z","end":"2026-10-16T14:31:21.135858Z","steps":["trace[136670332] 'process raft request' (duration: 189.084776ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:21.135933Z","caller":"traceutil/trace.go:171","msg":"trace[200511747] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"189.028885ms","start":"2026-10-16T14:31:20.946848Z","end":"2026-10-16T14:31:21.135877Z","steps":["trace[200511747] 'process raft request' (duration: 188.902222ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:21.135981Z","caller":"traceutil/trace.go:171","msg":"trace[1322460781] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"188.755849ms","start":"2026-10-16T14:31:20.947215Z","end":"2026-10-16T14:31:21.135971Z","steps":["trace[1322460781] 'process raft request' (duration: 188.563905ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:21.136219Z","caller":"traceutil/trace.go:171","msg":"trace[23247558] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"183.088512ms","start":"2026-10-16T14:31:20.95309Z","end":"2026-10-16T14:31:21.136178Z","steps":["trace[23247558] 'process raft request' (duration: 182.719194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:21.136231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.181906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"warn","ts":"2026-10-16T14:31:21.13627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.127808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-16T14:31:21.136307Z","caller":"traceutil/trace.go:171","msg":"trace[229703344] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5051; }","duration":"188.286388ms","start":"2026-10-16T14:31:20.948002Z","end":"2026-10-16T14:31:21.136288Z","steps":["trace[229703344] 'agreement among raft nodes before linearized reading' (duration: 188.047363ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:21.13618Z","caller":"traceutil/trace.go:171","msg":"trace[1249933932] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"190.6066ms","start":"2026-10-16T14:31:20.945538Z","end":"2026-10-16T14:31:21.136145Z","steps":["trace[1249933932] 'process raft request' (duration: 190.129269ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:21.136351Z","caller":"traceutil/trace.go:171","msg":"trace[187121917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5051; }","duration":"102.270321ms","start":"2026-10-16T14:31:21.034062Z","end":"2026-10-16T14:31:21.136332Z","steps":["trace[187121917] 'agreement among raft nodes before linearized reading' (duration: 102.025256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:21.13648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.990985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-16T14:31:21.136533Z","caller":"traceutil/trace.go:171","msg":"trace[100690610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5051; }","duration":"102.084366ms","start":"2026-10-16T14:31:21.034433Z","end":"2026-10-16T14:31:21.136518Z","steps":["trace[100690610] 'agreement among raft nodes before linearized reading' (duration: 101.850591ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:21.242063Z","caller":"traceutil/trace.go:171","msg":"trace[673115027] transaction","detail":"{read_only:false; response_revision:5053; number_of_response:1; }","duration":"100.43197ms","start":"2026-10-16T14:31:21.141569Z","end":"2026-10-16T14:31:21.242001Z","steps":["trace[673115027] 'process raft request' (duration: 99.818846ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:22.391539Z","caller":"traceutil/trace.go:171","msg":"trace[362486894] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"102.046556ms","start":"2026-10-16T14:31:22.289463Z","end":"2026-10-16T14:31:22.39151Z","steps":["trace[362486894] 'process raft request' (duration: 101.843281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:25.66167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.87083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8z99x\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-16T14:31:25.661777Z","caller":"traceutil/trace.go:171","msg":"trace[1146672253] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8z99x; range_end:; response_count:1; response_revision:5222; }","duration":"155.023654ms","start":"2026-10-16T14:31:25.506729Z","end":"2026-10-16T14:31:25.661753Z","steps":["trace[1146672253] 'range keys from in-memory index tree' (duration: 154.706226ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:25.661791Z","caller":"traceutil/trace.go:171","msg":"trace[856842166] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"108.223654ms","start":"2026-10-16T14:31:25.553549Z","end":"2026-10-16T14:31:25.661773Z","steps":["trace[856842166] 'process raft request' (duration: 99.061669ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:25.66167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.571317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-10-16T14:31:25.661948Z","caller":"traceutil/trace.go:171","msg":"trace[1789375299] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5222; }","duration":"139.949986ms","start":"2026-10-16T14:31:25.521988Z","end":"2026-10-16T14:31:25.661938Z","steps":["trace[1789375299] 'range keys from in-memory index tree' (duration: 139.431154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:25.661932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.52738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786936"} {"level":"info","ts":"2026-10-16T14:31:25.662013Z","caller":"traceutil/trace.go:171","msg":"trace[1640331096] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5222; }","duration":"124.656543ms","start":"2026-10-16T14:31:25.537336Z","end":"2026-10-16T14:31:25.661993Z","steps":["trace[1640331096] 'range keys from in-memory index tree' (duration: 124.0898ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:25.896794Z","caller":"traceutil/trace.go:171","msg":"trace[1759166909] linearizableReadLoop","detail":"{readStateIndex:5421; appliedIndex:5419; }","duration":"272.401303ms","start":"2026-10-16T14:31:25.624359Z","end":"2026-10-16T14:31:25.896761Z","steps":["trace[1759166909] 'read index received' (duration: 28.225152ms)","trace[1759166909] 'applied index is now lower than readState.Index' (duration: 244.175211ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:31:25.896897Z","caller":"traceutil/trace.go:171","msg":"trace[1290715149] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"335.433035ms","start":"2026-10-16T14:31:25.561423Z","end":"2026-10-16T14:31:25.896856Z","steps":["trace[1290715149] 'process raft request' (duration: 335.2081ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:25.897008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.614777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b09bbcb5-43e8-4a6d-a2bf-db8abdf2fcdb\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-16T14:31:25.897079Z","caller":"traceutil/trace.go:171","msg":"trace[1184982984] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b09bbcb5-43e8-4a6d-a2bf-db8abdf2fcdb; range_end:; response_count:1; response_revision:5224; }","duration":"272.753441ms","start":"2026-10-16T14:31:25.624311Z","end":"2026-10-16T14:31:25.897065Z","steps":["trace[1184982984] 'agreement among raft nodes before linearized reading' (duration: 272.552996ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:25.897114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:31:25.561399Z","time spent":"335.579758ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:31:25.92624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.497435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62984"} {"level":"warn","ts":"2026-10-16T14:31:25.926265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.245368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-9qlgv\" ","response":"range_response_count:1 size:5852"} {"level":"warn","ts":"2026-10-16T14:31:25.926263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.542454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b09bbcb5-43e8-4a6d-a2bf-db8abdf2fcdb\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-16T14:31:25.926319Z","caller":"traceutil/trace.go:171","msg":"trace[82624727] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5225; }","duration":"255.631047ms","start":"2026-10-16T14:31:25.670673Z","end":"2026-10-16T14:31:25.926305Z","steps":["trace[82624727] 'agreement among raft nodes before linearized reading' (duration: 255.31857ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:25.926343Z","caller":"traceutil/trace.go:171","msg":"trace[517804933] range","detail":"{range_begin:/registry/pods/monitoring/vector-9qlgv; range_end:; response_count:1; response_revision:5225; }","duration":"263.363501ms","start":"2026-10-16T14:31:25.662962Z","end":"2026-10-16T14:31:25.926326Z","steps":["trace[517804933] 'agreement among raft nodes before linearized reading' (duration: 263.113685ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:25.926365Z","caller":"traceutil/trace.go:171","msg":"trace[1188535385] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b09bbcb5-43e8-4a6d-a2bf-db8abdf2fcdb; range_end:; response_count:1; response_revision:5225; }","duration":"163.661397ms","start":"2026-10-16T14:31:25.76268Z","end":"2026-10-16T14:31:25.926342Z","steps":["trace[1188535385] 'agreement among raft nodes before linearized reading' (duration: 163.428372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:25.926282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.919258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-kqnh6-tls\" ","response":"range_response_count:1 size:2184"} {"level":"info","ts":"2026-10-16T14:31:25.926441Z","caller":"traceutil/trace.go:171","msg":"trace[1825534316] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-kqnh6-tls; range_end:; response_count:1; response_revision:5225; }","duration":"237.109653ms","start":"2026-10-16T14:31:25.689318Z","end":"2026-10-16T14:31:25.926427Z","steps":["trace[1825534316] 'agreement among raft nodes before linearized reading' (duration: 236.857437ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:28.050494Z","caller":"traceutil/trace.go:171","msg":"trace[378780836] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"118.576527ms","start":"2026-10-16T14:31:27.931894Z","end":"2026-10-16T14:31:28.05047Z","steps":["trace[378780836] 'process raft request' (duration: 118.522525ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:28.050556Z","caller":"traceutil/trace.go:171","msg":"trace[614487762] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"148.498237ms","start":"2026-10-16T14:31:27.902037Z","end":"2026-10-16T14:31:28.050536Z","steps":["trace[614487762] 'process raft request' (duration: 51.662317ms)","trace[614487762] 'compare' (duration: 96.456362ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:31:28.050642Z","caller":"traceutil/trace.go:171","msg":"trace[585866300] linearizableReadLoop","detail":"{readStateIndex:5466; appliedIndex:5465; }","duration":"146.770439ms","start":"2026-10-16T14:31:27.903839Z","end":"2026-10-16T14:31:28.050609Z","steps":["trace[585866300] 'read index received' (duration: 49.856147ms)","trace[585866300] 'applied index is now lower than readState.Index' (duration: 96.911092ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:31:28.050858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.995644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b09bbcb5-43e8-4a6d-a2bf-db8abdf2fcdb\" ","response":"range_response_count:1 size:3904"} {"level":"info","ts":"2026-10-16T14:31:28.050912Z","caller":"traceutil/trace.go:171","msg":"trace[1305800752] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b09bbcb5-43e8-4a6d-a2bf-db8abdf2fcdb; range_end:; response_count:1; response_revision:5267; }","duration":"147.066085ms","start":"2026-10-16T14:31:27.903833Z","end":"2026-10-16T14:31:28.050899Z","steps":["trace[1305800752] 'agreement among raft nodes before linearized reading' (duration: 146.901962ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:28.050958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.814351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8z99x\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-10-16T14:31:28.050865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.240785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-16T14:31:28.051001Z","caller":"traceutil/trace.go:171","msg":"trace[1120857849] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8z99x; range_end:; response_count:1; response_revision:5267; }","duration":"118.877143ms","start":"2026-10-16T14:31:27.932113Z","end":"2026-10-16T14:31:28.05099Z","steps":["trace[1120857849] 'agreement among raft nodes before linearized reading' (duration: 118.788551ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:28.051055Z","caller":"traceutil/trace.go:171","msg":"trace[980251960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5267; }","duration":"104.40888ms","start":"2026-10-16T14:31:27.946592Z","end":"2026-10-16T14:31:28.051001Z","steps":["trace[980251960] 'agreement among raft nodes before linearized reading' (duration: 104.211185ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:28.051009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.757068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-10-16T14:31:28.051168Z","caller":"traceutil/trace.go:171","msg":"trace[656298183] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5267; }","duration":"142.946083ms","start":"2026-10-16T14:31:27.908202Z","end":"2026-10-16T14:31:28.051148Z","steps":["trace[656298183] 'agreement among raft nodes before linearized reading' (duration: 142.576975ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:43.454653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.678694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-16T14:31:43.454728Z","caller":"traceutil/trace.go:171","msg":"trace[1038042880] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5519; }","duration":"100.832056ms","start":"2026-10-16T14:31:43.353881Z","end":"2026-10-16T14:31:43.454713Z","steps":["trace[1038042880] 'agreement among raft nodes before linearized reading' (duration: 100.614442ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:43.454652Z","caller":"traceutil/trace.go:171","msg":"trace[1555829547] transaction","detail":"{read_only:false; response_revision:5519; number_of_response:1; }","duration":"100.166202ms","start":"2026-10-16T14:31:43.354451Z","end":"2026-10-16T14:31:43.454618Z","steps":["trace[1555829547] 'process raft request' (duration: 99.829485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:46.809995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.815005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:31:46.810161Z","caller":"traceutil/trace.go:171","msg":"trace[1020411552] linearizableReadLoop","detail":"{readStateIndex:5801; appliedIndex:5800; }","duration":"170.434295ms","start":"2026-10-16T14:31:46.639709Z","end":"2026-10-16T14:31:46.810144Z","steps":["trace[1020411552] 'read index received' (duration: 28.386865ms)","trace[1020411552] 'applied index is now lower than readState.Index' (duration: 142.04549ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:31:46.810513Z","caller":"traceutil/trace.go:171","msg":"trace[1538322198] transaction","detail":"{read_only:false; response_revision:5594; number_of_response:1; }","duration":"359.590919ms","start":"2026-10-16T14:31:46.45086Z","end":"2026-10-16T14:31:46.810451Z","steps":["trace[1538322198] 'process raft request' (duration: 217.255483ms)","trace[1538322198] 'compare' (duration: 141.486497ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:31:46.810572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.885275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T14:31:46.810805Z","caller":"traceutil/trace.go:171","msg":"trace[1058346429] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5594; }","duration":"171.162311ms","start":"2026-10-16T14:31:46.639623Z","end":"2026-10-16T14:31:46.810786Z","steps":["trace[1058346429] 'agreement among raft nodes before linearized reading' (duration: 170.562918ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:46.810762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:31:46.450842Z","time spent":"359.838995ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":896,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-16T14:31:47.102743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.311117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:31:47.102856Z","caller":"traceutil/trace.go:171","msg":"trace[1472980906] linearizableReadLoop","detail":"{readStateIndex:5802; appliedIndex:5801; }","duration":"152.647086ms","start":"2026-10-16T14:31:46.95019Z","end":"2026-10-16T14:31:47.102837Z","steps":["trace[1472980906] 'read index received' (duration: 26.152085ms)","trace[1472980906] 'applied index is now lower than readState.Index' (duration: 126.493901ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:31:47.102899Z","caller":"traceutil/trace.go:171","msg":"trace[1956890555] transaction","detail":"{read_only:false; response_revision:5595; number_of_response:1; }","duration":"260.252875ms","start":"2026-10-16T14:31:46.842609Z","end":"2026-10-16T14:31:47.102862Z","steps":["trace[1956890555] 'process raft request' (duration: 133.737473ms)","trace[1956890555] 'compare' (duration: 126.171714ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:31:47.103101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.909753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3b8ee717-26b5-46a6-80ac-f4713cf13695\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-16T14:31:47.103154Z","caller":"traceutil/trace.go:171","msg":"trace[143944057] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3b8ee717-26b5-46a6-80ac-f4713cf13695; range_end:; response_count:1; response_revision:5595; }","duration":"152.999815ms","start":"2026-10-16T14:31:46.95014Z","end":"2026-10-16T14:31:47.10314Z","steps":["trace[143944057] 'agreement among raft nodes before linearized reading' (duration: 152.79105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:47.103151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.806981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:31:47.103217Z","caller":"traceutil/trace.go:171","msg":"trace[708275392] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:5595; }","duration":"144.895133ms","start":"2026-10-16T14:31:46.958306Z","end":"2026-10-16T14:31:47.103201Z","steps":["trace[708275392] 'agreement among raft nodes before linearized reading' (duration: 144.772631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:01.328853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.184808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3b8ee717-26b5-46a6-80ac-f4713cf13695\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-10-16T14:32:01.328991Z","caller":"traceutil/trace.go:171","msg":"trace[1372051097] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3b8ee717-26b5-46a6-80ac-f4713cf13695; range_end:; response_count:1; response_revision:5798; }","duration":"165.355911ms","start":"2026-10-16T14:32:01.163607Z","end":"2026-10-16T14:32:01.328963Z","steps":["trace[1372051097] 'range keys from in-memory index tree' (duration: 164.988384ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:01.329292Z","caller":"traceutil/trace.go:171","msg":"trace[612423446] transaction","detail":"{read_only:false; response_revision:5799; number_of_response:1; }","duration":"132.215619ms","start":"2026-10-16T14:32:01.197059Z","end":"2026-10-16T14:32:01.329274Z","steps":["trace[612423446] 'process raft request' (duration: 68.456792ms)","trace[612423446] 'compare' (duration: 63.341128ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:32:01.329236Z","caller":"traceutil/trace.go:171","msg":"trace[1965351443] linearizableReadLoop","detail":"{readStateIndex:6012; appliedIndex:6011; }","duration":"107.470846ms","start":"2026-10-16T14:32:01.221743Z","end":"2026-10-16T14:32:01.329214Z","steps":["trace[1965351443] 'read index received' (duration: 43.710409ms)","trace[1965351443] 'applied index is now lower than readState.Index' (duration: 63.759437ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:32:01.329648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.892385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-16T14:32:01.329745Z","caller":"traceutil/trace.go:171","msg":"trace[1433688816] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5799; }","duration":"108.027758ms","start":"2026-10-16T14:32:01.2217Z","end":"2026-10-16T14:32:01.329728Z","steps":["trace[1433688816] 'agreement among raft nodes before linearized reading' (duration: 107.776653ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:15.678683Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2972} {"level":"info","ts":"2026-10-16T14:32:15.768712Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2972,"took":"88.240135ms","hash":1569277150,"current-db-size-bytes":56770560,"current-db-size":"57 MB","current-db-size-in-use-bytes":32530432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T14:32:15.768801Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1569277150,"revision":2972,"compact-revision":-1} {"level":"info","ts":"2026-10-16T14:32:50.04496Z","caller":"traceutil/trace.go:171","msg":"trace[391637357] linearizableReadLoop","detail":"{readStateIndex:6422; appliedIndex:6420; }","duration":"136.156118ms","start":"2026-10-16T14:32:49.90877Z","end":"2026-10-16T14:32:50.044926Z","steps":["trace[391637357] 'read index received' (duration: 11.892196ms)","trace[391637357] 'applied index is now lower than readState.Index' (duration: 124.261942ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:32:50.045219Z","caller":"traceutil/trace.go:171","msg":"trace[1482575699] transaction","detail":"{read_only:false; response_revision:6196; number_of_response:1; }","duration":"136.823712ms","start":"2026-10-16T14:32:49.908256Z","end":"2026-10-16T14:32:50.04508Z","steps":["trace[1482575699] 'process raft request' (duration: 100.770245ms)","trace[1482575699] 'compare' (duration: 35.782121ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:32:50.045557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.747771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-16T14:32:50.045628Z","caller":"traceutil/trace.go:171","msg":"trace[749526887] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6196; }","duration":"136.859363ms","start":"2026-10-16T14:32:49.908752Z","end":"2026-10-16T14:32:50.045612Z","steps":["trace[749526887] 'agreement among raft nodes before linearized reading' (duration: 136.343102ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:37:15.685185Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5893} {"level":"info","ts":"2026-10-16T14:37:15.776686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5893,"took":"89.817899ms","hash":911453739,"current-db-size-bytes":56770560,"current-db-size":"57 MB","current-db-size-in-use-bytes":26144768,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-16T14:37:15.77674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":911453739,"revision":5893,"compact-revision":2972} {"level":"info","ts":"2026-10-16T14:38:17.285047Z","caller":"traceutil/trace.go:171","msg":"trace[274938648] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"454.771776ms","start":"2026-10-16T14:38:16.830246Z","end":"2026-10-16T14:38:17.285018Z","steps":["trace[274938648] 'process raft request' (duration: 454.575281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:38:17.285223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:38:16.83021Z","time spent":"454.924428ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:38:17.477231Z","caller":"traceutil/trace.go:171","msg":"trace[683382352] linearizableReadLoop","detail":"{readStateIndex:8884; appliedIndex:8882; }","duration":"366.773446ms","start":"2026-10-16T14:38:17.110432Z","end":"2026-10-16T14:38:17.477206Z","steps":["trace[683382352] 'read index received' (duration: 174.317851ms)","trace[683382352] 'applied index is now lower than readState.Index' (duration: 192.454475ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:38:17.477248Z","caller":"traceutil/trace.go:171","msg":"trace[1965572059] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"384.307789ms","start":"2026-10-16T14:38:17.092919Z","end":"2026-10-16T14:38:17.477226Z","steps":["trace[1965572059] 'process raft request' (duration: 383.563592ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:38:17.477344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.900699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:38:17.477369Z","caller":"traceutil/trace.go:171","msg":"trace[1764906430] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:8581; }","duration":"366.965411ms","start":"2026-10-16T14:38:17.110397Z","end":"2026-10-16T14:38:17.477363Z","steps":["trace[1764906430] 'agreement among raft nodes before linearized reading' (duration: 366.90026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:38:17.47739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:38:17.110383Z","time spent":"367.002271ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":23,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-10-16T14:38:17.477419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:38:17.092897Z","time spent":"384.445352ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:38:17.479194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.909094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-16T14:38:17.47925Z","caller":"traceutil/trace.go:171","msg":"trace[387577522] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:8582; }","duration":"364.989326ms","start":"2026-10-16T14:38:17.114245Z","end":"2026-10-16T14:38:17.479235Z","steps":["trace[387577522] 'agreement among raft nodes before linearized reading' (duration: 364.813282ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:38:17.47928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:38:17.114238Z","time spent":"365.034718ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-10-16T14:38:17.479573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.985814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-16T14:38:17.479606Z","caller":"traceutil/trace.go:171","msg":"trace[701188141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8582; }","duration":"124.047176ms","start":"2026-10-16T14:38:17.355552Z","end":"2026-10-16T14:38:17.479599Z","steps":["trace[701188141] 'agreement among raft nodes before linearized reading' (duration: 123.966954ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:38:28.886113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.359865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8z99x\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-16T14:38:28.886272Z","caller":"traceutil/trace.go:171","msg":"trace[780789164] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8z99x; range_end:; response_count:1; response_revision:8644; }","duration":"260.59005ms","start":"2026-10-16T14:38:28.625666Z","end":"2026-10-16T14:38:28.886256Z","steps":["trace[780789164] 'range keys from in-memory index tree' (duration: 260.044168ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:38:29.76272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.536958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:38:29.762848Z","caller":"traceutil/trace.go:171","msg":"trace[164591917] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"437.212233ms","start":"2026-10-16T14:38:29.325612Z","end":"2026-10-16T14:38:29.762824Z","steps":["trace[164591917] 'process raft request' (duration: 67.48172ms)","trace[164591917] 'compare' (duration: 369.409845ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:38:29.762935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:38:29.325589Z","time spent":"437.301304ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:38:29.769724Z","caller":"traceutil/trace.go:171","msg":"trace[481289829] linearizableReadLoop","detail":"{readStateIndex:8953; appliedIndex:8952; }","duration":"231.855447ms","start":"2026-10-16T14:38:29.53785Z","end":"2026-10-16T14:38:29.769705Z","steps":["trace[481289829] 'read index received' (duration: 231.724374ms)","trace[481289829] 'applied index is now lower than readState.Index' (duration: 130.363µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:38:29.770122Z","caller":"traceutil/trace.go:171","msg":"trace[2054005852] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"237.677078ms","start":"2026-10-16T14:38:29.532404Z","end":"2026-10-16T14:38:29.770081Z","steps":["trace[2054005852] 'process raft request' (duration: 237.218857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:38:29.770206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.323348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-16T14:38:29.770322Z","caller":"traceutil/trace.go:171","msg":"trace[1964252261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8647; }","duration":"232.484821ms","start":"2026-10-16T14:38:29.537808Z","end":"2026-10-16T14:38:29.770292Z","steps":["trace[1964252261] 'agreement among raft nodes before linearized reading' (duration: 232.122573ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:38:29.921207Z","caller":"traceutil/trace.go:171","msg":"trace[645225365] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"122.145832ms","start":"2026-10-16T14:38:29.79903Z","end":"2026-10-16T14:38:29.921176Z","steps":["trace[645225365] 'process raft request' (duration: 73.692908ms)","trace[645225365] 'compare' (duration: 47.83744ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:38:29.921513Z","caller":"traceutil/trace.go:171","msg":"trace[102090491] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"105.264104ms","start":"2026-10-16T14:38:29.816236Z","end":"2026-10-16T14:38:29.9215Z","steps":["trace[102090491] 'process raft request' (duration: 104.697482ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:38:56.664792Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023cd.wal"} {"level":"info","ts":"2026-10-16T14:38:58.957232Z","caller":"traceutil/trace.go:171","msg":"trace[866251076] linearizableReadLoop","detail":"{readStateIndex:9174; appliedIndex:9173; }","duration":"279.949164ms","start":"2026-10-16T14:38:58.67726Z","end":"2026-10-16T14:38:58.957209Z","steps":["trace[866251076] 'read index received' (duration: 279.586946ms)","trace[866251076] 'applied index is now lower than readState.Index' (duration: 361.248µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:38:58.957298Z","caller":"traceutil/trace.go:171","msg":"trace[2124926579] transaction","detail":"{read_only:false; response_revision:8860; number_of_response:1; }","duration":"374.845087ms","start":"2026-10-16T14:38:58.582435Z","end":"2026-10-16T14:38:58.95728Z","steps":["trace[2124926579] 'process raft request' (duration: 374.53578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:38:58.957399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:38:58.58241Z","time spent":"374.924018ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:38:58.957501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.22848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-16T14:38:58.957574Z","caller":"traceutil/trace.go:171","msg":"trace[308296695] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8860; }","duration":"280.341703ms","start":"2026-10-16T14:38:58.677221Z","end":"2026-10-16T14:38:58.957563Z","steps":["trace[308296695] 'agreement among raft nodes before linearized reading' (duration: 280.123168ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:38:59.317423Z","caller":"traceutil/trace.go:171","msg":"trace[703745952] linearizableReadLoop","detail":"{readStateIndex:9175; appliedIndex:9174; }","duration":"238.167808ms","start":"2026-10-16T14:38:59.07923Z","end":"2026-10-16T14:38:59.317398Z","steps":["trace[703745952] 'read index received' (duration: 238.052696ms)","trace[703745952] 'applied index is now lower than readState.Index' (duration: 114.182µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:38:59.317603Z","caller":"traceutil/trace.go:171","msg":"trace[807904611] transaction","detail":"{read_only:false; response_revision:8861; number_of_response:1; }","duration":"355.78828ms","start":"2026-10-16T14:38:58.961788Z","end":"2026-10-16T14:38:59.317576Z","steps":["trace[807904611] 'process raft request' (duration: 355.477193ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:38:59.31774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:38:58.961764Z","time spent":"355.902663ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:38:59.31777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.016216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:38:59.317843Z","caller":"traceutil/trace.go:171","msg":"trace[953407708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8861; }","duration":"192.101688ms","start":"2026-10-16T14:38:59.125724Z","end":"2026-10-16T14:38:59.317826Z","steps":["trace[953407708] 'agreement among raft nodes before linearized reading' (duration: 191.980315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:38:59.317812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.540607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8z99x\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-16T14:38:59.317933Z","caller":"traceutil/trace.go:171","msg":"trace[639475622] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8z99x; range_end:; response_count:1; response_revision:8861; }","duration":"238.742392ms","start":"2026-10-16T14:38:59.079178Z","end":"2026-10-16T14:38:59.317921Z","steps":["trace[639475622] 'agreement among raft nodes before linearized reading' (duration: 238.423905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:38:59.317899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.518481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-16T14:38:59.318256Z","caller":"traceutil/trace.go:171","msg":"trace[144746185] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:8861; }","duration":"181.704176ms","start":"2026-10-16T14:38:59.136327Z","end":"2026-10-16T14:38:59.318031Z","steps":["trace[144746185] 'agreement among raft nodes before linearized reading' (duration: 181.427969ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:02.059773Z","caller":"traceutil/trace.go:171","msg":"trace[1598322463] transaction","detail":"{read_only:false; response_revision:8874; number_of_response:1; }","duration":"186.8147ms","start":"2026-10-16T14:39:01.872931Z","end":"2026-10-16T14:39:02.059745Z","steps":["trace[1598322463] 'process raft request' (duration: 186.547234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:02.291522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.885037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:39:02.291906Z","caller":"traceutil/trace.go:171","msg":"trace[1455292463] transaction","detail":"{read_only:false; response_revision:8875; number_of_response:1; }","duration":"412.710814ms","start":"2026-10-16T14:39:01.879154Z","end":"2026-10-16T14:39:02.291865Z","steps":["trace[1455292463] 'process raft request' (duration: 227.395178ms)","trace[1455292463] 'compare' (duration: 184.737173ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:39:02.292038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:39:01.879118Z","time spent":"412.850337ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:39:02.29206Z","caller":"traceutil/trace.go:171","msg":"trace[1996939193] linearizableReadLoop","detail":"{readStateIndex:9190; appliedIndex:9188; }","duration":"337.740897ms","start":"2026-10-16T14:39:01.954295Z","end":"2026-10-16T14:39:02.292036Z","steps":["trace[1996939193] 'read index received' (duration: 105.217944ms)","trace[1996939193] 'applied index is now lower than readState.Index' (duration: 232.522453ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:39:02.292021Z","caller":"traceutil/trace.go:171","msg":"trace[691424253] transaction","detail":"{read_only:false; response_revision:8876; number_of_response:1; }","duration":"255.550577ms","start":"2026-10-16T14:39:02.036452Z","end":"2026-10-16T14:39:02.292002Z","steps":["trace[691424253] 'process raft request' (duration: 255.178979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:02.292161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.86484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-16T14:39:02.292188Z","caller":"traceutil/trace.go:171","msg":"trace[1610654908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8877; }","duration":"337.928111ms","start":"2026-10-16T14:39:01.954254Z","end":"2026-10-16T14:39:02.292182Z","steps":["trace[1610654908] 'agreement among raft nodes before linearized reading' (duration: 337.821269ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:02.292203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:39:01.954231Z","time spent":"337.968642ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-16T14:39:02.292105Z","caller":"traceutil/trace.go:171","msg":"trace[46890919] transaction","detail":"{read_only:false; response_revision:8877; number_of_response:1; }","duration":"253.564773ms","start":"2026-10-16T14:39:02.038522Z","end":"2026-10-16T14:39:02.292087Z","steps":["trace[46890919] 'process raft request' (duration: 253.41139ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:02.292305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.299038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-16T14:39:02.292324Z","caller":"traceutil/trace.go:171","msg":"trace[1834435168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8877; }","duration":"291.345128ms","start":"2026-10-16T14:39:02.000974Z","end":"2026-10-16T14:39:02.292319Z","steps":["trace[1834435168] 'agreement among raft nodes before linearized reading' (duration: 291.296477ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:02.29234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.40748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-16T14:39:02.292405Z","caller":"traceutil/trace.go:171","msg":"trace[400177192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8877; }","duration":"312.491672ms","start":"2026-10-16T14:39:01.979898Z","end":"2026-10-16T14:39:02.29239Z","steps":["trace[400177192] 'agreement among raft nodes before linearized reading' (duration: 312.312678ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:02.292446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:39:01.97984Z","time spent":"312.596824ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-16T14:39:02.29234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.913248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-16T14:39:02.292495Z","caller":"traceutil/trace.go:171","msg":"trace[1095871939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8877; }","duration":"177.101632ms","start":"2026-10-16T14:39:02.115377Z","end":"2026-10-16T14:39:02.292479Z","steps":["trace[1095871939] 'agreement among raft nodes before linearized reading' (duration: 176.844256ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:02.485715Z","caller":"traceutil/trace.go:171","msg":"trace[923485624] transaction","detail":"{read_only:false; response_revision:8878; number_of_response:1; }","duration":"189.563181ms","start":"2026-10-16T14:39:02.296128Z","end":"2026-10-16T14:39:02.485691Z","steps":["trace[923485624] 'process raft request' (duration: 189.418797ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:02.488035Z","caller":"traceutil/trace.go:171","msg":"trace[1149743614] transaction","detail":"{read_only:false; response_revision:8879; number_of_response:1; }","duration":"191.462464ms","start":"2026-10-16T14:39:02.296547Z","end":"2026-10-16T14:39:02.488009Z","steps":["trace[1149743614] 'process raft request' (duration: 191.224878ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:02.48817Z","caller":"traceutil/trace.go:171","msg":"trace[1868298284] transaction","detail":"{read_only:false; response_revision:8881; number_of_response:1; }","duration":"191.027784ms","start":"2026-10-16T14:39:02.297116Z","end":"2026-10-16T14:39:02.488144Z","steps":["trace[1868298284] 'process raft request' (duration: 190.84335ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:02.48803Z","caller":"traceutil/trace.go:171","msg":"trace[88032578] transaction","detail":"{read_only:false; response_revision:8880; number_of_response:1; }","duration":"190.86169ms","start":"2026-10-16T14:39:02.297137Z","end":"2026-10-16T14:39:02.487999Z","steps":["trace[88032578] 'process raft request' (duration: 190.766078ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:10.275053Z","caller":"traceutil/trace.go:171","msg":"trace[1621628412] linearizableReadLoop","detail":"{readStateIndex:9235; appliedIndex:9234; }","duration":"149.208768ms","start":"2026-10-16T14:39:10.125822Z","end":"2026-10-16T14:39:10.275031Z","steps":["trace[1621628412] 'read index received' (duration: 148.947562ms)","trace[1621628412] 'applied index is now lower than readState.Index' (duration: 260.106µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:39:10.275204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.379471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:39:10.27521Z","caller":"traceutil/trace.go:171","msg":"trace[1813062803] transaction","detail":"{read_only:false; response_revision:8918; number_of_response:1; }","duration":"178.840541ms","start":"2026-10-16T14:39:10.096336Z","end":"2026-10-16T14:39:10.275176Z","steps":["trace[1813062803] 'process raft request' (duration: 178.526564ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:10.275256Z","caller":"traceutil/trace.go:171","msg":"trace[1616873830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8918; }","duration":"149.483843ms","start":"2026-10-16T14:39:10.125763Z","end":"2026-10-16T14:39:10.275246Z","steps":["trace[1616873830] 'agreement among raft nodes before linearized reading' (duration: 149.397131ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:40:21.38395Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"53de2bdd9aa7b793","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T14:40:21.389883Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-16T14:40:21.390014Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-16T14:42:15.691643Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8051} {"level":"info","ts":"2026-10-16T14:42:15.76663Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8051,"took":"72.779219ms","hash":48431593,"current-db-size-bytes":56770560,"current-db-size":"57 MB","current-db-size-in-use-bytes":28098560,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T14:42:15.766691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":48431593,"revision":8051,"compact-revision":5893} {"level":"info","ts":"2026-10-16T14:46:24.253733Z","caller":"traceutil/trace.go:171","msg":"trace[582089422] transaction","detail":"{read_only:false; response_revision:12246; number_of_response:1; }","duration":"157.595157ms","start":"2026-10-16T14:46:24.096108Z","end":"2026-10-16T14:46:24.253703Z","steps":["trace[582089422] 'process raft request' (duration: 133.031517ms)","trace[582089422] 'compare' (duration: 24.378135ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:46:24.265889Z","caller":"traceutil/trace.go:171","msg":"trace[1515550305] linearizableReadLoop","detail":"{readStateIndex:12668; appliedIndex:12666; }","duration":"128.771452ms","start":"2026-10-16T14:46:24.137096Z","end":"2026-10-16T14:46:24.265868Z","steps":["trace[1515550305] 'read index received' (duration: 92.153832ms)","trace[1515550305] 'applied index is now lower than readState.Index' (duration: 36.6165ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:46:24.265998Z","caller":"traceutil/trace.go:171","msg":"trace[820977977] transaction","detail":"{read_only:false; response_revision:12247; number_of_response:1; }","duration":"167.018687ms","start":"2026-10-16T14:46:24.09895Z","end":"2026-10-16T14:46:24.265969Z","steps":["trace[820977977] 'process raft request' (duration: 166.778772ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:46:24.266089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.969026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-10-16T14:46:24.266148Z","caller":"traceutil/trace.go:171","msg":"trace[1542077864] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:12247; }","duration":"129.043378ms","start":"2026-10-16T14:46:24.137085Z","end":"2026-10-16T14:46:24.266129Z","steps":["trace[1542077864] 'agreement among raft nodes before linearized reading' (duration: 128.855643ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:46:24.536909Z","caller":"traceutil/trace.go:171","msg":"trace[2075956960] transaction","detail":"{read_only:false; response_revision:12248; number_of_response:1; }","duration":"140.768449ms","start":"2026-10-16T14:46:24.396119Z","end":"2026-10-16T14:46:24.536887Z","steps":["trace[2075956960] 'process raft request' (duration: 140.594986ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:47:15.6981Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10443} {"level":"info","ts":"2026-10-16T14:47:15.78763Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10443,"took":"87.261544ms","hash":576101718,"current-db-size-bytes":56770560,"current-db-size":"57 MB","current-db-size-in-use-bytes":25722880,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-16T14:47:15.787739Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":576101718,"revision":10443,"compact-revision":8051} {"level":"info","ts":"2026-10-16T14:52:15.712795Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12597} {"level":"info","ts":"2026-10-16T14:52:15.797882Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12597,"took":"82.737464ms","hash":2117331122,"current-db-size-bytes":56770560,"current-db-size":"57 MB","current-db-size-in-use-bytes":27881472,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T14:52:15.797939Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2117331122,"revision":12597,"compact-revision":10443} {"level":"info","ts":"2026-10-16T14:53:02.353193Z","caller":"traceutil/trace.go:171","msg":"trace[1714475518] linearizableReadLoop","detail":"{readStateIndex:15422; appliedIndex:15421; }","duration":"179.120455ms","start":"2026-10-16T14:53:02.174048Z","end":"2026-10-16T14:53:02.353168Z","steps":["trace[1714475518] 'read index received' (duration: 178.88396ms)","trace[1714475518] 'applied index is now lower than readState.Index' (duration: 235.285µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:53:02.353292Z","caller":"traceutil/trace.go:171","msg":"trace[1344750142] transaction","detail":"{read_only:false; response_revision:14907; number_of_response:1; }","duration":"183.400471ms","start":"2026-10-16T14:53:02.169864Z","end":"2026-10-16T14:53:02.353265Z","steps":["trace[1344750142] 'process raft request' (duration: 183.131135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:53:02.353472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.398761ms","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-10-16T14:53:02.35361Z","caller":"traceutil/trace.go:171","msg":"trace[1612411500] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:14907; }","duration":"179.577036ms","start":"2026-10-16T14:53:02.174016Z","end":"2026-10-16T14:53:02.353593Z","steps":["trace[1612411500] 'agreement among raft nodes before linearized reading' (duration: 179.281519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:53:02.353472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.987318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8z99x\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-16T14:53:02.353732Z","caller":"traceutil/trace.go:171","msg":"trace[2006174345] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8z99x; range_end:; response_count:1; response_revision:14907; }","duration":"136.267804ms","start":"2026-10-16T14:53:02.217432Z","end":"2026-10-16T14:53:02.3537Z","steps":["trace[2006174345] 'agreement among raft nodes before linearized reading' (duration: 135.917326ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:53:03.262513Z","caller":"traceutil/trace.go:171","msg":"trace[957545143] transaction","detail":"{read_only:false; response_revision:14909; number_of_response:1; }","duration":"338.650421ms","start":"2026-10-16T14:53:02.923812Z","end":"2026-10-16T14:53:03.262462Z","steps":["trace[957545143] 'process raft request' (duration: 338.206901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:53:03.262718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.709979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-16T14:53:03.262819Z","caller":"traceutil/trace.go:171","msg":"trace[749564755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14909; }","duration":"142.839702ms","start":"2026-10-16T14:53:03.119959Z","end":"2026-10-16T14:53:03.262798Z","steps":["trace[749564755] 'agreement among raft nodes before linearized reading' (duration: 142.574156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:53:03.262732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:53:02.923794Z","time spent":"338.821215ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:53:03.262461Z","caller":"traceutil/trace.go:171","msg":"trace[292428703] linearizableReadLoop","detail":"{readStateIndex:15424; appliedIndex:15423; }","duration":"142.429482ms","start":"2026-10-16T14:53:03.119965Z","end":"2026-10-16T14:53:03.262395Z","steps":["trace[292428703] 'read index received' (duration: 142.374931ms)","trace[292428703] 'applied index is now lower than readState.Index' (duration: 53.611µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:53:03.263211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.461176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:53:03.263286Z","caller":"traceutil/trace.go:171","msg":"trace[213810691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14909; }","duration":"135.552359ms","start":"2026-10-16T14:53:03.127718Z","end":"2026-10-16T14:53:03.26327Z","steps":["trace[213810691] 'agreement among raft nodes before linearized reading' (duration: 135.453626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:53:03.263573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.625805ms","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-10-16T14:53:03.263625Z","caller":"traceutil/trace.go:171","msg":"trace[2009821869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14909; }","duration":"133.693776ms","start":"2026-10-16T14:53:03.129917Z","end":"2026-10-16T14:53:03.26361Z","steps":["trace[2009821869] 'agreement among raft nodes before linearized reading' (duration: 133.572814ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:53:03.263618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.678231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-16T14:53:03.263797Z","caller":"traceutil/trace.go:171","msg":"trace[753992019] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14909; }","duration":"135.760743ms","start":"2026-10-16T14:53:03.127902Z","end":"2026-10-16T14:53:03.263663Z","steps":["trace[753992019] 'agreement among raft nodes before linearized reading' (duration: 135.62422ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:53:03.412082Z","caller":"traceutil/trace.go:171","msg":"trace[18699884] transaction","detail":"{read_only:false; response_revision:14910; number_of_response:1; }","duration":"143.310852ms","start":"2026-10-16T14:53:03.26873Z","end":"2026-10-16T14:53:03.412041Z","steps":["trace[18699884] 'process raft request' (duration: 140.96197ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:53:03.421995Z","caller":"traceutil/trace.go:171","msg":"trace[226621519] transaction","detail":"{read_only:false; response_revision:14911; number_of_response:1; }","duration":"152.668352ms","start":"2026-10-16T14:53:03.269299Z","end":"2026-10-16T14:53:03.421967Z","steps":["trace[226621519] 'process raft request' (duration: 152.476627ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:53:03.422249Z","caller":"traceutil/trace.go:171","msg":"trace[1846903094] transaction","detail":"{read_only:false; response_revision:14912; number_of_response:1; }","duration":"152.211752ms","start":"2026-10-16T14:53:03.269985Z","end":"2026-10-16T14:53:03.422197Z","steps":["trace[1846903094] 'process raft request' (duration: 151.939526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:53:26.751546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.937844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8z99x\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-16T14:53:26.75183Z","caller":"traceutil/trace.go:171","msg":"trace[141974581] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8z99x; range_end:; response_count:1; response_revision:15230; }","duration":"168.278752ms","start":"2026-10-16T14:53:26.58353Z","end":"2026-10-16T14:53:26.751809Z","steps":["trace[141974581] 'range keys from in-memory index tree' (duration: 167.655548ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:54:38.265509Z","caller":"traceutil/trace.go:171","msg":"trace[1595776918] transaction","detail":"{read_only:false; response_revision:15968; number_of_response:1; }","duration":"105.778781ms","start":"2026-10-16T14:54:38.159701Z","end":"2026-10-16T14:54:38.26548Z","steps":["trace[1595776918] 'process raft request' (duration: 105.622918ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:54:44.718079Z","caller":"traceutil/trace.go:171","msg":"trace[233046309] transaction","detail":"{read_only:false; response_revision:16000; number_of_response:1; }","duration":"122.645949ms","start":"2026-10-16T14:54:44.595406Z","end":"2026-10-16T14:54:44.718052Z","steps":["trace[233046309] 'process raft request' (duration: 122.498716ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:54:45.000524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.785133ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-16T14:57:15.719424Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14648} {"level":"info","ts":"2026-10-16T14:57:15.808582Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14648,"took":"86.437927ms","hash":1666327213,"current-db-size-bytes":56770560,"current-db-size":"57 MB","current-db-size-in-use-bytes":31637504,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T14:57:15.808657Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1666327213,"revision":14648,"compact-revision":12597} {"level":"info","ts":"2026-10-16T15:00:59.753514Z","caller":"traceutil/trace.go:171","msg":"trace[504119571] transaction","detail":"{read_only:false; response_revision:18763; number_of_response:1; }","duration":"122.582637ms","start":"2026-10-16T15:00:59.630904Z","end":"2026-10-16T15:00:59.753486Z","steps":["trace[504119571] 'process raft request' (duration: 122.428284ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:06.185558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.331158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T15:01:06.185756Z","caller":"traceutil/trace.go:171","msg":"trace[2003850911] transaction","detail":"{read_only:false; response_revision:18801; number_of_response:1; }","duration":"136.607172ms","start":"2026-10-16T15:01:06.04913Z","end":"2026-10-16T15:01:06.185737Z","steps":["trace[2003850911] 'process raft request' (duration: 28.020648ms)","trace[2003850911] 'compare' (duration: 108.097573ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:01:06.326507Z","caller":"traceutil/trace.go:171","msg":"trace[1295759179] transaction","detail":"{read_only:false; response_revision:18802; number_of_response:1; }","duration":"100.184505ms","start":"2026-10-16T15:01:06.226267Z","end":"2026-10-16T15:01:06.326452Z","steps":["trace[1295759179] 'process raft request' (duration: 98.252622ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:07.373241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228093848637539703,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-16T15:01:07.476743Z","caller":"traceutil/trace.go:171","msg":"trace[271864158] linearizableReadLoop","detail":"{readStateIndex:19448; appliedIndex:19447; }","duration":"603.921446ms","start":"2026-10-16T15:01:06.872797Z","end":"2026-10-16T15:01:07.476718Z","steps":["trace[271864158] 'read index received' (duration: 603.637279ms)","trace[271864158] 'applied index is now lower than readState.Index' (duration: 282.927µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:01:07.476871Z","caller":"traceutil/trace.go:171","msg":"trace[992825929] transaction","detail":"{read_only:false; response_revision:18805; number_of_response:1; }","duration":"657.544248ms","start":"2026-10-16T15:01:06.819316Z","end":"2026-10-16T15:01:07.476861Z","steps":["trace[992825929] 'process raft request' (duration: 657.215051ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:07.47698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:01:06.819251Z","time spent":"657.64577ms","remote":"127.0.0.1:35656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20877,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:01:07.477124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.136549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:01:07.477206Z","caller":"traceutil/trace.go:171","msg":"trace[992111737] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:18805; }","duration":"446.274732ms","start":"2026-10-16T15:01:07.030911Z","end":"2026-10-16T15:01:07.477185Z","steps":["trace[992111737] 'agreement among raft nodes before linearized reading' (duration: 446.128689ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:07.477247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:01:07.030891Z","time spent":"446.345694ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"warn","ts":"2026-10-16T15:01:07.477599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.187236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-10-16T15:01:07.477619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.607621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:01:07.47765Z","caller":"traceutil/trace.go:171","msg":"trace[108020435] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18805; }","duration":"196.273688ms","start":"2026-10-16T15:01:07.281363Z","end":"2026-10-16T15:01:07.477637Z","steps":["trace[108020435] 'agreement among raft nodes before linearized reading' (duration: 196.123705ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:01:07.477664Z","caller":"traceutil/trace.go:171","msg":"trace[579272697] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:18805; }","duration":"127.710633ms","start":"2026-10-16T15:01:07.349941Z","end":"2026-10-16T15:01:07.477652Z","steps":["trace[579272697] 'agreement among raft nodes before linearized reading' (duration: 127.639311ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:07.477622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.803985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-10-16T15:01:07.477621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.844984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-mmmwz\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-10-16T15:01:07.477846Z","caller":"traceutil/trace.go:171","msg":"trace[385822615] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-mmmwz; range_end:; response_count:1; response_revision:18805; }","duration":"343.11793ms","start":"2026-10-16T15:01:07.134718Z","end":"2026-10-16T15:01:07.477836Z","steps":["trace[385822615] 'agreement among raft nodes before linearized reading' (duration: 342.784852ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:07.477877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:01:07.134699Z","time spent":"343.168951ms","remote":"127.0.0.1:35656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22155,"request content":"key:\"/registry/pods/openstack/nova-compute-default-mmmwz\" "} {"level":"info","ts":"2026-10-16T15:01:07.477819Z","caller":"traceutil/trace.go:171","msg":"trace[248502569] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:18805; }","duration":"605.046901ms","start":"2026-10-16T15:01:06.872741Z","end":"2026-10-16T15:01:07.477788Z","steps":["trace[248502569] 'agreement among raft nodes before linearized reading' (duration: 604.665712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:07.478008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:01:06.872721Z","time spent":"605.270766ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-10-16T15:01:07.625044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.084511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:01:07.625115Z","caller":"traceutil/trace.go:171","msg":"trace[1346040070] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:18807; }","duration":"107.195342ms","start":"2026-10-16T15:01:07.517901Z","end":"2026-10-16T15:01:07.625097Z","steps":["trace[1346040070] 'agreement among raft nodes before linearized reading' (duration: 107.08177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:07.625821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.279439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:962446"} {"level":"info","ts":"2026-10-16T15:01:07.625879Z","caller":"traceutil/trace.go:171","msg":"trace[1450214638] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:18807; }","duration":"138.361752ms","start":"2026-10-16T15:01:07.487502Z","end":"2026-10-16T15:01:07.625864Z","steps":["trace[1450214638] 'agreement among raft nodes before linearized reading' (duration: 137.273137ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:07.626012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.420452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:962446"} {"level":"info","ts":"2026-10-16T15:01:07.626102Z","caller":"traceutil/trace.go:171","msg":"trace[1954190018] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:18807; }","duration":"138.528835ms","start":"2026-10-16T15:01:07.487557Z","end":"2026-10-16T15:01:07.626086Z","steps":["trace[1954190018] 'agreement among raft nodes before linearized reading' (duration: 137.481091ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:01:47.056996Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"53de2bdd9aa7b793","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T15:01:47.064564Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-16T15:01:47.064722Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-16T15:02:15.727066Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17068} {"level":"info","ts":"2026-10-16T15:02:15.830328Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17068,"took":"99.959231ms","hash":1822494055,"current-db-size-bytes":56770560,"current-db-size":"57 MB","current-db-size-in-use-bytes":33390592,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T15:02:15.830396Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1822494055,"revision":17068,"compact-revision":14648} {"level":"info","ts":"2026-10-16T15:06:42.945472Z","caller":"traceutil/trace.go:171","msg":"trace[2054161310] linearizableReadLoop","detail":"{readStateIndex:22219; appliedIndex:22218; }","duration":"298.510771ms","start":"2026-10-16T15:06:42.646937Z","end":"2026-10-16T15:06:42.945448Z","steps":["trace[2054161310] 'read index received' (duration: 298.289406ms)","trace[2054161310] 'applied index is now lower than readState.Index' (duration: 220.505µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:06:42.945633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.677745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:06:42.945616Z","caller":"traceutil/trace.go:171","msg":"trace[1832577513] transaction","detail":"{read_only:false; response_revision:21488; number_of_response:1; }","duration":"312.151587ms","start":"2026-10-16T15:06:42.633431Z","end":"2026-10-16T15:06:42.945583Z","steps":["trace[1832577513] 'process raft request' (duration: 311.85275ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:06:42.94567Z","caller":"traceutil/trace.go:171","msg":"trace[2119237223] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:21488; }","duration":"298.777257ms","start":"2026-10-16T15:06:42.646879Z","end":"2026-10-16T15:06:42.945656Z","steps":["trace[2119237223] 'agreement among raft nodes before linearized reading' (duration: 298.691595ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:06:42.945725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.336771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-16T15:06:42.94578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:06:42.633406Z","time spent":"312.28807ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:06:42.945802Z","caller":"traceutil/trace.go:171","msg":"trace[1778178055] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:21488; }","duration":"163.467904ms","start":"2026-10-16T15:06:42.782315Z","end":"2026-10-16T15:06:42.945783Z","steps":["trace[1778178055] 'agreement among raft nodes before linearized reading' (duration: 163.320951ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:06:43.063569Z","caller":"traceutil/trace.go:171","msg":"trace[1414282016] linearizableReadLoop","detail":"{readStateIndex:22220; appliedIndex:22219; }","duration":"100.734918ms","start":"2026-10-16T15:06:42.962812Z","end":"2026-10-16T15:06:43.063547Z","steps":["trace[1414282016] 'read index received' (duration: 93.019034ms)","trace[1414282016] 'applied index is now lower than readState.Index' (duration: 7.714584ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:06:43.06393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.096096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-16T15:06:43.063999Z","caller":"traceutil/trace.go:171","msg":"trace[317642195] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:21489; }","duration":"101.213029ms","start":"2026-10-16T15:06:42.962769Z","end":"2026-10-16T15:06:43.063982Z","steps":["trace[317642195] 'agreement among raft nodes before linearized reading' (duration: 100.994284ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:06:43.06423Z","caller":"traceutil/trace.go:171","msg":"trace[1120587898] transaction","detail":"{read_only:false; response_revision:21489; number_of_response:1; }","duration":"107.730995ms","start":"2026-10-16T15:06:42.956485Z","end":"2026-10-16T15:06:43.064216Z","steps":["trace[1120587898] 'process raft request' (duration: 99.412868ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:06:59.151474Z","caller":"traceutil/trace.go:171","msg":"trace[1247846708] transaction","detail":"{read_only:false; response_revision:21572; number_of_response:1; }","duration":"128.322807ms","start":"2026-10-16T15:06:59.023128Z","end":"2026-10-16T15:06:59.151451Z","steps":["trace[1247846708] 'process raft request' (duration: 128.148973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:06:59.195517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.506602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T15:06:59.195618Z","caller":"traceutil/trace.go:171","msg":"trace[1987526141] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:21574; }","duration":"108.663006ms","start":"2026-10-16T15:06:59.086933Z","end":"2026-10-16T15:06:59.195596Z","steps":["trace[1987526141] 'agreement among raft nodes before linearized reading' (duration: 108.477912ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:06:59.377551Z","caller":"traceutil/trace.go:171","msg":"trace[1365619629] linearizableReadLoop","detail":"{readStateIndex:22309; appliedIndex:22308; }","duration":"180.563537ms","start":"2026-10-16T15:06:59.196945Z","end":"2026-10-16T15:06:59.377508Z","steps":["trace[1365619629] 'read index received' (duration: 161.204153ms)","trace[1365619629] 'applied index is now lower than readState.Index' (duration: 19.357614ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:06:59.377608Z","caller":"traceutil/trace.go:171","msg":"trace[1230791983] transaction","detail":"{read_only:false; response_revision:21575; number_of_response:1; }","duration":"215.167003ms","start":"2026-10-16T15:06:59.162413Z","end":"2026-10-16T15:06:59.37758Z","steps":["trace[1230791983] 'process raft request' (duration: 195.751768ms)","trace[1230791983] 'compare' (duration: 19.167019ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:06:59.377735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.769552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:06:59.377793Z","caller":"traceutil/trace.go:171","msg":"trace[1295135177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21575; }","duration":"180.837813ms","start":"2026-10-16T15:06:59.196939Z","end":"2026-10-16T15:06:59.377777Z","steps":["trace[1295135177] 'agreement among raft nodes before linearized reading' (duration: 180.732221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:06:59.761811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.895183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T15:06:59.761905Z","caller":"traceutil/trace.go:171","msg":"trace[95094751] linearizableReadLoop","detail":"{readStateIndex:22310; appliedIndex:22309; }","duration":"384.249933ms","start":"2026-10-16T15:06:59.377637Z","end":"2026-10-16T15:06:59.761887Z","steps":["trace[95094751] 'read index received' (duration: 216.180866ms)","trace[95094751] 'applied index is now lower than readState.Index' (duration: 168.067627ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:06:59.761971Z","caller":"traceutil/trace.go:171","msg":"trace[1082970557] transaction","detail":"{read_only:false; response_revision:21576; number_of_response:1; }","duration":"512.420195ms","start":"2026-10-16T15:06:59.249525Z","end":"2026-10-16T15:06:59.761945Z","steps":["trace[1082970557] 'process raft request' (duration: 344.313427ms)","trace[1082970557] 'compare' (duration: 167.798761ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:06:59.762026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.982367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-16T15:06:59.762057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:06:59.249408Z","time spent":"512.60292ms","remote":"127.0.0.1:35722","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-10-16T15:06:59.762067Z","caller":"traceutil/trace.go:171","msg":"trace[884304112] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:21576; }","duration":"177.051049ms","start":"2026-10-16T15:06:59.585005Z","end":"2026-10-16T15:06:59.762056Z","steps":["trace[884304112] 'agreement among raft nodes before linearized reading' (duration: 176.981677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:06:59.762139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.771487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-16T15:06:59.762213Z","caller":"traceutil/trace.go:171","msg":"trace[1650916483] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:21576; }","duration":"530.90279ms","start":"2026-10-16T15:06:59.231291Z","end":"2026-10-16T15:06:59.762194Z","steps":["trace[1650916483] 'agreement among raft nodes before linearized reading' (duration: 530.685415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:06:59.762243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:06:59.231272Z","time spent":"530.964641ms","remote":"127.0.0.1:35514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/162.253.55.209\" "} {"level":"warn","ts":"2026-10-16T15:07:00.111079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.338955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T15:07:00.111203Z","caller":"traceutil/trace.go:171","msg":"trace[1146556339] linearizableReadLoop","detail":"{readStateIndex:22313; appliedIndex:22311; }","duration":"112.39922ms","start":"2026-10-16T15:06:59.998793Z","end":"2026-10-16T15:07:00.111192Z","steps":["trace[1146556339] 'read index received' (duration: 34.621µs)","trace[1146556339] 'applied index is now lower than readState.Index' (duration: 112.363979ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:07:00.111261Z","caller":"traceutil/trace.go:171","msg":"trace[1010743902] transaction","detail":"{read_only:false; response_revision:21577; number_of_response:1; }","duration":"345.253279ms","start":"2026-10-16T15:06:59.766Z","end":"2026-10-16T15:07:00.111253Z","steps":["trace[1010743902] 'process raft request' (duration: 159.681929ms)","trace[1010743902] 'compare' (duration: 185.065859ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:07:00.111298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:06:59.765981Z","time spent":"345.29709ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:07:00.111733Z","caller":"traceutil/trace.go:171","msg":"trace[1223537888] transaction","detail":"{read_only:false; response_revision:21578; number_of_response:1; }","duration":"325.408244ms","start":"2026-10-16T15:06:59.786233Z","end":"2026-10-16T15:07:00.111641Z","steps":["trace[1223537888] 'process raft request' (duration: 324.916443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:07:00.111917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.067114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-16T15:07:00.111936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:06:59.786216Z","time spent":"325.615469ms","remote":"127.0.0.1:35514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:07:00.111948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.140727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-16T15:07:00.111987Z","caller":"traceutil/trace.go:171","msg":"trace[532032182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21578; }","duration":"113.205667ms","start":"2026-10-16T15:06:59.998765Z","end":"2026-10-16T15:07:00.11197Z","steps":["trace[532032182] 'agreement among raft nodes before linearized reading' (duration: 112.777767ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:07:00.112024Z","caller":"traceutil/trace.go:171","msg":"trace[1238868758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21578; }","duration":"109.244999ms","start":"2026-10-16T15:07:00.002759Z","end":"2026-10-16T15:07:00.112004Z","steps":["trace[1238868758] 'agreement among raft nodes before linearized reading' (duration: 109.000873ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:07:00.596835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.328448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T15:07:00.597026Z","caller":"traceutil/trace.go:171","msg":"trace[19654403] linearizableReadLoop","detail":"{readStateIndex:22315; appliedIndex:22313; }","duration":"479.875746ms","start":"2026-10-16T15:07:00.117133Z","end":"2026-10-16T15:07:00.597009Z","steps":["trace[19654403] 'read index received' (duration: 160.298773ms)","trace[19654403] 'applied index is now lower than readState.Index' (duration: 319.576133ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:07:00.597106Z","caller":"traceutil/trace.go:171","msg":"trace[1759059249] transaction","detail":"{read_only:false; response_revision:21580; number_of_response:1; }","duration":"480.105931ms","start":"2026-10-16T15:07:00.116974Z","end":"2026-10-16T15:07:00.59708Z","steps":["trace[1759059249] 'process raft request' (duration: 479.980948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:07:00.597215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.086201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-16T15:07:00.597232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:07:00.116961Z","time spent":"480.207083ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":416,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:07:00.597249Z","caller":"traceutil/trace.go:171","msg":"trace[504975151] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:21580; }","duration":"480.149282ms","start":"2026-10-16T15:07:00.11709Z","end":"2026-10-16T15:07:00.597239Z","steps":["trace[504975151] 'agreement among raft nodes before linearized reading' (duration: 480.012329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:07:00.597284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:07:00.117075Z","time spent":"480.198184ms","remote":"127.0.0.1:35726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-10-16T15:07:00.597269Z","caller":"traceutil/trace.go:171","msg":"trace[778366701] transaction","detail":"{read_only:false; response_revision:21579; number_of_response:1; }","duration":"481.474242ms","start":"2026-10-16T15:07:00.115764Z","end":"2026-10-16T15:07:00.597238Z","steps":["trace[778366701] 'process raft request' (duration: 161.652213ms)","trace[778366701] 'compare' (duration: 319.204075ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:07:00.597419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:07:00.115735Z","time spent":"481.597245ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:07:00.59757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.925598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:07:00.597612Z","caller":"traceutil/trace.go:171","msg":"trace[375545557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21580; }","duration":"472.00363ms","start":"2026-10-16T15:07:00.125597Z","end":"2026-10-16T15:07:00.597601Z","steps":["trace[375545557] 'agreement among raft nodes before linearized reading' (duration: 471.940008ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:07:00.597643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:07:00.125579Z","time spent":"472.057501ms","remote":"127.0.0.1:35494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-16T15:07:00.59774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.540337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-16T15:07:00.597784Z","caller":"traceutil/trace.go:171","msg":"trace[1521110314] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21580; }","duration":"466.602149ms","start":"2026-10-16T15:07:00.131169Z","end":"2026-10-16T15:07:00.597771Z","steps":["trace[1521110314] 'agreement among raft nodes before linearized reading' (duration: 466.503346ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:07:00.597816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:07:00.131159Z","time spent":"466.65019ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-16T15:07:15.733992Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19569} {"level":"info","ts":"2026-10-16T15:07:15.879262Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19569,"took":"141.90022ms","hash":2512472503,"current-db-size-bytes":56770560,"current-db-size":"57 MB","current-db-size-in-use-bytes":29941760,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T15:07:15.879333Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2512472503,"revision":19569,"compact-revision":17068} {"level":"warn","ts":"2026-10-16T15:11:02.532413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.69837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T15:11:02.532624Z","caller":"traceutil/trace.go:171","msg":"trace[1846519899] transaction","detail":"{read_only:false; response_revision:24093; number_of_response:1; }","duration":"162.790659ms","start":"2026-10-16T15:11:02.369821Z","end":"2026-10-16T15:11:02.532611Z","steps":["trace[1846519899] 'process raft request' (duration: 162.764659ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:11:02.532671Z","caller":"traceutil/trace.go:171","msg":"trace[1211845538] linearizableReadLoop","detail":"{readStateIndex:24899; appliedIndex:24898; }","duration":"407.73668ms","start":"2026-10-16T15:11:02.124896Z","end":"2026-10-16T15:11:02.532633Z","steps":["trace[1211845538] 'read index received' (duration: 284.686042ms)","trace[1211845538] 'applied index is now lower than readState.Index' (duration: 123.046268ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:11:02.532775Z","caller":"traceutil/trace.go:171","msg":"trace[778486968] transaction","detail":"{read_only:false; response_revision:24090; number_of_response:1; }","duration":"367.376785ms","start":"2026-10-16T15:11:02.165391Z","end":"2026-10-16T15:11:02.532768Z","steps":["trace[778486968] 'process raft request' (duration: 367.105639ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:11:02.532835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:11:02.165355Z","time spent":"367.457697ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:11:02.532931Z","caller":"traceutil/trace.go:171","msg":"trace[196744525] transaction","detail":"{read_only:false; response_revision:24091; number_of_response:1; }","duration":"365.436511ms","start":"2026-10-16T15:11:02.167487Z","end":"2026-10-16T15:11:02.532923Z","steps":["trace[196744525] 'process raft request' (duration: 365.049443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:11:02.532965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:11:02.167461Z","time spent":"365.487963ms","remote":"127.0.0.1:35722","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-10-16T15:11:02.533016Z","caller":"traceutil/trace.go:171","msg":"trace[2050900961] transaction","detail":"{read_only:false; response_revision:24092; number_of_response:1; }","duration":"235.574131ms","start":"2026-10-16T15:11:02.297436Z","end":"2026-10-16T15:11:02.53301Z","steps":["trace[2050900961] 'process raft request' (duration: 235.125281ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:11:02.533093Z","caller":"traceutil/trace.go:171","msg":"trace[1209170499] transaction","detail":"{read_only:false; response_revision:24089; number_of_response:1; }","duration":"438.149752ms","start":"2026-10-16T15:11:02.094924Z","end":"2026-10-16T15:11:02.533074Z","steps":["trace[1209170499] 'process raft request' (duration: 314.722895ms)","trace[1209170499] 'compare' (duration: 122.044586ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:11:02.533243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:11:02.094896Z","time spent":"438.251824ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:11:02.533328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.988292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:11:02.53337Z","caller":"traceutil/trace.go:171","msg":"trace[767451434] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:24093; }","duration":"237.075935ms","start":"2026-10-16T15:11:02.296274Z","end":"2026-10-16T15:11:02.53335Z","steps":["trace[767451434] 'agreement among raft nodes before linearized reading' (duration: 237.022754ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:11:02.533495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.61229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:11:02.533516Z","caller":"traceutil/trace.go:171","msg":"trace[420577432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24093; }","duration":"408.672151ms","start":"2026-10-16T15:11:02.124838Z","end":"2026-10-16T15:11:02.53351Z","steps":["trace[420577432] 'agreement among raft nodes before linearized reading' (duration: 408.637481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:11:02.533531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:11:02.124792Z","time spent":"408.735963ms","remote":"127.0.0.1:35494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-16T15:12:15.742156Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21659} {"level":"info","ts":"2026-10-16T15:12:15.856306Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21659,"took":"110.382605ms","hash":1474800314,"current-db-size-bytes":57614336,"current-db-size":"58 MB","current-db-size-in-use-bytes":43409408,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-16T15:12:15.856383Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1474800314,"revision":21659,"compact-revision":19569} {"level":"info","ts":"2026-10-16T15:13:53.46921Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067f3.wal"} {"level":"info","ts":"2026-10-16T15:14:27.897475Z","caller":"traceutil/trace.go:171","msg":"trace[1232344747] transaction","detail":"{read_only:false; response_revision:26097; number_of_response:1; }","duration":"183.897152ms","start":"2026-10-16T15:14:27.71355Z","end":"2026-10-16T15:14:27.897447Z","steps":["trace[1232344747] 'process raft request' (duration: 183.585635ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:14:33.504548Z","caller":"traceutil/trace.go:171","msg":"trace[1027765035] linearizableReadLoop","detail":"{readStateIndex:26996; appliedIndex:26995; }","duration":"228.777378ms","start":"2026-10-16T15:14:33.275752Z","end":"2026-10-16T15:14:33.504529Z","steps":["trace[1027765035] 'read index received' (duration: 228.697736ms)","trace[1027765035] 'applied index is now lower than readState.Index' (duration: 78.912µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:14:33.50458Z","caller":"traceutil/trace.go:171","msg":"trace[1777265582] transaction","detail":"{read_only:false; response_revision:26132; number_of_response:1; }","duration":"229.962405ms","start":"2026-10-16T15:14:33.274604Z","end":"2026-10-16T15:14:33.504566Z","steps":["trace[1777265582] 'process raft request' (duration: 229.806522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:14:33.504757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.987113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-16T15:14:33.504789Z","caller":"traceutil/trace.go:171","msg":"trace[1143468954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26132; }","duration":"229.033794ms","start":"2026-10-16T15:14:33.275745Z","end":"2026-10-16T15:14:33.504779Z","steps":["trace[1143468954] 'agreement among raft nodes before linearized reading' (duration: 228.87355ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:14:33.914796Z","caller":"traceutil/trace.go:171","msg":"trace[584507590] transaction","detail":"{read_only:false; response_revision:26135; number_of_response:1; }","duration":"519.70726ms","start":"2026-10-16T15:14:33.395045Z","end":"2026-10-16T15:14:33.914752Z","steps":["trace[584507590] 'process raft request' (duration: 517.757386ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:14:33.914953Z","caller":"traceutil/trace.go:171","msg":"trace[1303581981] transaction","detail":"{read_only:false; response_revision:26133; number_of_response:1; }","duration":"544.934866ms","start":"2026-10-16T15:14:33.369975Z","end":"2026-10-16T15:14:33.91491Z","steps":["trace[1303581981] 'process raft request' (duration: 542.073151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:14:33.915116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:14:33.395038Z","time spent":"519.932835ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:14:33.915196Z","caller":"traceutil/trace.go:171","msg":"trace[985606242] transaction","detail":"{read_only:false; response_revision:26134; number_of_response:1; }","duration":"520.201501ms","start":"2026-10-16T15:14:33.394973Z","end":"2026-10-16T15:14:33.915175Z","steps":["trace[985606242] 'process raft request' (duration: 517.738865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:14:33.915259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:14:33.394958Z","time spent":"520.267673ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:14:33.915146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:14:33.36995Z","time spent":"545.083339ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:14:33.929704Z","caller":"traceutil/trace.go:171","msg":"trace[1821170358] linearizableReadLoop","detail":"{readStateIndex:27001; appliedIndex:26996; }","duration":"424.436914ms","start":"2026-10-16T15:14:33.504601Z","end":"2026-10-16T15:14:33.929038Z","steps":["trace[1821170358] 'read index received' (duration: 407.460444ms)","trace[1821170358] 'applied index is now lower than readState.Index' (duration: 16.97519ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:14:33.929696Z","caller":"traceutil/trace.go:171","msg":"trace[741372703] transaction","detail":"{read_only:false; response_revision:26136; number_of_response:1; }","duration":"533.333605ms","start":"2026-10-16T15:14:33.396321Z","end":"2026-10-16T15:14:33.929654Z","steps":["trace[741372703] 'process raft request' (duration: 516.546829ms)","trace[741372703] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/messaging-topology-operator-leader-election; req_size:553; } (duration: 15.61677ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:14:33.929722Z","caller":"traceutil/trace.go:171","msg":"trace[1322569825] transaction","detail":"{read_only:false; response_revision:26137; number_of_response:1; }","duration":"532.004946ms","start":"2026-10-16T15:14:33.397686Z","end":"2026-10-16T15:14:33.929691Z","steps":["trace[1322569825] 'process raft request' (duration: 531.274329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:14:33.929868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:14:33.39631Z","time spent":"533.477449ms","remote":"127.0.0.1:35722","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-10-16T15:14:33.929988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:14:33.397664Z","time spent":"532.22398ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:14:33.930962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.488797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-16T15:14:33.931021Z","caller":"traceutil/trace.go:171","msg":"trace[1730635043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26137; }","duration":"652.567568ms","start":"2026-10-16T15:14:33.278435Z","end":"2026-10-16T15:14:33.931002Z","steps":["trace[1730635043] 'agreement among raft nodes before linearized reading' (duration: 651.100795ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:14:33.931054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:14:33.278424Z","time spent":"652.622559ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-16T15:14:34.230385Z","caller":"traceutil/trace.go:171","msg":"trace[1946529045] linearizableReadLoop","detail":"{readStateIndex:27004; appliedIndex:27001; }","duration":"300.584808ms","start":"2026-10-16T15:14:33.929766Z","end":"2026-10-16T15:14:34.230351Z","steps":["trace[1946529045] 'read index received' (duration: 300.352003ms)","trace[1946529045] 'applied index is now lower than readState.Index' (duration: 231.645µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:14:34.230733Z","caller":"traceutil/trace.go:171","msg":"trace[1104728149] transaction","detail":"{read_only:false; response_revision:26138; number_of_response:1; }","duration":"721.704908ms","start":"2026-10-16T15:14:33.509006Z","end":"2026-10-16T15:14:34.230711Z","steps":["trace[1104728149] 'process raft request' (duration: 721.063173ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:14:34.230748Z","caller":"traceutil/trace.go:171","msg":"trace[744320696] transaction","detail":"{read_only:false; response_revision:26139; number_of_response:1; }","duration":"504.584981ms","start":"2026-10-16T15:14:33.726141Z","end":"2026-10-16T15:14:34.230726Z","steps":["trace[744320696] 'process raft request' (duration: 504.08105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:14:34.230926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:14:33.508962Z","time spent":"721.854341ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:14:34.23093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.051598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-10-16T15:14:34.231022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:14:33.726112Z","time spent":"504.822986ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:14:34.230983Z","caller":"traceutil/trace.go:171","msg":"trace[798295152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26140; }","duration":"309.14518ms","start":"2026-10-16T15:14:33.921824Z","end":"2026-10-16T15:14:34.230969Z","steps":["trace[798295152] 'agreement among raft nodes before linearized reading' (duration: 308.838713ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:14:34.231194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:14:33.921805Z","time spent":"309.377835ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-16T15:14:34.230695Z","caller":"traceutil/trace.go:171","msg":"trace[747145597] transaction","detail":"{read_only:false; response_revision:26140; number_of_response:1; }","duration":"352.828279ms","start":"2026-10-16T15:14:33.877826Z","end":"2026-10-16T15:14:34.230654Z","steps":["trace[747145597] 'process raft request' (duration: 352.45384ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:14:34.231461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:14:33.877806Z","time spent":"353.585906ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:14:34.303823Z","caller":"traceutil/trace.go:171","msg":"trace[1502664315] transaction","detail":"{read_only:false; response_revision:26141; number_of_response:1; }","duration":"369.261938ms","start":"2026-10-16T15:14:33.934533Z","end":"2026-10-16T15:14:34.303795Z","steps":["trace[1502664315] 'process raft request' (duration: 369.057904ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:14:34.303981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:14:33.934508Z","time spent":"369.391871ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:14:34.304038Z","caller":"traceutil/trace.go:171","msg":"trace[429620172] transaction","detail":"{read_only:false; response_revision:26142; number_of_response:1; }","duration":"311.660347ms","start":"2026-10-16T15:14:33.992343Z","end":"2026-10-16T15:14:34.304003Z","steps":["trace[429620172] 'process raft request' (duration: 311.407891ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:14:34.304165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:14:33.992297Z","time spent":"311.79589ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:14:34.30416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.203701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-16T15:14:34.304203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.151588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:14:34.304248Z","caller":"traceutil/trace.go:171","msg":"trace[510158591] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26142; }","duration":"280.378926ms","start":"2026-10-16T15:14:34.023845Z","end":"2026-10-16T15:14:34.304224Z","steps":["trace[510158591] 'agreement among raft nodes before linearized reading' (duration: 280.181391ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:14:34.304288Z","caller":"traceutil/trace.go:171","msg":"trace[1306674037] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:26142; }","duration":"126.313072ms","start":"2026-10-16T15:14:34.177953Z","end":"2026-10-16T15:14:34.304266Z","steps":["trace[1306674037] 'agreement among raft nodes before linearized reading' (duration: 126.077957ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:14:34.343308Z","caller":"traceutil/trace.go:171","msg":"trace[841402629] transaction","detail":"{read_only:false; response_revision:26143; number_of_response:1; }","duration":"102.447186ms","start":"2026-10-16T15:14:34.240831Z","end":"2026-10-16T15:14:34.343278Z","steps":["trace[841402629] 'process raft request' (duration: 101.850343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:14:34.344517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.991988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-f75d76dc-sq8mj\" ","response":"range_response_count:1 size:8642"} {"level":"info","ts":"2026-10-16T15:14:34.344604Z","caller":"traceutil/trace.go:171","msg":"trace[33808058] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-f75d76dc-sq8mj; range_end:; response_count:1; response_revision:26143; }","duration":"107.141331ms","start":"2026-10-16T15:14:34.237445Z","end":"2026-10-16T15:14:34.344586Z","steps":["trace[33808058] 'agreement among raft nodes before linearized reading' (duration: 106.712162ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:15:38.935144Z","caller":"traceutil/trace.go:171","msg":"trace[139637105] transaction","detail":"{read_only:false; response_revision:26751; number_of_response:1; }","duration":"143.498396ms","start":"2026-10-16T15:15:38.791625Z","end":"2026-10-16T15:15:38.935123Z","steps":["trace[139637105] 'process raft request' (duration: 143.449935ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:15:38.935655Z","caller":"traceutil/trace.go:171","msg":"trace[1120126929] transaction","detail":"{read_only:false; response_revision:26750; number_of_response:1; }","duration":"378.189068ms","start":"2026-10-16T15:15:38.557449Z","end":"2026-10-16T15:15:38.935638Z","steps":["trace[1120126929] 'process raft request' (duration: 328.182977ms)","trace[1120126929] 'compare' (duration: 48.969577ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:15:38.935885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:15:38.557426Z","time spent":"378.369431ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:15:38.936139Z","caller":"traceutil/trace.go:171","msg":"trace[1330166332] linearizableReadLoop","detail":"{readStateIndex:27629; appliedIndex:27627; }","duration":"371.068377ms","start":"2026-10-16T15:15:38.565052Z","end":"2026-10-16T15:15:38.93612Z","steps":["trace[1330166332] 'read index received' (duration: 508.561µs)","trace[1330166332] 'applied index is now lower than readState.Index' (duration: 370.557606ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:15:38.937808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.754896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-16T15:15:38.937857Z","caller":"traceutil/trace.go:171","msg":"trace[430973714] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26751; }","duration":"372.862928ms","start":"2026-10-16T15:15:38.564984Z","end":"2026-10-16T15:15:38.937847Z","steps":["trace[430973714] 'agreement among raft nodes before linearized reading' (duration: 371.467957ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:15:38.937889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:15:38.564963Z","time spent":"372.918139ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2675,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-10-16T15:15:38.938316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.769618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-16T15:15:38.938357Z","caller":"traceutil/trace.go:171","msg":"trace[167694895] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26751; }","duration":"340.85229ms","start":"2026-10-16T15:15:38.597495Z","end":"2026-10-16T15:15:38.938348Z","steps":["trace[167694895] 'agreement among raft nodes before linearized reading' (duration: 340.707297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:15:38.93839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:15:38.597475Z","time spent":"340.908452ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-16T15:15:38.938891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.471887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-16T15:15:38.93898Z","caller":"traceutil/trace.go:171","msg":"trace[1416356595] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:26751; }","duration":"152.59224ms","start":"2026-10-16T15:15:38.786375Z","end":"2026-10-16T15:15:38.938968Z","steps":["trace[1416356595] 'agreement among raft nodes before linearized reading' (duration: 152.414006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:15:38.939327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.838759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-16T15:15:38.939369Z","caller":"traceutil/trace.go:171","msg":"trace[1965464870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26751; }","duration":"265.928181ms","start":"2026-10-16T15:15:38.67343Z","end":"2026-10-16T15:15:38.939359Z","steps":["trace[1965464870] 'agreement among raft nodes before linearized reading' (duration: 265.810418ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:15:38.939614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.79304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-16T15:15:38.939652Z","caller":"traceutil/trace.go:171","msg":"trace[1357607606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26751; }","duration":"274.870681ms","start":"2026-10-16T15:15:38.664773Z","end":"2026-10-16T15:15:38.939644Z","steps":["trace[1357607606] 'agreement among raft nodes before linearized reading' (duration: 274.765239ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:15:39.178169Z","caller":"traceutil/trace.go:171","msg":"trace[1060506874] transaction","detail":"{read_only:false; response_revision:26752; number_of_response:1; }","duration":"233.860212ms","start":"2026-10-16T15:15:38.944274Z","end":"2026-10-16T15:15:39.178134Z","steps":["trace[1060506874] 'process raft request' (duration: 163.170597ms)","trace[1060506874] 'compare' (duration: 70.083091ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:15:39.568408Z","caller":"traceutil/trace.go:171","msg":"trace[1929320507] transaction","detail":"{read_only:false; response_revision:26756; number_of_response:1; }","duration":"340.194426ms","start":"2026-10-16T15:15:39.228191Z","end":"2026-10-16T15:15:39.568386Z","steps":["trace[1929320507] 'process raft request' (duration: 340.155405ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:15:39.568833Z","caller":"traceutil/trace.go:171","msg":"trace[2120062888] transaction","detail":"{read_only:false; response_revision:26753; number_of_response:1; }","duration":"624.493389ms","start":"2026-10-16T15:15:38.944298Z","end":"2026-10-16T15:15:39.568791Z","steps":["trace[2120062888] 'process raft request' (duration: 539.373971ms)","trace[2120062888] 'compare' (duration: 84.450563ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:15:39.568839Z","caller":"traceutil/trace.go:171","msg":"trace[2088608946] transaction","detail":"{read_only:false; response_revision:26754; number_of_response:1; }","duration":"624.380736ms","start":"2026-10-16T15:15:38.944413Z","end":"2026-10-16T15:15:39.568793Z","steps":["trace[2088608946] 'process raft request' (duration: 623.847474ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:15:39.568862Z","caller":"traceutil/trace.go:171","msg":"trace[1480469214] transaction","detail":"{read_only:false; response_revision:26755; number_of_response:1; }","duration":"544.66615ms","start":"2026-10-16T15:15:39.024168Z","end":"2026-10-16T15:15:39.568834Z","steps":["trace[1480469214] 'process raft request' (duration: 544.133368ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:15:39.568854Z","caller":"traceutil/trace.go:171","msg":"trace[1399162620] linearizableReadLoop","detail":"{readStateIndex:27633; appliedIndex:27630; }","duration":"601.289689ms","start":"2026-10-16T15:15:38.967535Z","end":"2026-10-16T15:15:39.568825Z","steps":["trace[1399162620] 'read index received' (duration: 139.939667ms)","trace[1399162620] 'applied index is now lower than readState.Index' (duration: 461.347272ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:15:39.569037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:15:39.024152Z","time spent":"544.813143ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:15:39.569049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:15:38.944405Z","time spent":"624.54316ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:15:39.569041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:15:38.944278Z","time spent":"624.656462ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:15:39.569046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.498103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-16T15:15:39.56928Z","caller":"traceutil/trace.go:171","msg":"trace[1849355439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26756; }","duration":"601.748329ms","start":"2026-10-16T15:15:38.967501Z","end":"2026-10-16T15:15:39.56925Z","steps":["trace[1849355439] 'agreement among raft nodes before linearized reading' (duration: 601.396651ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:15:39.569311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.595826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T15:15:39.569335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.668807ms","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-10-16T15:15:39.569355Z","caller":"traceutil/trace.go:171","msg":"trace[1804854541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26756; }","duration":"444.683158ms","start":"2026-10-16T15:15:39.124657Z","end":"2026-10-16T15:15:39.56934Z","steps":["trace[1804854541] 'agreement among raft nodes before linearized reading' (duration: 444.606707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:15:39.569356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:15:38.967486Z","time spent":"601.856201ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-16T15:15:39.569377Z","caller":"traceutil/trace.go:171","msg":"trace[2096118273] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:26756; }","duration":"249.746899ms","start":"2026-10-16T15:15:39.319619Z","end":"2026-10-16T15:15:39.569366Z","steps":["trace[2096118273] 'agreement among raft nodes before linearized reading' (duration: 249.663487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:15:39.569387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:15:39.124633Z","time spent":"444.747559ms","remote":"127.0.0.1:35494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-16T15:15:39.569383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.231435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T15:15:39.569393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.091371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:15:39.569437Z","caller":"traceutil/trace.go:171","msg":"trace[951669967] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:26756; }","duration":"215.309276ms","start":"2026-10-16T15:15:39.35411Z","end":"2026-10-16T15:15:39.569419Z","steps":["trace[951669967] 'agreement among raft nodes before linearized reading' (duration: 215.213144ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:15:39.569459Z","caller":"traceutil/trace.go:171","msg":"trace[2010049844] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:26756; }","duration":"285.204994ms","start":"2026-10-16T15:15:39.284238Z","end":"2026-10-16T15:15:39.569443Z","steps":["trace[2010049844] 'agreement among raft nodes before linearized reading' (duration: 285.067571ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:15:39.569664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.292643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-10-16T15:15:39.568956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:15:39.228161Z","time spent":"340.388921ms","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:15:39.569729Z","caller":"traceutil/trace.go:171","msg":"trace[871090785] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:26756; }","duration":"317.388635ms","start":"2026-10-16T15:15:39.252321Z","end":"2026-10-16T15:15:39.56971Z","steps":["trace[871090785] 'agreement among raft nodes before linearized reading' (duration: 317.061387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:15:39.56976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:15:39.252307Z","time spent":"317.445987ms","remote":"127.0.0.1:35514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/162.253.55.209\" "} {"level":"info","ts":"2026-10-16T15:15:39.70876Z","caller":"traceutil/trace.go:171","msg":"trace[2050549128] transaction","detail":"{read_only:false; response_revision:26757; number_of_response:1; }","duration":"134.099496ms","start":"2026-10-16T15:15:39.574634Z","end":"2026-10-16T15:15:39.708734Z","steps":["trace[2050549128] 'process raft request' (duration: 133.972893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:15:40.025809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.176964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-16T15:15:40.025904Z","caller":"traceutil/trace.go:171","msg":"trace[641070902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26758; }","duration":"170.321578ms","start":"2026-10-16T15:15:39.85556Z","end":"2026-10-16T15:15:40.025882Z","steps":["trace[641070902] 'range keys from in-memory index tree' (duration: 169.894849ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:15.750797Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24900} {"level":"info","ts":"2026-10-16T15:17:15.928367Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24900,"took":"173.236631ms","hash":271637320,"current-db-size-bytes":59744256,"current-db-size":"60 MB","current-db-size-in-use-bytes":36085760,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-16T15:17:15.928445Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":271637320,"revision":24900,"compact-revision":21659} {"level":"info","ts":"2026-10-16T15:19:33.444557Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"53de2bdd9aa7b793","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T15:19:33.459074Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-16T15:19:33.459209Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-16T15:22:15.767853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27697} {"level":"info","ts":"2026-10-16T15:22:15.91091Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27697,"took":"138.636025ms","hash":1663563607,"current-db-size-bytes":59744256,"current-db-size":"60 MB","current-db-size-in-use-bytes":34377728,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-16T15:22:15.910979Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1663563607,"revision":27697,"compact-revision":24900} {"level":"info","ts":"2026-10-16T15:27:15.77616Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30340} {"level":"info","ts":"2026-10-16T15:27:15.922094Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30340,"took":"141.537772ms","hash":3339505119,"current-db-size-bytes":59744256,"current-db-size":"60 MB","current-db-size-in-use-bytes":30580736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T15:27:15.922168Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3339505119,"revision":30340,"compact-revision":27697} {"level":"info","ts":"2026-10-16T15:32:15.785822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32599} {"level":"info","ts":"2026-10-16T15:32:15.944415Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32599,"took":"154.39857ms","hash":3764651469,"current-db-size-bytes":60788736,"current-db-size":"61 MB","current-db-size-in-use-bytes":30195712,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T15:32:15.944488Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3764651469,"revision":32599,"compact-revision":30340} {"level":"info","ts":"2026-10-16T15:37:15.7935Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34828} {"level":"info","ts":"2026-10-16T15:37:15.933053Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34828,"took":"135.333234ms","hash":1686000632,"current-db-size-bytes":60788736,"current-db-size":"61 MB","current-db-size-in-use-bytes":31576064,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T15:37:15.933117Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1686000632,"revision":34828,"compact-revision":32599} {"level":"info","ts":"2026-10-16T15:40:52.255577Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"53de2bdd9aa7b793","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T15:40:52.263321Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-16T15:40:52.263445Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-16T15:42:15.801392Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37123} {"level":"info","ts":"2026-10-16T15:42:15.937686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37123,"took":"129.432282ms","hash":3461772645,"current-db-size-bytes":61214720,"current-db-size":"61 MB","current-db-size-in-use-bytes":32591872,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T15:42:15.937745Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3461772645,"revision":37123,"compact-revision":34828} {"level":"info","ts":"2026-10-16T15:47:15.810053Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39402} {"level":"info","ts":"2026-10-16T15:47:15.954553Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39402,"took":"140.153813ms","hash":1925351293,"current-db-size-bytes":61214720,"current-db-size":"61 MB","current-db-size-in-use-bytes":30695424,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T15:47:15.954637Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1925351293,"revision":39402,"compact-revision":37123}