{"level":"warn","ts":"2026-10-13T02:22:58.456961Z","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-13T02:22:58.457064Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.104: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.104:2380","--initial-cluster=instance=https://162.253.55.104:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.104:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.104: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-13T02:22:58.457137Z","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-13T02:22:58.457149Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.104:2380"]} {"level":"info","ts":"2026-10-13T02:22:58.457168Z","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-13T02:22:58.457973Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.104:2379"]} {"level":"info","ts":"2026-10-13T02:22:58.458055Z","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.104:2380"],"listen-peer-urls":["https://162.253.55.104:2380"],"advertise-client-urls":["https://162.253.55.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.104: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-13T02:22:58.460465Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.150583ms"} {"level":"info","ts":"2026-10-13T02:22:58.465177Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8572cc3a5351216d","cluster-id":"c1a156b8f69e6a79"} {"level":"info","ts":"2026-10-13T02:22:58.465242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d switched to configuration voters=()"} {"level":"info","ts":"2026-10-13T02:22:58.465258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became follower at term 0"} {"level":"info","ts":"2026-10-13T02:22:58.46527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8572cc3a5351216d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-13T02:22:58.465296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became follower at term 1"} {"level":"info","ts":"2026-10-13T02:22:58.465336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d switched to configuration voters=(9615972705267425645)"} {"level":"warn","ts":"2026-10-13T02:22:58.468502Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-13T02:22:58.470906Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-13T02:22:58.472118Z","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-13T02:22:58.474314Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8572cc3a5351216d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-13T02:22:58.474703Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8572cc3a5351216d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-13T02:22:58.474849Z","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-13T02:22:58.474902Z","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-13T02:22:58.47491Z","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-13T02:22:58.476784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d switched to configuration voters=(9615972705267425645)"} {"level":"info","ts":"2026-10-13T02:22:58.476889Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c1a156b8f69e6a79","local-member-id":"8572cc3a5351216d","added-peer-id":"8572cc3a5351216d","added-peer-peer-urls":["https://162.253.55.104:2380"]} {"level":"info","ts":"2026-10-13T02:22:58.47753Z","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-13T02:22:58.477705Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.104:2380"} {"level":"info","ts":"2026-10-13T02:22:58.477755Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8572cc3a5351216d","initial-advertise-peer-urls":["https://162.253.55.104:2380"],"listen-peer-urls":["https://162.253.55.104:2380"],"advertise-client-urls":["https://162.253.55.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-13T02:22:58.477796Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.104:2380"} {"level":"info","ts":"2026-10-13T02:22:58.477819Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-13T02:22:58.865817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d is starting a new election at term 1"} {"level":"info","ts":"2026-10-13T02:22:58.865879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became pre-candidate at term 1"} {"level":"info","ts":"2026-10-13T02:22:58.86593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d received MsgPreVoteResp from 8572cc3a5351216d at term 1"} {"level":"info","ts":"2026-10-13T02:22:58.865967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became candidate at term 2"} {"level":"info","ts":"2026-10-13T02:22:58.865981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d received MsgVoteResp from 8572cc3a5351216d at term 2"} {"level":"info","ts":"2026-10-13T02:22:58.865999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became leader at term 2"} {"level":"info","ts":"2026-10-13T02:22:58.866025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8572cc3a5351216d elected leader 8572cc3a5351216d at term 2"} {"level":"info","ts":"2026-10-13T02:22:58.86684Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T02:22:58.867768Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8572cc3a5351216d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.104:2379]}","request-path":"/0/members/8572cc3a5351216d/attributes","cluster-id":"c1a156b8f69e6a79","publish-timeout":"7s"} {"level":"info","ts":"2026-10-13T02:22:58.867785Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T02:22:58.867873Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T02:22:58.868146Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-13T02:22:58.868181Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-13T02:22:58.868351Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c1a156b8f69e6a79","local-member-id":"8572cc3a5351216d","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T02:22:58.868508Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T02:22:58.86855Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T02:22:58.87115Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.104:2379"} {"level":"info","ts":"2026-10-13T02:22:58.871158Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-13T02:23:50.526226Z","caller":"traceutil/trace.go:171","msg":"trace[818385466] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"124.70417ms","start":"2026-10-13T02:23:50.401493Z","end":"2026-10-13T02:23:50.526197Z","steps":["trace[818385466] 'process raft request' (duration: 52.023261ms)","trace[818385466] 'compare' (duration: 72.549385ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T02:23:51.031751Z","caller":"traceutil/trace.go:171","msg":"trace[873667892] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"249.278615ms","start":"2026-10-13T02:23:50.782446Z","end":"2026-10-13T02:23:51.031725Z","steps":["trace[873667892] 'process raft request' (duration: 248.734525ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:23:51.551844Z","caller":"traceutil/trace.go:171","msg":"trace[212095420] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"171.672274ms","start":"2026-10-13T02:23:51.380122Z","end":"2026-10-13T02:23:51.551795Z","steps":["trace[212095420] 'read index received' (duration: 163.062822ms)","trace[212095420] 'applied index is now lower than readState.Index' (duration: 8.608522ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T02:23:51.551934Z","caller":"traceutil/trace.go:171","msg":"trace[614040258] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"432.868993ms","start":"2026-10-13T02:23:51.119015Z","end":"2026-10-13T02:23:51.551884Z","steps":["trace[614040258] 'process raft request' (duration: 424.277922ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:23:51.552115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.970619ms","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-13T02:23:51.552265Z","caller":"traceutil/trace.go:171","msg":"trace[822350449] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:656; }","duration":"172.167033ms","start":"2026-10-13T02:23:51.380083Z","end":"2026-10-13T02:23:51.55225Z","steps":["trace[822350449] 'agreement among raft nodes before linearized reading' (duration: 171.820797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:23:51.552471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T02:23:51.118969Z","time spent":"433.059027ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T02:24:07.614852Z","caller":"traceutil/trace.go:171","msg":"trace[1337897446] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"110.607215ms","start":"2026-10-13T02:24:07.504225Z","end":"2026-10-13T02:24:07.614832Z","steps":["trace[1337897446] 'process raft request' (duration: 110.445192ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:24:07.679902Z","caller":"traceutil/trace.go:171","msg":"trace[192109773] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:852; }","duration":"120.937598ms","start":"2026-10-13T02:24:07.558929Z","end":"2026-10-13T02:24:07.679866Z","steps":["trace[192109773] 'read index received' (duration: 55.73672ms)","trace[192109773] 'applied index is now lower than readState.Index' (duration: 65.198738ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T02:24:07.680127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.958689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T02:24:07.680128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.165152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T02:24:07.680246Z","caller":"traceutil/trace.go:171","msg":"trace[1314409189] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:824; }","duration":"121.351466ms","start":"2026-10-13T02:24:07.558878Z","end":"2026-10-13T02:24:07.680229Z","steps":["trace[1314409189] 'agreement among raft nodes before linearized reading' (duration: 121.119852ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:24:07.680174Z","caller":"traceutil/trace.go:171","msg":"trace[1918310895] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:824; }","duration":"121.054121ms","start":"2026-10-13T02:24:07.559107Z","end":"2026-10-13T02:24:07.680161Z","steps":["trace[1918310895] 'agreement among raft nodes before linearized reading' (duration: 120.926878ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:24:07.680742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.697689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T02:24:07.680864Z","caller":"traceutil/trace.go:171","msg":"trace[242447064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:824; }","duration":"107.859841ms","start":"2026-10-13T02:24:07.572978Z","end":"2026-10-13T02:24:07.680838Z","steps":["trace[242447064] 'agreement among raft nodes before linearized reading' (duration: 107.669098ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:24:37.972245Z","caller":"traceutil/trace.go:171","msg":"trace[1193311025] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"120.874276ms","start":"2026-10-13T02:24:37.851348Z","end":"2026-10-13T02:24:37.972222Z","steps":["trace[1193311025] 'process raft request' (duration: 120.704923ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:25:25.146563Z","caller":"traceutil/trace.go:171","msg":"trace[1775083745] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"265.595711ms","start":"2026-10-13T02:25:24.88094Z","end":"2026-10-13T02:25:25.146535Z","steps":["trace[1775083745] 'process raft request' (duration: 265.447959ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:25:25.192328Z","caller":"traceutil/trace.go:171","msg":"trace[331645200] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"258.424829ms","start":"2026-10-13T02:25:24.933866Z","end":"2026-10-13T02:25:25.192291Z","steps":["trace[331645200] 'process raft request' (duration: 258.154764ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:25:41.713566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.376012ms","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-13T02:25:41.713663Z","caller":"traceutil/trace.go:171","msg":"trace[741588462] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2034; }","duration":"108.546625ms","start":"2026-10-13T02:25:41.605098Z","end":"2026-10-13T02:25:41.713645Z","steps":["trace[741588462] 'range keys from in-memory index tree' (duration: 108.190259ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:26:14.374829Z","caller":"traceutil/trace.go:171","msg":"trace[1909289391] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"100.689309ms","start":"2026-10-13T02:26:14.274115Z","end":"2026-10-13T02:26:14.374804Z","steps":["trace[1909289391] 'process raft request' (duration: 100.546497ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:26:20.73042Z","caller":"traceutil/trace.go:171","msg":"trace[702017555] linearizableReadLoop","detail":"{readStateIndex:2333; appliedIndex:2332; }","duration":"157.480832ms","start":"2026-10-13T02:26:20.572917Z","end":"2026-10-13T02:26:20.730398Z","steps":["trace[702017555] 'read index received' (duration: 157.259358ms)","trace[702017555] 'applied index is now lower than readState.Index' (duration: 220.494µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T02:26:20.730526Z","caller":"traceutil/trace.go:171","msg":"trace[491635086] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"335.113995ms","start":"2026-10-13T02:26:20.395389Z","end":"2026-10-13T02:26:20.730503Z","steps":["trace[491635086] 'process raft request' (duration: 334.84285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:26:20.730635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.758882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-10-13T02:26:20.730607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.679515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T02:26:20.730677Z","caller":"traceutil/trace.go:171","msg":"trace[587464186] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2249; }","duration":"124.841923ms","start":"2026-10-13T02:26:20.605824Z","end":"2026-10-13T02:26:20.730666Z","steps":["trace[587464186] 'agreement among raft nodes before linearized reading' (duration: 124.713831ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:26:20.730703Z","caller":"traceutil/trace.go:171","msg":"trace[1192739184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2249; }","duration":"157.839588ms","start":"2026-10-13T02:26:20.572841Z","end":"2026-10-13T02:26:20.73068Z","steps":["trace[1192739184] 'agreement among raft nodes before linearized reading' (duration: 157.674205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:26:20.731031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T02:26:20.395344Z","time spent":"335.231247ms","remote":"127.0.0.1:33938","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-13T02:26:20.98348Z","caller":"traceutil/trace.go:171","msg":"trace[1566538838] linearizableReadLoop","detail":"{readStateIndex:2334; appliedIndex:2333; }","duration":"237.41485ms","start":"2026-10-13T02:26:20.746041Z","end":"2026-10-13T02:26:20.983455Z","steps":["trace[1566538838] 'read index received' (duration: 160.931226ms)","trace[1566538838] 'applied index is now lower than readState.Index' (duration: 76.482324ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T02:26:20.983605Z","caller":"traceutil/trace.go:171","msg":"trace[330577466] transaction","detail":"{read_only:false; response_revision:2250; number_of_response:1; }","duration":"248.883501ms","start":"2026-10-13T02:26:20.734705Z","end":"2026-10-13T02:26:20.983589Z","steps":["trace[330577466] 'process raft request' (duration: 172.321475ms)","trace[330577466] 'compare' (duration: 76.278121ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T02:26:20.983939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.881369ms","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-13T02:26:20.984007Z","caller":"traceutil/trace.go:171","msg":"trace[2094317002] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2250; }","duration":"237.984001ms","start":"2026-10-13T02:26:20.746003Z","end":"2026-10-13T02:26:20.983987Z","steps":["trace[2094317002] 'agreement among raft nodes before linearized reading' (duration: 237.783087ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:30:45.886622Z","caller":"traceutil/trace.go:171","msg":"trace[129671071] transaction","detail":"{read_only:false; response_revision:4053; number_of_response:1; }","duration":"148.072631ms","start":"2026-10-13T02:30:45.738525Z","end":"2026-10-13T02:30:45.886598Z","steps":["trace[129671071] 'process raft request' (duration: 147.926938ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:32:24.31923Z","caller":"traceutil/trace.go:171","msg":"trace[1963907931] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"136.452395ms","start":"2026-10-13T02:32:24.182743Z","end":"2026-10-13T02:32:24.319196Z","steps":["trace[1963907931] 'process raft request' (duration: 136.262372ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:32:24.465621Z","caller":"traceutil/trace.go:171","msg":"trace[1917282817] linearizableReadLoop","detail":"{readStateIndex:5495; appliedIndex:5494; }","duration":"138.562954ms","start":"2026-10-13T02:32:24.327032Z","end":"2026-10-13T02:32:24.465595Z","steps":["trace[1917282817] 'read index received' (duration: 138.177557ms)","trace[1917282817] 'applied index is now lower than readState.Index' (duration: 384.427µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T02:32:24.465713Z","caller":"traceutil/trace.go:171","msg":"trace[540357249] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"162.720053ms","start":"2026-10-13T02:32:24.302938Z","end":"2026-10-13T02:32:24.465658Z","steps":["trace[540357249] 'process raft request' (duration: 162.393977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:32:24.465881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.830589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f893685e-d1e8-4bf4-8447-517d65e26e19\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-10-13T02:32:24.465922Z","caller":"traceutil/trace.go:171","msg":"trace[386419178] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f893685e-d1e8-4bf4-8447-517d65e26e19; range_end:; response_count:1; response_revision:5295; }","duration":"138.933981ms","start":"2026-10-13T02:32:24.326976Z","end":"2026-10-13T02:32:24.46591Z","steps":["trace[386419178] 'agreement among raft nodes before linearized reading' (duration: 138.760228ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:32:40.893388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.389457ms","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-13T02:32:40.893532Z","caller":"traceutil/trace.go:171","msg":"trace[815706862] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:5571; }","duration":"162.608981ms","start":"2026-10-13T02:32:40.730897Z","end":"2026-10-13T02:32:40.893506Z","steps":["trace[815706862] 'count revisions from in-memory index tree' (duration: 162.281225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:32:49.261807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.951862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T02:32:49.261958Z","caller":"traceutil/trace.go:171","msg":"trace[1096229419] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5749; }","duration":"188.197097ms","start":"2026-10-13T02:32:49.073732Z","end":"2026-10-13T02:32:49.261929Z","steps":["trace[1096229419] 'range keys from in-memory index tree' (duration: 187.793739ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:32:49.262127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.3051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T02:32:49.26237Z","caller":"traceutil/trace.go:171","msg":"trace[538218150] linearizableReadLoop","detail":"{readStateIndex:5963; appliedIndex:5961; }","duration":"163.347395ms","start":"2026-10-13T02:32:49.098997Z","end":"2026-10-13T02:32:49.262345Z","steps":["trace[538218150] 'read index received' (duration: 52.482715ms)","trace[538218150] 'applied index is now lower than readState.Index' (duration: 110.86278ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T02:32:49.262379Z","caller":"traceutil/trace.go:171","msg":"trace[1041757978] transaction","detail":"{read_only:false; response_revision:5750; number_of_response:1; }","duration":"185.098289ms","start":"2026-10-13T02:32:49.077253Z","end":"2026-10-13T02:32:49.262351Z","steps":["trace[1041757978] 'process raft request' (duration: 74.280109ms)","trace[1041757978] 'compare' (duration: 110.148157ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T02:32:49.262439Z","caller":"traceutil/trace.go:171","msg":"trace[1312945242] transaction","detail":"{read_only:false; response_revision:5751; number_of_response:1; }","duration":"182.385719ms","start":"2026-10-13T02:32:49.08002Z","end":"2026-10-13T02:32:49.262406Z","steps":["trace[1312945242] 'process raft request' (duration: 182.243216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:32:49.262787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.736602ms","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-13T02:32:49.262928Z","caller":"traceutil/trace.go:171","msg":"trace[990391839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5751; }","duration":"163.957707ms","start":"2026-10-13T02:32:49.09894Z","end":"2026-10-13T02:32:49.262897Z","steps":["trace[990391839] 'agreement among raft nodes before linearized reading' (duration: 163.540969ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:32:49.3379Z","caller":"traceutil/trace.go:171","msg":"trace[661961239] transaction","detail":"{read_only:false; response_revision:5752; number_of_response:1; }","duration":"109.982635ms","start":"2026-10-13T02:32:49.227865Z","end":"2026-10-13T02:32:49.337848Z","steps":["trace[661961239] 'process raft request' (duration: 109.771421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:32:49.338265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.922546ms","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-13T02:32:49.338357Z","caller":"traceutil/trace.go:171","msg":"trace[1964181428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5752; }","duration":"234.057709ms","start":"2026-10-13T02:32:49.104277Z","end":"2026-10-13T02:32:49.338335Z","steps":["trace[1964181428] 'agreement among raft nodes before linearized reading' (duration: 233.592971ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:32:49.389947Z","caller":"traceutil/trace.go:171","msg":"trace[1942738550] transaction","detail":"{read_only:false; response_revision:5753; number_of_response:1; }","duration":"122.491405ms","start":"2026-10-13T02:32:49.267414Z","end":"2026-10-13T02:32:49.389906Z","steps":["trace[1942738550] 'process raft request' (duration: 97.614363ms)","trace[1942738550] 'compare' (duration: 24.725309ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T02:32:49.390356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.698202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bl4wt\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-13T02:32:49.390441Z","caller":"traceutil/trace.go:171","msg":"trace[522185289] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bl4wt; range_end:; response_count:1; response_revision:5755; }","duration":"113.856655ms","start":"2026-10-13T02:32:49.276566Z","end":"2026-10-13T02:32:49.390423Z","steps":["trace[522185289] 'agreement among raft nodes before linearized reading' (duration: 113.58532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:32:49.390343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.79002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T02:32:49.39057Z","caller":"traceutil/trace.go:171","msg":"trace[1198425704] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5755; }","duration":"124.032976ms","start":"2026-10-13T02:32:49.266505Z","end":"2026-10-13T02:32:49.390538Z","steps":["trace[1198425704] 'agreement among raft nodes before linearized reading' (duration: 123.524105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:32:49.641154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.986664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T02:32:49.641267Z","caller":"traceutil/trace.go:171","msg":"trace[24745579] linearizableReadLoop","detail":"{readStateIndex:5968; appliedIndex:5967; }","duration":"137.175449ms","start":"2026-10-13T02:32:49.504074Z","end":"2026-10-13T02:32:49.641249Z","steps":["trace[24745579] 'read index received' (duration: 6.93056ms)","trace[24745579] 'applied index is now lower than readState.Index' (duration: 130.243789ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T02:32:49.641322Z","caller":"traceutil/trace.go:171","msg":"trace[1270529438] transaction","detail":"{read_only:false; response_revision:5756; number_of_response:1; }","duration":"157.503576ms","start":"2026-10-13T02:32:49.483785Z","end":"2026-10-13T02:32:49.641289Z","steps":["trace[1270529438] 'process raft request' (duration: 27.291967ms)","trace[1270529438] 'compare' (duration: 129.868432ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T02:32:49.64138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.301671ms","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-13T02:32:49.641407Z","caller":"traceutil/trace.go:171","msg":"trace[762510376] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5756; }","duration":"137.347202ms","start":"2026-10-13T02:32:49.50405Z","end":"2026-10-13T02:32:49.641397Z","steps":["trace[762510376] 'agreement among raft nodes before linearized reading' (duration: 137.24393ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:32:59.101088Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2975} {"level":"info","ts":"2026-10-13T02:32:59.180384Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2975,"took":"77.703044ms","hash":3631682721,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":32542720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-13T02:32:59.180474Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3631682721,"revision":2975,"compact-revision":-1} {"level":"info","ts":"2026-10-13T02:37:59.110889Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5864} {"level":"info","ts":"2026-10-13T02:37:59.206425Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5864,"took":"93.414274ms","hash":1840792395,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":26890240,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-13T02:37:59.20661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1840792395,"revision":5864,"compact-revision":2975} {"level":"info","ts":"2026-10-13T02:39:15.038889Z","caller":"traceutil/trace.go:171","msg":"trace[39569624] transaction","detail":"{read_only:false; response_revision:8667; number_of_response:1; }","duration":"257.916064ms","start":"2026-10-13T02:39:14.780938Z","end":"2026-10-13T02:39:15.038854Z","steps":["trace[39569624] 'process raft request' (duration: 257.69094ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:39:15.040211Z","caller":"traceutil/trace.go:171","msg":"trace[256414929] linearizableReadLoop","detail":"{readStateIndex:8973; appliedIndex:8971; }","duration":"221.901389ms","start":"2026-10-13T02:39:14.818274Z","end":"2026-10-13T02:39:15.040175Z","steps":["trace[256414929] 'read index received' (duration: 220.436781ms)","trace[256414929] 'applied index is now lower than readState.Index' (duration: 1.462978ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T02:39:15.040462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.152563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T02:39:15.040522Z","caller":"traceutil/trace.go:171","msg":"trace[1056845265] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:8669; }","duration":"222.290056ms","start":"2026-10-13T02:39:14.818214Z","end":"2026-10-13T02:39:15.040504Z","steps":["trace[1056845265] 'agreement among raft nodes before linearized reading' (duration: 222.091682ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:39:15.040666Z","caller":"traceutil/trace.go:171","msg":"trace[877361450] transaction","detail":"{read_only:false; response_revision:8668; number_of_response:1; }","duration":"254.941678ms","start":"2026-10-13T02:39:14.785701Z","end":"2026-10-13T02:39:15.040643Z","steps":["trace[877361450] 'process raft request' (duration: 254.203445ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:39:15.040839Z","caller":"traceutil/trace.go:171","msg":"trace[456716] transaction","detail":"{read_only:false; response_revision:8669; number_of_response:1; }","duration":"197.671353ms","start":"2026-10-13T02:39:14.843157Z","end":"2026-10-13T02:39:15.040828Z","steps":["trace[456716] 'process raft request' (duration: 196.95428ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:39:17.354988Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000231c.wal"} {"level":"info","ts":"2026-10-13T02:39:26.670234Z","caller":"traceutil/trace.go:171","msg":"trace[1983595257] transaction","detail":"{read_only:false; response_revision:8729; number_of_response:1; }","duration":"116.275283ms","start":"2026-10-13T02:39:26.55393Z","end":"2026-10-13T02:39:26.670206Z","steps":["trace[1983595257] 'process raft request' (duration: 116.10719ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:39:27.502045Z","caller":"traceutil/trace.go:171","msg":"trace[1434466169] transaction","detail":"{read_only:false; response_revision:8733; number_of_response:1; }","duration":"209.398884ms","start":"2026-10-13T02:39:27.292615Z","end":"2026-10-13T02:39:27.502014Z","steps":["trace[1434466169] 'process raft request' (duration: 209.19465ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:39:56.953631Z","caller":"traceutil/trace.go:171","msg":"trace[1114720884] transaction","detail":"{read_only:false; response_revision:8942; number_of_response:1; }","duration":"127.190358ms","start":"2026-10-13T02:39:56.826414Z","end":"2026-10-13T02:39:56.953604Z","steps":["trace[1114720884] 'process raft request' (duration: 124.284974ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:40:00.673915Z","caller":"traceutil/trace.go:171","msg":"trace[599096301] linearizableReadLoop","detail":"{readStateIndex:9277; appliedIndex:9276; }","duration":"100.609941ms","start":"2026-10-13T02:40:00.573276Z","end":"2026-10-13T02:40:00.673886Z","steps":["trace[599096301] 'read index received' (duration: 100.393837ms)","trace[599096301] 'applied index is now lower than readState.Index' (duration: 214.824µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T02:40:00.673978Z","caller":"traceutil/trace.go:171","msg":"trace[1899561263] transaction","detail":"{read_only:false; response_revision:8960; number_of_response:1; }","duration":"119.759049ms","start":"2026-10-13T02:40:00.554193Z","end":"2026-10-13T02:40:00.673952Z","steps":["trace[1899561263] 'process raft request' (duration: 119.509735ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:40:00.674131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.825176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T02:40:00.674234Z","caller":"traceutil/trace.go:171","msg":"trace[2039024546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8960; }","duration":"100.967498ms","start":"2026-10-13T02:40:00.573242Z","end":"2026-10-13T02:40:00.67421Z","steps":["trace[2039024546] 'agreement among raft nodes before linearized reading' (duration: 100.776643ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:40:00.823308Z","caller":"traceutil/trace.go:171","msg":"trace[1174075127] transaction","detail":"{read_only:false; response_revision:8961; number_of_response:1; }","duration":"144.645436ms","start":"2026-10-13T02:40:00.678615Z","end":"2026-10-13T02:40:00.82326Z","steps":["trace[1174075127] 'process raft request' (duration: 89.650067ms)","trace[1174075127] 'compare' (duration: 54.764645ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T02:40:08.98729Z","caller":"traceutil/trace.go:171","msg":"trace[1438467751] transaction","detail":"{read_only:false; response_revision:9005; number_of_response:1; }","duration":"127.481653ms","start":"2026-10-13T02:40:08.859779Z","end":"2026-10-13T02:40:08.987261Z","steps":["trace[1438467751] 'process raft request' (duration: 127.252189ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:40:09.886131Z","caller":"traceutil/trace.go:171","msg":"trace[1002309469] linearizableReadLoop","detail":"{readStateIndex:9329; appliedIndex:9328; }","duration":"132.326764ms","start":"2026-10-13T02:40:09.753763Z","end":"2026-10-13T02:40:09.886089Z","steps":["trace[1002309469] 'read index received' (duration: 132.042729ms)","trace[1002309469] 'applied index is now lower than readState.Index' (duration: 282.135µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T02:40:09.886234Z","caller":"traceutil/trace.go:171","msg":"trace[249675888] transaction","detail":"{read_only:false; response_revision:9010; number_of_response:1; }","duration":"135.83833ms","start":"2026-10-13T02:40:09.750364Z","end":"2026-10-13T02:40:09.886202Z","steps":["trace[249675888] 'process raft request' (duration: 135.472104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:40:09.886389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.585849ms","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-13T02:40:09.886487Z","caller":"traceutil/trace.go:171","msg":"trace[1037270080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9010; }","duration":"132.728721ms","start":"2026-10-13T02:40:09.753736Z","end":"2026-10-13T02:40:09.886465Z","steps":["trace[1037270080] 'agreement among raft nodes before linearized reading' (duration: 132.503207ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:41:12.424539Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8572cc3a5351216d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-13T02:41:12.432838Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-13T02:41:12.432998Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-13T02:42:59.117854Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8036} {"level":"info","ts":"2026-10-13T02:42:59.190778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8036,"took":"70.542207ms","hash":639795365,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":27860992,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-13T02:42:59.190853Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":639795365,"revision":8036,"compact-revision":5864} {"level":"info","ts":"2026-10-13T02:45:52.559427Z","caller":"traceutil/trace.go:171","msg":"trace[2095480750] transaction","detail":"{read_only:false; response_revision:11497; number_of_response:1; }","duration":"108.409506ms","start":"2026-10-13T02:45:52.45098Z","end":"2026-10-13T02:45:52.559389Z","steps":["trace[2095480750] 'process raft request' (duration: 86.347143ms)","trace[2095480750] 'compare' (duration: 21.87569ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T02:47:20.27528Z","caller":"traceutil/trace.go:171","msg":"trace[1624346226] transaction","detail":"{read_only:false; response_revision:12312; number_of_response:1; }","duration":"204.351134ms","start":"2026-10-13T02:47:20.070901Z","end":"2026-10-13T02:47:20.275253Z","steps":["trace[1624346226] 'process raft request' (duration: 204.154691ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:47:20.296037Z","caller":"traceutil/trace.go:171","msg":"trace[454054461] transaction","detail":"{read_only:false; response_revision:12313; number_of_response:1; }","duration":"223.038203ms","start":"2026-10-13T02:47:20.072978Z","end":"2026-10-13T02:47:20.296016Z","steps":["trace[454054461] 'process raft request' (duration: 222.709757ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:47:59.125238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10401} {"level":"info","ts":"2026-10-13T02:47:59.210915Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10401,"took":"83.119261ms","hash":1524934528,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":25255936,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-13T02:47:59.210981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1524934528,"revision":10401,"compact-revision":8036} {"level":"info","ts":"2026-10-13T02:52:59.132434Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12588} {"level":"info","ts":"2026-10-13T02:52:59.21619Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12588,"took":"81.086907ms","hash":2937070495,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":27762688,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-13T02:52:59.216266Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2937070495,"revision":12588,"compact-revision":10401} {"level":"info","ts":"2026-10-13T02:53:54.877295Z","caller":"traceutil/trace.go:171","msg":"trace[1067301560] transaction","detail":"{read_only:false; response_revision:14937; number_of_response:1; }","duration":"160.564383ms","start":"2026-10-13T02:53:54.716705Z","end":"2026-10-13T02:53:54.877269Z","steps":["trace[1067301560] 'process raft request' (duration: 160.417441ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:53:54.945209Z","caller":"traceutil/trace.go:171","msg":"trace[1150834407] transaction","detail":"{read_only:false; response_revision:14938; number_of_response:1; }","duration":"221.565755ms","start":"2026-10-13T02:53:54.72362Z","end":"2026-10-13T02:53:54.945186Z","steps":["trace[1150834407] 'process raft request' (duration: 221.376272ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:53:54.945638Z","caller":"traceutil/trace.go:171","msg":"trace[1907627132] transaction","detail":"{read_only:false; response_revision:14939; number_of_response:1; }","duration":"214.892421ms","start":"2026-10-13T02:53:54.730722Z","end":"2026-10-13T02:53:54.945614Z","steps":["trace[1907627132] 'process raft request' (duration: 214.406112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:54:03.925662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.923087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T02:54:03.925778Z","caller":"traceutil/trace.go:171","msg":"trace[30811046] transaction","detail":"{read_only:false; response_revision:14985; number_of_response:1; }","duration":"141.232222ms","start":"2026-10-13T02:54:03.784528Z","end":"2026-10-13T02:54:03.92576Z","steps":["trace[30811046] 'process raft request' (duration: 23.114762ms)","trace[30811046] 'compare' (duration: 117.810384ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T02:54:12.956515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.309259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-13T02:54:12.956583Z","caller":"traceutil/trace.go:171","msg":"trace[982987069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15042; }","duration":"285.420091ms","start":"2026-10-13T02:54:12.671149Z","end":"2026-10-13T02:54:12.956569Z","steps":["trace[982987069] 'range keys from in-memory index tree' (duration: 285.188937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:54:12.956517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.314443ms","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-13T02:54:12.95673Z","caller":"traceutil/trace.go:171","msg":"trace[197762463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15042; }","duration":"297.522257ms","start":"2026-10-13T02:54:12.659164Z","end":"2026-10-13T02:54:12.956686Z","steps":["trace[197762463] 'range keys from in-memory index tree' (duration: 297.205211ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:54:37.226878Z","caller":"traceutil/trace.go:171","msg":"trace[2040678480] transaction","detail":"{read_only:false; response_revision:15364; number_of_response:1; }","duration":"110.089549ms","start":"2026-10-13T02:54:37.116764Z","end":"2026-10-13T02:54:37.226853Z","steps":["trace[2040678480] 'process raft request' (duration: 109.920976ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:54:37.254796Z","caller":"traceutil/trace.go:171","msg":"trace[411201905] transaction","detail":"{read_only:false; response_revision:15365; number_of_response:1; }","duration":"119.873463ms","start":"2026-10-13T02:54:37.13487Z","end":"2026-10-13T02:54:37.254743Z","steps":["trace[411201905] 'process raft request' (duration: 119.470316ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:54:37.255002Z","caller":"traceutil/trace.go:171","msg":"trace[290266797] transaction","detail":"{read_only:false; response_revision:15366; number_of_response:1; }","duration":"102.964306ms","start":"2026-10-13T02:54:37.152003Z","end":"2026-10-13T02:54:37.254967Z","steps":["trace[290266797] 'process raft request' (duration: 102.59895ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:55:29.801399Z","caller":"traceutil/trace.go:171","msg":"trace[1162042048] linearizableReadLoop","detail":"{readStateIndex:16546; appliedIndex:16545; }","duration":"156.995531ms","start":"2026-10-13T02:55:29.644377Z","end":"2026-10-13T02:55:29.801372Z","steps":["trace[1162042048] 'read index received' (duration: 156.818928ms)","trace[1162042048] 'applied index is now lower than readState.Index' (duration: 175.673µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T02:55:29.801457Z","caller":"traceutil/trace.go:171","msg":"trace[186314332] transaction","detail":"{read_only:false; response_revision:15997; number_of_response:1; }","duration":"165.270506ms","start":"2026-10-13T02:55:29.63613Z","end":"2026-10-13T02:55:29.8014Z","steps":["trace[186314332] 'process raft request' (duration: 165.104923ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:55:29.801649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.249206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-hfhcq\" ","response":"range_response_count:1 size:22100"} {"level":"info","ts":"2026-10-13T02:55:29.801711Z","caller":"traceutil/trace.go:171","msg":"trace[1918232332] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-hfhcq; range_end:; response_count:1; response_revision:15997; }","duration":"157.358369ms","start":"2026-10-13T02:55:29.644338Z","end":"2026-10-13T02:55:29.801696Z","steps":["trace[1918232332] 'agreement among raft nodes before linearized reading' (duration: 157.161525ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:55:54.591541Z","caller":"traceutil/trace.go:171","msg":"trace[2051926432] linearizableReadLoop","detail":"{readStateIndex:16680; appliedIndex:16679; }","duration":"316.218205ms","start":"2026-10-13T02:55:54.275294Z","end":"2026-10-13T02:55:54.591512Z","steps":["trace[2051926432] 'read index received' (duration: 315.96941ms)","trace[2051926432] 'applied index is now lower than readState.Index' (duration: 247.695µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T02:55:54.59167Z","caller":"traceutil/trace.go:171","msg":"trace[83891882] transaction","detail":"{read_only:false; response_revision:16126; number_of_response:1; }","duration":"363.63818ms","start":"2026-10-13T02:55:54.228003Z","end":"2026-10-13T02:55:54.591641Z","steps":["trace[83891882] 'process raft request' (duration: 363.276464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:55:54.591838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.50197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-10-13T02:55:54.591893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T02:55:54.227985Z","time spent":"363.776154ms","remote":"127.0.0.1:33938","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-13T02:55:54.591911Z","caller":"traceutil/trace.go:171","msg":"trace[1219685531] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16126; }","duration":"316.643533ms","start":"2026-10-13T02:55:54.275245Z","end":"2026-10-13T02:55:54.591889Z","steps":["trace[1219685531] 'agreement among raft nodes before linearized reading' (duration: 316.391428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:55:54.591972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T02:55:54.275221Z","time spent":"316.736275ms","remote":"127.0.0.1:33912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-10-13T02:55:54.608311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.652683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-13T02:55:54.608364Z","caller":"traceutil/trace.go:171","msg":"trace[1876039465] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16127; }","duration":"332.734434ms","start":"2026-10-13T02:55:54.275617Z","end":"2026-10-13T02:55:54.608351Z","steps":["trace[1876039465] 'agreement among raft nodes before linearized reading' (duration: 332.575041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:55:54.608343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.258657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T02:55:54.608396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T02:55:54.275607Z","time spent":"332.781014ms","remote":"127.0.0.1:33816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":692,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-10-13T02:55:54.608436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.441423ms","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-13T02:55:54.60844Z","caller":"traceutil/trace.go:171","msg":"trace[1366228530] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16127; }","duration":"210.378899ms","start":"2026-10-13T02:55:54.398031Z","end":"2026-10-13T02:55:54.60841Z","steps":["trace[1366228530] 'agreement among raft nodes before linearized reading' (duration: 210.215286ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:55:54.608486Z","caller":"traceutil/trace.go:171","msg":"trace[1240309950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16127; }","duration":"208.530285ms","start":"2026-10-13T02:55:54.39994Z","end":"2026-10-13T02:55:54.60847Z","steps":["trace[1240309950] 'agreement among raft nodes before linearized reading' (duration: 208.415793ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:55:54.60846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.644212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T02:55:54.608574Z","caller":"traceutil/trace.go:171","msg":"trace[1633226572] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16127; }","duration":"204.786664ms","start":"2026-10-13T02:55:54.403761Z","end":"2026-10-13T02:55:54.608548Z","steps":["trace[1633226572] 'agreement among raft nodes before linearized reading' (duration: 204.448228ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:55:54.608571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.787414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T02:55:54.608632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.684085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-13T02:55:54.608649Z","caller":"traceutil/trace.go:171","msg":"trace[34955613] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16127; }","duration":"204.889796ms","start":"2026-10-13T02:55:54.403738Z","end":"2026-10-13T02:55:54.608627Z","steps":["trace[34955613] 'agreement among raft nodes before linearized reading' (duration: 204.736523ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:55:54.608669Z","caller":"traceutil/trace.go:171","msg":"trace[559711619] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16127; }","duration":"210.758125ms","start":"2026-10-13T02:55:54.397902Z","end":"2026-10-13T02:55:54.60866Z","steps":["trace[559711619] 'agreement among raft nodes before linearized reading' (duration: 210.669104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:55:54.608657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.461965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-13T02:55:54.608764Z","caller":"traceutil/trace.go:171","msg":"trace[1971083206] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16127; }","duration":"124.596868ms","start":"2026-10-13T02:55:54.484144Z","end":"2026-10-13T02:55:54.608741Z","steps":["trace[1971083206] 'agreement among raft nodes before linearized reading' (duration: 124.278472ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:55:54.608814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.010889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T02:55:54.60884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.073798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-10-13T02:55:54.608845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.922499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T02:55:54.608873Z","caller":"traceutil/trace.go:171","msg":"trace[1382681971] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16127; }","duration":"184.129339ms","start":"2026-10-13T02:55:54.424734Z","end":"2026-10-13T02:55:54.608864Z","steps":["trace[1382681971] 'agreement among raft nodes before linearized reading' (duration: 183.993926ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:55:54.608906Z","caller":"traceutil/trace.go:171","msg":"trace[356716296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16127; }","duration":"233.123063ms","start":"2026-10-13T02:55:54.375769Z","end":"2026-10-13T02:55:54.608892Z","steps":["trace[356716296] 'agreement among raft nodes before linearized reading' (duration: 233.022232ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:55:54.608853Z","caller":"traceutil/trace.go:171","msg":"trace[1355944662] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16127; }","duration":"333.05107ms","start":"2026-10-13T02:55:54.275793Z","end":"2026-10-13T02:55:54.608844Z","steps":["trace[1355944662] 'agreement among raft nodes before linearized reading' (duration: 332.993519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:55:54.608831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.395973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bl4wt\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-10-13T02:55:54.609032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.439659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-10-13T02:55:54.609042Z","caller":"traceutil/trace.go:171","msg":"trace[989349452] transaction","detail":"{read_only:false; response_revision:16127; number_of_response:1; }","duration":"107.147451ms","start":"2026-10-13T02:55:54.501881Z","end":"2026-10-13T02:55:54.609029Z","steps":["trace[989349452] 'process raft request' (duration: 106.205424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:55:54.609029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.893596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T02:55:54.609066Z","caller":"traceutil/trace.go:171","msg":"trace[1583577001] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16127; }","duration":"188.493389ms","start":"2026-10-13T02:55:54.420564Z","end":"2026-10-13T02:55:54.609058Z","steps":["trace[1583577001] 'agreement among raft nodes before linearized reading' (duration: 188.408148ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:55:54.609086Z","caller":"traceutil/trace.go:171","msg":"trace[157999564] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16127; }","duration":"204.962928ms","start":"2026-10-13T02:55:54.404107Z","end":"2026-10-13T02:55:54.609069Z","steps":["trace[157999564] 'agreement among raft nodes before linearized reading' (duration: 204.885376ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T02:55:54.609098Z","caller":"traceutil/trace.go:171","msg":"trace[995382538] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bl4wt; range_end:; response_count:1; response_revision:16127; }","duration":"207.678728ms","start":"2026-10-13T02:55:54.401358Z","end":"2026-10-13T02:55:54.609036Z","steps":["trace[995382538] 'agreement among raft nodes before linearized reading' (duration: 206.963415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T02:55:54.608984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T02:55:54.275762Z","time spent":"333.211783ms","remote":"127.0.0.1:33912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-10-13T02:57:59.140387Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14570} {"level":"info","ts":"2026-10-13T02:57:59.231945Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14570,"took":"88.630816ms","hash":3091511707,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":31240192,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-13T02:57:59.23202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3091511707,"revision":14570,"compact-revision":12588} {"level":"warn","ts":"2026-10-13T03:02:02.352018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.565995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-13T03:02:02.35212Z","caller":"traceutil/trace.go:171","msg":"trace[1267409866] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:18844; }","duration":"121.766049ms","start":"2026-10-13T03:02:02.230332Z","end":"2026-10-13T03:02:02.352098Z","steps":["trace[1267409866] 'count revisions from in-memory index tree' (duration: 121.478723ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:02:09.236042Z","caller":"traceutil/trace.go:171","msg":"trace[1773364606] transaction","detail":"{read_only:false; response_revision:18894; number_of_response:1; }","duration":"110.592379ms","start":"2026-10-13T03:02:09.125408Z","end":"2026-10-13T03:02:09.236Z","steps":["trace[1773364606] 'process raft request' (duration: 110.210402ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:02:09.236042Z","caller":"traceutil/trace.go:171","msg":"trace[139569703] transaction","detail":"{read_only:false; response_revision:18893; number_of_response:1; }","duration":"110.862264ms","start":"2026-10-13T03:02:09.125123Z","end":"2026-10-13T03:02:09.235985Z","steps":["trace[139569703] 'process raft request' (duration: 110.314793ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:02:10.171668Z","caller":"traceutil/trace.go:171","msg":"trace[1938041118] transaction","detail":"{read_only:false; response_revision:18896; number_of_response:1; }","duration":"193.224966ms","start":"2026-10-13T03:02:09.978401Z","end":"2026-10-13T03:02:10.171626Z","steps":["trace[1938041118] 'process raft request' (duration: 193.006872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:02:10.171855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.869525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:02:10.171911Z","caller":"traceutil/trace.go:171","msg":"trace[1099184571] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:18896; }","duration":"165.004288ms","start":"2026-10-13T03:02:10.006893Z","end":"2026-10-13T03:02:10.171897Z","steps":["trace[1099184571] 'agreement among raft nodes before linearized reading' (duration: 164.841795ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:02:10.171671Z","caller":"traceutil/trace.go:171","msg":"trace[1858864736] linearizableReadLoop","detail":"{readStateIndex:19541; appliedIndex:19541; }","duration":"164.712543ms","start":"2026-10-13T03:02:10.006918Z","end":"2026-10-13T03:02:10.171631Z","steps":["trace[1858864736] 'read index received' (duration: 164.699402ms)","trace[1858864736] 'applied index is now lower than readState.Index' (duration: 10.841µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:02:10.339074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.822743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T03:02:10.33938Z","caller":"traceutil/trace.go:171","msg":"trace[1958525403] transaction","detail":"{read_only:false; response_revision:18897; number_of_response:1; }","duration":"247.008772ms","start":"2026-10-13T03:02:10.092298Z","end":"2026-10-13T03:02:10.339307Z","steps":["trace[1958525403] 'process raft request' (duration: 80.857084ms)","trace[1958525403] 'compare' (duration: 165.7099ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:02:47.041801Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8572cc3a5351216d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-13T03:02:47.058481Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-13T03:02:47.058754Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-13T03:02:59.15084Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16896} {"level":"info","ts":"2026-10-13T03:02:59.251717Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16896,"took":"97.685988ms","hash":3740738175,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":33615872,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-13T03:02:59.251796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3740738175,"revision":16896,"compact-revision":14570} {"level":"info","ts":"2026-10-13T03:07:59.157917Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19507} {"level":"info","ts":"2026-10-13T03:07:59.2946Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19507,"took":"133.216889ms","hash":623986343,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":30801920,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-13T03:07:59.294666Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":623986343,"revision":19507,"compact-revision":16896} {"level":"warn","ts":"2026-10-13T03:08:10.426299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.599854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-13T03:08:10.426393Z","caller":"traceutil/trace.go:171","msg":"trace[1509327491] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21703; }","duration":"311.744476ms","start":"2026-10-13T03:08:10.114625Z","end":"2026-10-13T03:08:10.426369Z","steps":["trace[1509327491] 'range keys from in-memory index tree' (duration: 311.358349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:08:10.426439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:08:10.114595Z","time spent":"311.833338ms","remote":"127.0.0.1:33840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-13T03:08:10.748398Z","caller":"traceutil/trace.go:171","msg":"trace[1229432515] transaction","detail":"{read_only:false; response_revision:21704; number_of_response:1; }","duration":"143.357259ms","start":"2026-10-13T03:08:10.605006Z","end":"2026-10-13T03:08:10.748364Z","steps":["trace[1229432515] 'process raft request' (duration: 143.021823ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:08:10.748369Z","caller":"traceutil/trace.go:171","msg":"trace[1733933329] linearizableReadLoop","detail":"{readStateIndex:22441; appliedIndex:22440; }","duration":"137.586391ms","start":"2026-10-13T03:08:10.610616Z","end":"2026-10-13T03:08:10.748202Z","steps":["trace[1733933329] 'read index received' (duration: 137.465779ms)","trace[1733933329] 'applied index is now lower than readState.Index' (duration: 119.652µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:08:10.748675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.03983ms","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-13T03:08:10.74872Z","caller":"traceutil/trace.go:171","msg":"trace[1890124188] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:21704; }","duration":"138.096911ms","start":"2026-10-13T03:08:10.610611Z","end":"2026-10-13T03:08:10.748708Z","steps":["trace[1890124188] 'agreement among raft nodes before linearized reading' (duration: 137.905707ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:08:10.750781Z","caller":"traceutil/trace.go:171","msg":"trace[391326054] transaction","detail":"{read_only:false; response_revision:21705; number_of_response:1; }","duration":"128.710525ms","start":"2026-10-13T03:08:10.622043Z","end":"2026-10-13T03:08:10.750754Z","steps":["trace[391326054] 'process raft request' (duration: 128.542962ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:08:10.750923Z","caller":"traceutil/trace.go:171","msg":"trace[493805099] transaction","detail":"{read_only:false; response_revision:21706; number_of_response:1; }","duration":"124.910865ms","start":"2026-10-13T03:08:10.625976Z","end":"2026-10-13T03:08:10.750887Z","steps":["trace[493805099] 'process raft request' (duration: 124.722431ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:08:11.094247Z","caller":"traceutil/trace.go:171","msg":"trace[641010327] linearizableReadLoop","detail":"{readStateIndex:22444; appliedIndex:22443; }","duration":"254.344134ms","start":"2026-10-13T03:08:10.839878Z","end":"2026-10-13T03:08:11.094222Z","steps":["trace[641010327] 'read index received' (duration: 179.466934ms)","trace[641010327] 'applied index is now lower than readState.Index' (duration: 74.87592ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:08:11.094382Z","caller":"traceutil/trace.go:171","msg":"trace[1629613010] transaction","detail":"{read_only:false; response_revision:21707; number_of_response:1; }","duration":"341.032284ms","start":"2026-10-13T03:08:10.753337Z","end":"2026-10-13T03:08:11.094369Z","steps":["trace[1629613010] 'process raft request' (duration: 266.103603ms)","trace[1629613010] 'compare' (duration: 74.653025ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:08:11.094611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:08:10.753319Z","time spent":"341.101166ms","remote":"127.0.0.1:33724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1393,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:08:11.094699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.612089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-13T03:08:11.09472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.817653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-13T03:08:11.094753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.666471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-13T03:08:11.094793Z","caller":"traceutil/trace.go:171","msg":"trace[1783743692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21707; }","duration":"254.767422ms","start":"2026-10-13T03:08:10.840001Z","end":"2026-10-13T03:08:11.094768Z","steps":["trace[1783743692] 'agreement among raft nodes before linearized reading' (duration: 254.541658ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:08:11.09481Z","caller":"traceutil/trace.go:171","msg":"trace[1564236268] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21707; }","duration":"254.989226ms","start":"2026-10-13T03:08:10.839798Z","end":"2026-10-13T03:08:11.094788Z","steps":["trace[1564236268] 'agreement among raft nodes before linearized reading' (duration: 254.747481ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:08:11.094836Z","caller":"traceutil/trace.go:171","msg":"trace[527703825] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21707; }","duration":"253.780804ms","start":"2026-10-13T03:08:10.841034Z","end":"2026-10-13T03:08:11.094815Z","steps":["trace[527703825] 'agreement among raft nodes before linearized reading' (duration: 253.522909ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:08:11.434351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.251396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T03:08:11.434573Z","caller":"traceutil/trace.go:171","msg":"trace[1509520508] linearizableReadLoop","detail":"{readStateIndex:22447; appliedIndex:22445; }","duration":"186.256021ms","start":"2026-10-13T03:08:11.248295Z","end":"2026-10-13T03:08:11.434551Z","steps":["trace[1509520508] 'read index received' (duration: 19.659888ms)","trace[1509520508] 'applied index is now lower than readState.Index' (duration: 166.594803ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:08:11.434669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.363933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:08:11.434666Z","caller":"traceutil/trace.go:171","msg":"trace[1886409380] transaction","detail":"{read_only:false; response_revision:21710; number_of_response:1; }","duration":"334.94094ms","start":"2026-10-13T03:08:11.0997Z","end":"2026-10-13T03:08:11.434641Z","steps":["trace[1886409380] 'process raft request' (duration: 334.752497ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:08:11.43471Z","caller":"traceutil/trace.go:171","msg":"trace[1449516919] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:21710; }","duration":"186.453294ms","start":"2026-10-13T03:08:11.248241Z","end":"2026-10-13T03:08:11.434694Z","steps":["trace[1449516919] 'agreement among raft nodes before linearized reading' (duration: 186.380663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:08:11.43478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:08:11.099669Z","time spent":"335.048863ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:08:11.434853Z","caller":"traceutil/trace.go:171","msg":"trace[2005271519] transaction","detail":"{read_only:false; response_revision:21709; number_of_response:1; }","duration":"334.988561ms","start":"2026-10-13T03:08:11.099701Z","end":"2026-10-13T03:08:11.43469Z","steps":["trace[2005271519] 'process raft request' (duration: 168.309197ms)","trace[2005271519] 'compare' (duration: 166.131274ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:08:11.435057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:08:11.099676Z","time spent":"335.294107ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:08:11.696984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.721007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:08:11.697097Z","caller":"traceutil/trace.go:171","msg":"trace[2023222405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21710; }","duration":"120.882629ms","start":"2026-10-13T03:08:11.57619Z","end":"2026-10-13T03:08:11.697072Z","steps":["trace[2023222405] 'range keys from in-memory index tree' (duration: 120.636085ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:08:12.044855Z","caller":"traceutil/trace.go:171","msg":"trace[1625025236] transaction","detail":"{read_only:false; response_revision:21711; number_of_response:1; }","duration":"290.621752ms","start":"2026-10-13T03:08:11.75421Z","end":"2026-10-13T03:08:12.044832Z","steps":["trace[1625025236] 'process raft request' (duration: 290.416539ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:08:12.151855Z","caller":"traceutil/trace.go:171","msg":"trace[1635250527] linearizableReadLoop","detail":"{readStateIndex:22449; appliedIndex:22447; }","duration":"114.603892ms","start":"2026-10-13T03:08:12.037229Z","end":"2026-10-13T03:08:12.151833Z","steps":["trace[1635250527] 'read index received' (duration: 7.423139ms)","trace[1635250527] 'applied index is now lower than readState.Index' (duration: 107.179813ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:08:12.151892Z","caller":"traceutil/trace.go:171","msg":"trace[1455498919] transaction","detail":"{read_only:false; response_revision:21712; number_of_response:1; }","duration":"339.953764ms","start":"2026-10-13T03:08:11.811908Z","end":"2026-10-13T03:08:12.151861Z","steps":["trace[1455498919] 'process raft request' (duration: 338.870654ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:08:12.152034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.795886ms","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-13T03:08:12.15207Z","caller":"traceutil/trace.go:171","msg":"trace[2037882345] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21712; }","duration":"114.887758ms","start":"2026-10-13T03:08:12.037174Z","end":"2026-10-13T03:08:12.152061Z","steps":["trace[2037882345] 'agreement among raft nodes before linearized reading' (duration: 114.734905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:08:12.152226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:08:11.811885Z","time spent":"340.170687ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:12:27.867476Z","caller":"traceutil/trace.go:171","msg":"trace[2110455183] transaction","detail":"{read_only:false; response_revision:24298; number_of_response:1; }","duration":"275.309346ms","start":"2026-10-13T03:12:27.59214Z","end":"2026-10-13T03:12:27.867449Z","steps":["trace[2110455183] 'process raft request' (duration: 275.157163ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:12:27.870722Z","caller":"traceutil/trace.go:171","msg":"trace[1977413634] transaction","detail":"{read_only:false; response_revision:24299; number_of_response:1; }","duration":"188.692318ms","start":"2026-10-13T03:12:27.681975Z","end":"2026-10-13T03:12:27.870667Z","steps":["trace[1977413634] 'process raft request' (duration: 188.449283ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:12:59.167268Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21647} {"level":"info","ts":"2026-10-13T03:12:59.298446Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21647,"took":"125.408904ms","hash":1435695192,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":42582016,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-13T03:12:59.29853Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1435695192,"revision":21647,"compact-revision":19507} {"level":"info","ts":"2026-10-13T03:15:17.361224Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068ce.wal"} {"level":"warn","ts":"2026-10-13T03:16:16.698382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.951417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T03:16:16.698519Z","caller":"traceutil/trace.go:171","msg":"trace[1948113677] linearizableReadLoop","detail":"{readStateIndex:27429; appliedIndex:27428; }","duration":"154.809593ms","start":"2026-10-13T03:16:16.54369Z","end":"2026-10-13T03:16:16.6985Z","steps":["trace[1948113677] 'read index received' (duration: 46.619052ms)","trace[1948113677] 'applied index is now lower than readState.Index' (duration: 108.189221ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:16:16.698609Z","caller":"traceutil/trace.go:171","msg":"trace[555529804] transaction","detail":"{read_only:false; response_revision:26555; number_of_response:1; }","duration":"209.186509ms","start":"2026-10-13T03:16:16.489412Z","end":"2026-10-13T03:16:16.698599Z","steps":["trace[555529804] 'process raft request' (duration: 100.928896ms)","trace[555529804] 'compare' (duration: 107.779044ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:16:16.698781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.091578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-13T03:16:16.698811Z","caller":"traceutil/trace.go:171","msg":"trace[885861122] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:26555; }","duration":"155.16898ms","start":"2026-10-13T03:16:16.543632Z","end":"2026-10-13T03:16:16.698801Z","steps":["trace[885861122] 'agreement among raft nodes before linearized reading' (duration: 155.104028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:16.698796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.081977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:16:16.698903Z","caller":"traceutil/trace.go:171","msg":"trace[50559119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26555; }","duration":"125.2406ms","start":"2026-10-13T03:16:16.573636Z","end":"2026-10-13T03:16:16.698877Z","steps":["trace[50559119] 'agreement among raft nodes before linearized reading' (duration: 125.043716ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:17.194299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.960108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T03:16:17.194387Z","caller":"traceutil/trace.go:171","msg":"trace[1412889986] transaction","detail":"{read_only:false; response_revision:26556; number_of_response:1; }","duration":"292.074858ms","start":"2026-10-13T03:16:16.902296Z","end":"2026-10-13T03:16:17.194371Z","steps":["trace[1412889986] 'process raft request' (duration: 15.971508ms)","trace[1412889986] 'compare' (duration: 275.354117ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:16:17.27269Z","caller":"traceutil/trace.go:171","msg":"trace[1998717273] transaction","detail":"{read_only:false; response_revision:26557; number_of_response:1; }","duration":"342.348659ms","start":"2026-10-13T03:16:16.930313Z","end":"2026-10-13T03:16:17.272662Z","steps":["trace[1998717273] 'process raft request' (duration: 342.223336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:17.272826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:16.930299Z","time spent":"342.45967ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:16:17.329629Z","caller":"traceutil/trace.go:171","msg":"trace[360587564] transaction","detail":"{read_only:false; response_revision:26558; number_of_response:1; }","duration":"391.901165ms","start":"2026-10-13T03:16:16.937703Z","end":"2026-10-13T03:16:17.329604Z","steps":["trace[360587564] 'process raft request' (duration: 379.045824ms)","trace[360587564] 'compare' (duration: 12.635136ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:16:17.329768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:16.937691Z","time spent":"392.002707ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:16:17.329875Z","caller":"traceutil/trace.go:171","msg":"trace[944604442] linearizableReadLoop","detail":"{readStateIndex:27432; appliedIndex:27430; }","duration":"376.295354ms","start":"2026-10-13T03:16:16.953572Z","end":"2026-10-13T03:16:17.329868Z","steps":["trace[944604442] 'read index received' (duration: 318.902461ms)","trace[944604442] 'applied index is now lower than readState.Index' (duration: 57.392073ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:16:17.330133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.560839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-13T03:16:17.33019Z","caller":"traceutil/trace.go:171","msg":"trace[525645050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26559; }","duration":"376.668141ms","start":"2026-10-13T03:16:16.953509Z","end":"2026-10-13T03:16:17.330177Z","steps":["trace[525645050] 'agreement among raft nodes before linearized reading' (duration: 376.471107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:17.330211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:16.953486Z","time spent":"376.719982ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-13T03:16:17.330154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.881184ms","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-13T03:16:17.330282Z","caller":"traceutil/trace.go:171","msg":"trace[1380020857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26559; }","duration":"109.034478ms","start":"2026-10-13T03:16:17.221239Z","end":"2026-10-13T03:16:17.330273Z","steps":["trace[1380020857] 'agreement among raft nodes before linearized reading' (duration: 108.839664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:21.500115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.186192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T03:16:21.500316Z","caller":"traceutil/trace.go:171","msg":"trace[862110706] transaction","detail":"{read_only:false; response_revision:26596; number_of_response:1; }","duration":"143.95836ms","start":"2026-10-13T03:16:21.356302Z","end":"2026-10-13T03:16:21.50026Z","steps":["trace[862110706] 'process raft request' (duration: 35.479323ms)","trace[862110706] 'compare' (duration: 107.723394ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:16:22.315735Z","caller":"traceutil/trace.go:171","msg":"trace[258997424] transaction","detail":"{read_only:false; response_revision:26599; number_of_response:1; }","duration":"130.067582ms","start":"2026-10-13T03:16:22.185629Z","end":"2026-10-13T03:16:22.315697Z","steps":["trace[258997424] 'process raft request' (duration: 129.879417ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:22.62441Z","caller":"traceutil/trace.go:171","msg":"trace[980570935] transaction","detail":"{read_only:false; response_revision:26601; number_of_response:1; }","duration":"396.121524ms","start":"2026-10-13T03:16:22.228244Z","end":"2026-10-13T03:16:22.624366Z","steps":["trace[980570935] 'process raft request' (duration: 395.827969ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:22.624455Z","caller":"traceutil/trace.go:171","msg":"trace[1202971484] transaction","detail":"{read_only:false; response_revision:26602; number_of_response:1; }","duration":"393.607387ms","start":"2026-10-13T03:16:22.230818Z","end":"2026-10-13T03:16:22.624425Z","steps":["trace[1202971484] 'process raft request' (duration: 393.330102ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:22.624498Z","caller":"traceutil/trace.go:171","msg":"trace[1080517958] transaction","detail":"{read_only:false; response_revision:26600; number_of_response:1; }","duration":"434.378449ms","start":"2026-10-13T03:16:22.190083Z","end":"2026-10-13T03:16:22.624462Z","steps":["trace[1080517958] 'process raft request' (duration: 350.001612ms)","trace[1080517958] 'compare' (duration: 83.812867ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:16:22.624565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:22.22822Z","time spent":"396.262647ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:22.624598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:22.190026Z","time spent":"434.515502ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:16:22.624578Z","caller":"traceutil/trace.go:171","msg":"trace[340606258] linearizableReadLoop","detail":"{readStateIndex:27477; appliedIndex:27474; }","duration":"290.037462ms","start":"2026-10-13T03:16:22.334455Z","end":"2026-10-13T03:16:22.624493Z","steps":["trace[340606258] 'read index received' (duration: 205.622854ms)","trace[340606258] 'applied index is now lower than readState.Index' (duration: 84.404548ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:16:22.624626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:22.230763Z","time spent":"393.75918ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:22.624853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.586262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-13T03:16:22.6249Z","caller":"traceutil/trace.go:171","msg":"trace[2082415315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26603; }","duration":"190.654003ms","start":"2026-10-13T03:16:22.434231Z","end":"2026-10-13T03:16:22.624885Z","steps":["trace[2082415315] 'agreement among raft nodes before linearized reading' (duration: 190.529441ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:22.624935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.107329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-13T03:16:22.62498Z","caller":"traceutil/trace.go:171","msg":"trace[1287573143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26603; }","duration":"224.21195ms","start":"2026-10-13T03:16:22.400755Z","end":"2026-10-13T03:16:22.624967Z","steps":["trace[1287573143] 'agreement among raft nodes before linearized reading' (duration: 223.978816ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:22.626601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.318197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-13T03:16:22.626681Z","caller":"traceutil/trace.go:171","msg":"trace[2039438003] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:26603; }","duration":"292.260234ms","start":"2026-10-13T03:16:22.334399Z","end":"2026-10-13T03:16:22.626659Z","steps":["trace[2039438003] 'agreement among raft nodes before linearized reading' (duration: 290.252146ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:22.912897Z","caller":"traceutil/trace.go:171","msg":"trace[985187619] transaction","detail":"{read_only:false; response_revision:26606; number_of_response:1; }","duration":"170.759662ms","start":"2026-10-13T03:16:22.742108Z","end":"2026-10-13T03:16:22.912867Z","steps":["trace[985187619] 'process raft request' (duration: 170.713061ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:22.91295Z","caller":"traceutil/trace.go:171","msg":"trace[1101238466] transaction","detail":"{read_only:false; response_revision:26604; number_of_response:1; }","duration":"283.53759ms","start":"2026-10-13T03:16:22.629367Z","end":"2026-10-13T03:16:22.912904Z","steps":["trace[1101238466] 'process raft request' (duration: 283.222194ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:22.912977Z","caller":"traceutil/trace.go:171","msg":"trace[623382199] transaction","detail":"{read_only:false; response_revision:26605; number_of_response:1; }","duration":"282.362397ms","start":"2026-10-13T03:16:22.630589Z","end":"2026-10-13T03:16:22.912951Z","steps":["trace[623382199] 'process raft request' (duration: 282.191924ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:23.023965Z","caller":"traceutil/trace.go:171","msg":"trace[1656828717] linearizableReadLoop","detail":"{readStateIndex:27483; appliedIndex:27479; }","duration":"191.55485ms","start":"2026-10-13T03:16:22.832384Z","end":"2026-10-13T03:16:23.023939Z","steps":["trace[1656828717] 'read index received' (duration: 80.276351ms)","trace[1656828717] 'applied index is now lower than readState.Index' (duration: 111.277629ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:16:23.024088Z","caller":"traceutil/trace.go:171","msg":"trace[1925187633] transaction","detail":"{read_only:false; response_revision:26607; number_of_response:1; }","duration":"194.77285ms","start":"2026-10-13T03:16:22.829285Z","end":"2026-10-13T03:16:23.024058Z","steps":["trace[1925187633] 'process raft request' (duration: 192.391297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:23.024336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.936137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-13T03:16:23.024425Z","caller":"traceutil/trace.go:171","msg":"trace[913334285] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26607; }","duration":"192.087791ms","start":"2026-10-13T03:16:22.832315Z","end":"2026-10-13T03:16:23.024403Z","steps":["trace[913334285] 'agreement among raft nodes before linearized reading' (duration: 191.774434ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:23.32737Z","caller":"traceutil/trace.go:171","msg":"trace[902940689] transaction","detail":"{read_only:false; response_revision:26608; number_of_response:1; }","duration":"112.87064ms","start":"2026-10-13T03:16:23.214465Z","end":"2026-10-13T03:16:23.327335Z","steps":["trace[902940689] 'process raft request' (duration: 104.062505ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:23.32842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.931564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-10-13T03:16:23.328528Z","caller":"traceutil/trace.go:171","msg":"trace[1965644870] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26607; }","duration":"194.093458ms","start":"2026-10-13T03:16:23.134405Z","end":"2026-10-13T03:16:23.328499Z","steps":["trace[1965644870] 'range keys from in-memory index tree' (duration: 192.410086ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:23.504489Z","caller":"traceutil/trace.go:171","msg":"trace[1659448908] transaction","detail":"{read_only:false; response_revision:26609; number_of_response:1; }","duration":"165.978182ms","start":"2026-10-13T03:16:23.338459Z","end":"2026-10-13T03:16:23.504437Z","steps":["trace[1659448908] 'process raft request' (duration: 139.10906ms)","trace[1659448908] 'compare' (duration: 26.558586ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:16:23.573643Z","caller":"traceutil/trace.go:171","msg":"trace[1196492768] transaction","detail":"{read_only:false; response_revision:26610; number_of_response:1; }","duration":"220.825858ms","start":"2026-10-13T03:16:23.352779Z","end":"2026-10-13T03:16:23.573605Z","steps":["trace[1196492768] 'process raft request' (duration: 220.679544ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:23.881149Z","caller":"traceutil/trace.go:171","msg":"trace[2130346787] linearizableReadLoop","detail":"{readStateIndex:27487; appliedIndex:27486; }","duration":"295.446551ms","start":"2026-10-13T03:16:23.585673Z","end":"2026-10-13T03:16:23.88112Z","steps":["trace[2130346787] 'read index received' (duration: 295.269378ms)","trace[2130346787] 'applied index is now lower than readState.Index' (duration: 175.973µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:16:23.881247Z","caller":"traceutil/trace.go:171","msg":"trace[494273594] transaction","detail":"{read_only:false; response_revision:26611; number_of_response:1; }","duration":"302.874351ms","start":"2026-10-13T03:16:23.578332Z","end":"2026-10-13T03:16:23.881207Z","steps":["trace[494273594] 'process raft request' (duration: 302.605526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:23.881353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.654065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-13T03:16:23.881396Z","caller":"traceutil/trace.go:171","msg":"trace[463690223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26611; }","duration":"295.754137ms","start":"2026-10-13T03:16:23.585628Z","end":"2026-10-13T03:16:23.881382Z","steps":["trace[463690223] 'agreement among raft nodes before linearized reading' (duration: 295.600704ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:23.881438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:23.578312Z","time spent":"303.013953ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:24.427565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.317738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:16:24.427655Z","caller":"traceutil/trace.go:171","msg":"trace[1866438257] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26613; }","duration":"174.45553ms","start":"2026-10-13T03:16:24.253174Z","end":"2026-10-13T03:16:24.42763Z","steps":["trace[1866438257] 'count revisions from in-memory index tree' (duration: 174.237606ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:24.427621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.064572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-13T03:16:24.427713Z","caller":"traceutil/trace.go:171","msg":"trace[716791706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26613; }","duration":"404.212055ms","start":"2026-10-13T03:16:24.023482Z","end":"2026-10-13T03:16:24.427694Z","steps":["trace[716791706] 'range keys from in-memory index tree' (duration: 403.828268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:24.427747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:24.02346Z","time spent":"404.278396ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-13T03:16:24.610306Z","caller":"traceutil/trace.go:171","msg":"trace[753609804] transaction","detail":"{read_only:false; response_revision:26614; number_of_response:1; }","duration":"287.927772ms","start":"2026-10-13T03:16:24.322349Z","end":"2026-10-13T03:16:24.610277Z","steps":["trace[753609804] 'process raft request' (duration: 287.758849ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:25.015213Z","caller":"traceutil/trace.go:171","msg":"trace[1705863552] linearizableReadLoop","detail":"{readStateIndex:27491; appliedIndex:27489; }","duration":"634.979068ms","start":"2026-10-13T03:16:24.380209Z","end":"2026-10-13T03:16:25.015188Z","steps":["trace[1705863552] 'read index received' (duration: 229.890937ms)","trace[1705863552] 'applied index is now lower than readState.Index' (duration: 405.087191ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:16:25.015277Z","caller":"traceutil/trace.go:171","msg":"trace[525332002] transaction","detail":"{read_only:false; response_revision:26615; number_of_response:1; }","duration":"654.255448ms","start":"2026-10-13T03:16:24.360977Z","end":"2026-10-13T03:16:25.015232Z","steps":["trace[525332002] 'process raft request' (duration: 639.967111ms)","trace[525332002] 'compare' (duration: 13.38623ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:16:25.015387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.160082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:16:25.015422Z","caller":"traceutil/trace.go:171","msg":"trace[1302625236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26616; }","duration":"635.245903ms","start":"2026-10-13T03:16:24.380165Z","end":"2026-10-13T03:16:25.015411Z","steps":["trace[1302625236] 'agreement among raft nodes before linearized reading' (duration: 635.157652ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:25.015394Z","caller":"traceutil/trace.go:171","msg":"trace[111473380] transaction","detail":"{read_only:false; response_revision:26616; number_of_response:1; }","duration":"583.391744ms","start":"2026-10-13T03:16:24.431958Z","end":"2026-10-13T03:16:25.01535Z","steps":["trace[111473380] 'process raft request' (duration: 583.18832ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:25.015454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:24.380143Z","time spent":"635.302025ms","remote":"127.0.0.1:33670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-13T03:16:25.015484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:24.360951Z","time spent":"654.433242ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:25.015594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:24.431932Z","time spent":"583.548867ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:16:25.032683Z","caller":"traceutil/trace.go:171","msg":"trace[759754988] transaction","detail":"{read_only:false; response_revision:26617; number_of_response:1; }","duration":"402.187236ms","start":"2026-10-13T03:16:24.630461Z","end":"2026-10-13T03:16:25.032648Z","steps":["trace[759754988] 'process raft request' (duration: 401.920081ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:25.032915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:24.630433Z","time spent":"402.35752ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:16:25.032985Z","caller":"traceutil/trace.go:171","msg":"trace[1895227452] transaction","detail":"{read_only:false; response_revision:26618; number_of_response:1; }","duration":"402.397321ms","start":"2026-10-13T03:16:24.630545Z","end":"2026-10-13T03:16:25.032942Z","steps":["trace[1895227452] 'process raft request' (duration: 402.046984ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:25.033173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:24.630538Z","time spent":"402.539764ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:16:25.033411Z","caller":"traceutil/trace.go:171","msg":"trace[1596467683] transaction","detail":"{read_only:false; response_revision:26619; number_of_response:1; }","duration":"402.695917ms","start":"2026-10-13T03:16:24.630698Z","end":"2026-10-13T03:16:25.033394Z","steps":["trace[1596467683] 'process raft request' (duration: 402.152746ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:25.033499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:24.63066Z","time spent":"402.799529ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:25.033493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.395373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:16:25.033623Z","caller":"traceutil/trace.go:171","msg":"trace[1252594634] transaction","detail":"{read_only:false; response_revision:26620; number_of_response:1; }","duration":"115.806805ms","start":"2026-10-13T03:16:24.917802Z","end":"2026-10-13T03:16:25.033609Z","steps":["trace[1252594634] 'process raft request' (duration: 115.449878ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:25.03364Z","caller":"traceutil/trace.go:171","msg":"trace[364820054] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:26620; }","duration":"248.616227ms","start":"2026-10-13T03:16:24.785003Z","end":"2026-10-13T03:16:25.033619Z","steps":["trace[364820054] 'agreement among raft nodes before linearized reading' (duration: 248.388653ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:25.033899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.929348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-13T03:16:25.03397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.338725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-13T03:16:25.033994Z","caller":"traceutil/trace.go:171","msg":"trace[2061301856] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26620; }","duration":"116.03395ms","start":"2026-10-13T03:16:24.917935Z","end":"2026-10-13T03:16:25.033969Z","steps":["trace[2061301856] 'agreement among raft nodes before linearized reading' (duration: 115.814665ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:25.034013Z","caller":"traceutil/trace.go:171","msg":"trace[1391268548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26620; }","duration":"116.414906ms","start":"2026-10-13T03:16:24.917586Z","end":"2026-10-13T03:16:25.034001Z","steps":["trace[1391268548] 'agreement among raft nodes before linearized reading' (duration: 116.241233ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:25.392684Z","caller":"traceutil/trace.go:171","msg":"trace[279730203] transaction","detail":"{read_only:false; response_revision:26621; number_of_response:1; }","duration":"354.12872ms","start":"2026-10-13T03:16:25.038525Z","end":"2026-10-13T03:16:25.392653Z","steps":["trace[279730203] 'process raft request' (duration: 353.965007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:25.392857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:25.038496Z","time spent":"354.266372ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:16:25.405604Z","caller":"traceutil/trace.go:171","msg":"trace[1014375714] linearizableReadLoop","detail":"{readStateIndex:27498; appliedIndex:27496; }","duration":"365.625844ms","start":"2026-10-13T03:16:25.039947Z","end":"2026-10-13T03:16:25.405573Z","steps":["trace[1014375714] 'read index received' (duration: 352.643741ms)","trace[1014375714] 'applied index is now lower than readState.Index' (duration: 12.980023ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:16:25.405761Z","caller":"traceutil/trace.go:171","msg":"trace[141274563] transaction","detail":"{read_only:false; response_revision:26622; number_of_response:1; }","duration":"366.279616ms","start":"2026-10-13T03:16:25.039422Z","end":"2026-10-13T03:16:25.405702Z","steps":["trace[141274563] 'process raft request' (duration: 365.951849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:25.405832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.877499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:16:25.405872Z","caller":"traceutil/trace.go:171","msg":"trace[1241093306] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26623; }","duration":"365.93704ms","start":"2026-10-13T03:16:25.039922Z","end":"2026-10-13T03:16:25.405859Z","steps":["trace[1241093306] 'agreement among raft nodes before linearized reading' (duration: 365.818937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:25.405912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:25.039856Z","time spent":"366.047181ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-10-13T03:16:25.405956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:25.039396Z","time spent":"366.452489ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:25.40633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.691482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-13T03:16:25.406435Z","caller":"traceutil/trace.go:171","msg":"trace[245341586] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:26623; }","duration":"314.856076ms","start":"2026-10-13T03:16:25.091552Z","end":"2026-10-13T03:16:25.406408Z","steps":["trace[245341586] 'agreement among raft nodes before linearized reading' (duration: 314.613391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:25.406479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:25.090906Z","time spent":"315.562819ms","remote":"127.0.0.1:34226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":60,"response size":32,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-10-13T03:16:25.966251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.733816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T03:16:25.966357Z","caller":"traceutil/trace.go:171","msg":"trace[88103298] linearizableReadLoop","detail":"{readStateIndex:27501; appliedIndex:27500; }","duration":"385.092009ms","start":"2026-10-13T03:16:25.581249Z","end":"2026-10-13T03:16:25.966341Z","steps":["trace[88103298] 'read index received' (duration: 118.072507ms)","trace[88103298] 'applied index is now lower than readState.Index' (duration: 267.016351ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:16:25.966567Z","caller":"traceutil/trace.go:171","msg":"trace[1576126666] transaction","detail":"{read_only:false; response_revision:26625; number_of_response:1; }","duration":"388.001842ms","start":"2026-10-13T03:16:25.578552Z","end":"2026-10-13T03:16:25.966554Z","steps":["trace[1576126666] 'process raft request' (duration: 120.876349ms)","trace[1576126666] 'compare' (duration: 265.632915ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:16:25.966633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:25.578532Z","time spent":"388.071224ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:25.966812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.558507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:16:25.966845Z","caller":"traceutil/trace.go:171","msg":"trace[1954910506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26625; }","duration":"385.611518ms","start":"2026-10-13T03:16:25.581226Z","end":"2026-10-13T03:16:25.966837Z","steps":["trace[1954910506] 'agreement among raft nodes before linearized reading' (duration: 385.559277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:25.96687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:25.581218Z","time spent":"385.646708ms","remote":"127.0.0.1:33658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-13T03:16:26.198966Z","caller":"traceutil/trace.go:171","msg":"trace[1826705972] transaction","detail":"{read_only:false; response_revision:26627; number_of_response:1; }","duration":"225.651899ms","start":"2026-10-13T03:16:25.973281Z","end":"2026-10-13T03:16:26.198933Z","steps":["trace[1826705972] 'process raft request' (duration: 225.468135ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:27.436933Z","caller":"traceutil/trace.go:171","msg":"trace[1330573508] linearizableReadLoop","detail":"{readStateIndex:27509; appliedIndex:27505; }","duration":"100.340135ms","start":"2026-10-13T03:16:27.336561Z","end":"2026-10-13T03:16:27.436901Z","steps":["trace[1330573508] 'read index received' (duration: 80.690718ms)","trace[1330573508] 'applied index is now lower than readState.Index' (duration: 19.647737ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:16:27.437287Z","caller":"traceutil/trace.go:171","msg":"trace[1669917228] transaction","detail":"{read_only:false; response_revision:26632; number_of_response:1; }","duration":"396.933729ms","start":"2026-10-13T03:16:27.040308Z","end":"2026-10-13T03:16:27.437241Z","steps":["trace[1669917228] 'process raft request' (duration: 396.502101ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:27.437374Z","caller":"traceutil/trace.go:171","msg":"trace[802387674] transaction","detail":"{read_only:false; response_revision:26633; number_of_response:1; }","duration":"396.815867ms","start":"2026-10-13T03:16:27.040514Z","end":"2026-10-13T03:16:27.43733Z","steps":["trace[802387674] 'process raft request' (duration: 396.334288ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:27.437405Z","caller":"traceutil/trace.go:171","msg":"trace[1830244046] transaction","detail":"{read_only:false; response_revision:26630; number_of_response:1; }","duration":"397.47512ms","start":"2026-10-13T03:16:27.039889Z","end":"2026-10-13T03:16:27.437364Z","steps":["trace[1830244046] 'process raft request' (duration: 377.345314ms)","trace[1830244046] 'compare' (duration: 19.257779ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:16:27.437433Z","caller":"traceutil/trace.go:171","msg":"trace[1936906710] transaction","detail":"{read_only:false; response_revision:26631; number_of_response:1; }","duration":"397.327416ms","start":"2026-10-13T03:16:27.040062Z","end":"2026-10-13T03:16:27.437389Z","steps":["trace[1936906710] 'process raft request' (duration: 396.693374ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:27.437567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:27.040492Z","time spent":"396.983241ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:27.437588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:27.039844Z","time spent":"397.645002ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:27.437586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.012078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-13T03:16:27.437648Z","caller":"traceutil/trace.go:171","msg":"trace[1683040671] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:26633; }","duration":"101.130801ms","start":"2026-10-13T03:16:27.336499Z","end":"2026-10-13T03:16:27.43763Z","steps":["trace[1683040671] 'agreement among raft nodes before linearized reading' (duration: 100.849015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:27.43764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:27.040007Z","time spent":"397.51499ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:27.437765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:27.04028Z","time spent":"397.180263ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:36.409485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.044394ms","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-13T03:16:36.409577Z","caller":"traceutil/trace.go:171","msg":"trace[726893397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26699; }","duration":"144.194367ms","start":"2026-10-13T03:16:36.265361Z","end":"2026-10-13T03:16:36.409556Z","steps":["trace[726893397] 'range keys from in-memory index tree' (duration: 143.846941ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:36.541899Z","caller":"traceutil/trace.go:171","msg":"trace[37473390] transaction","detail":"{read_only:false; response_revision:26700; number_of_response:1; }","duration":"127.735478ms","start":"2026-10-13T03:16:36.414128Z","end":"2026-10-13T03:16:36.541863Z","steps":["trace[37473390] 'process raft request' (duration: 127.430383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:37.096904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.596091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T03:16:37.097074Z","caller":"traceutil/trace.go:171","msg":"trace[971595020] linearizableReadLoop","detail":"{readStateIndex:27579; appliedIndex:27578; }","duration":"227.782848ms","start":"2026-10-13T03:16:36.869246Z","end":"2026-10-13T03:16:37.097029Z","steps":["trace[971595020] 'read index received' (duration: 43.029934ms)","trace[971595020] 'applied index is now lower than readState.Index' (duration: 184.751514ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:16:37.097148Z","caller":"traceutil/trace.go:171","msg":"trace[1273856848] transaction","detail":"{read_only:false; response_revision:26701; number_of_response:1; }","duration":"347.62217ms","start":"2026-10-13T03:16:36.749488Z","end":"2026-10-13T03:16:37.097111Z","steps":["trace[1273856848] 'process raft request' (duration: 162.734883ms)","trace[1273856848] 'compare' (duration: 184.472049ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:16:37.097221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.973502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-13T03:16:37.097262Z","caller":"traceutil/trace.go:171","msg":"trace[1307244850] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:26701; }","duration":"228.056053ms","start":"2026-10-13T03:16:36.869191Z","end":"2026-10-13T03:16:37.097247Z","steps":["trace[1307244850] 'agreement among raft nodes before linearized reading' (duration: 227.953861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:37.097323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:36.749462Z","time spent":"347.766182ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:37.098703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.9882ms","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-13T03:16:37.098785Z","caller":"traceutil/trace.go:171","msg":"trace[1142002012] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26701; }","duration":"169.106172ms","start":"2026-10-13T03:16:36.92966Z","end":"2026-10-13T03:16:37.098766Z","steps":["trace[1142002012] 'agreement among raft nodes before linearized reading' (duration: 167.764927ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:38.000736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2408758616430133610,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-13T03:16:38.137539Z","caller":"traceutil/trace.go:171","msg":"trace[1676099300] transaction","detail":"{read_only:false; response_revision:26703; number_of_response:1; }","duration":"657.790578ms","start":"2026-10-13T03:16:37.479712Z","end":"2026-10-13T03:16:38.137503Z","steps":["trace[1676099300] 'process raft request' (duration: 657.578294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:38.137721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:37.479688Z","time spent":"657.942481ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:16:38.657681Z","caller":"traceutil/trace.go:171","msg":"trace[701428312] transaction","detail":"{read_only:false; response_revision:26706; number_of_response:1; }","duration":"1.162524606s","start":"2026-10-13T03:16:37.495122Z","end":"2026-10-13T03:16:38.657647Z","steps":["trace[701428312] 'process raft request' (duration: 1.162453154s)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:38.657707Z","caller":"traceutil/trace.go:171","msg":"trace[1721360272] transaction","detail":"{read_only:false; response_revision:26705; number_of_response:1; }","duration":"1.162826751s","start":"2026-10-13T03:16:37.494849Z","end":"2026-10-13T03:16:38.657675Z","steps":["trace[1721360272] 'process raft request' (duration: 1.162682039s)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:38.657707Z","caller":"traceutil/trace.go:171","msg":"trace[921794673] transaction","detail":"{read_only:false; response_revision:26704; number_of_response:1; }","duration":"1.174396338s","start":"2026-10-13T03:16:37.483272Z","end":"2026-10-13T03:16:38.657669Z","steps":["trace[921794673] 'process raft request' (duration: 1.174093052s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:38.657862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:37.494821Z","time spent":"1.162954094s","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:38.657858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:37.495107Z","time spent":"1.162662288s","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:16:38.657942Z","caller":"traceutil/trace.go:171","msg":"trace[1794624105] transaction","detail":"{read_only:false; response_revision:26707; number_of_response:1; }","duration":"1.159322806s","start":"2026-10-13T03:16:37.498606Z","end":"2026-10-13T03:16:38.657929Z","steps":["trace[1794624105] 'process raft request' (duration: 1.15900123s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:38.657993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:37.498584Z","time spent":"1.159387938s","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:16:38.657996Z","caller":"traceutil/trace.go:171","msg":"trace[2076215333] transaction","detail":"{read_only:false; response_revision:26708; number_of_response:1; }","duration":"1.017973573s","start":"2026-10-13T03:16:37.640012Z","end":"2026-10-13T03:16:38.657985Z","steps":["trace[2076215333] 'process raft request' (duration: 1.01781343s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:38.658076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:37.639984Z","time spent":"1.018036194s","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:38.658147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:37.483245Z","time spent":"1.174572621s","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:16:38.658114Z","caller":"traceutil/trace.go:171","msg":"trace[970736735] transaction","detail":"{read_only:false; response_revision:26709; number_of_response:1; }","duration":"969.062489ms","start":"2026-10-13T03:16:37.689028Z","end":"2026-10-13T03:16:38.658091Z","steps":["trace[970736735] 'process raft request' (duration: 968.895756ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:38.65831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:37.689015Z","time spent":"969.264082ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:39.170336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.35561ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-13T03:16:39.171909Z","caller":"traceutil/trace.go:171","msg":"trace[748441120] linearizableReadLoop","detail":"{readStateIndex:27588; appliedIndex:27580; }","duration":"1.671611685s","start":"2026-10-13T03:16:37.500268Z","end":"2026-10-13T03:16:39.17188Z","steps":["trace[748441120] 'read index received' (duration: 637.065131ms)","trace[748441120] 'applied index is now lower than readState.Index' (duration: 1.034544974s)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:16:39.17194Z","caller":"traceutil/trace.go:171","msg":"trace[546401680] transaction","detail":"{read_only:false; response_revision:26710; number_of_response:1; }","duration":"1.157967491s","start":"2026-10-13T03:16:38.013934Z","end":"2026-10-13T03:16:39.171902Z","steps":["trace[546401680] 'process raft request' (duration: 1.156524654s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:39.172163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:38.013897Z","time spent":"1.158124595s","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:39.185686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.677150529s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-13T03:16:39.185773Z","caller":"traceutil/trace.go:171","msg":"trace[946105344] transaction","detail":"{read_only:false; response_revision:26711; number_of_response:1; }","duration":"217.372755ms","start":"2026-10-13T03:16:38.968378Z","end":"2026-10-13T03:16:39.185751Z","steps":["trace[946105344] 'process raft request' (duration: 215.015181ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:39.185831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136616842s","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-13T03:16:39.185889Z","caller":"traceutil/trace.go:171","msg":"trace[1477737314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26712; }","duration":"1.136703313s","start":"2026-10-13T03:16:38.049165Z","end":"2026-10-13T03:16:39.185869Z","steps":["trace[1477737314] 'agreement among raft nodes before linearized reading' (duration: 1.136604702s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:39.185902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101756181s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-10-13T03:16:39.185934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:38.049145Z","time spent":"1.136779994s","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-13T03:16:39.185961Z","caller":"traceutil/trace.go:171","msg":"trace[1219323480] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:26712; }","duration":"1.101868133s","start":"2026-10-13T03:16:38.084074Z","end":"2026-10-13T03:16:39.185942Z","steps":["trace[1219323480] 'agreement among raft nodes before linearized reading' (duration: 1.101622628s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:39.186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.405559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T03:16:39.186011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:38.084028Z","time spent":"1.101971535s","remote":"127.0.0.1:48702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":163,"response size":33,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-10-13T03:16:39.18608Z","caller":"traceutil/trace.go:171","msg":"trace[1708673056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26712; }","duration":"613.502261ms","start":"2026-10-13T03:16:38.572563Z","end":"2026-10-13T03:16:39.186065Z","steps":["trace[1708673056] 'agreement among raft nodes before linearized reading' (duration: 613.401719ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:39.186126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:38.572552Z","time spent":"613.563962ms","remote":"127.0.0.1:33670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-13T03:16:39.185704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.610098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-13T03:16:39.186195Z","caller":"traceutil/trace.go:171","msg":"trace[1522576195] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26712; }","duration":"618.198259ms","start":"2026-10-13T03:16:38.567987Z","end":"2026-10-13T03:16:39.186185Z","steps":["trace[1522576195] 'agreement among raft nodes before linearized reading' (duration: 617.548307ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:39.185908Z","caller":"traceutil/trace.go:171","msg":"trace[1147180957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26712; }","duration":"1.677338002s","start":"2026-10-13T03:16:37.508461Z","end":"2026-10-13T03:16:39.185799Z","steps":["trace[1147180957] 'agreement among raft nodes before linearized reading' (duration: 1.677105318s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:39.1863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:37.508443Z","time spent":"1.677836351s","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-13T03:16:39.185704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.485688ms","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-13T03:16:39.186397Z","caller":"traceutil/trace.go:171","msg":"trace[1175251823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26712; }","duration":"640.257091ms","start":"2026-10-13T03:16:38.546131Z","end":"2026-10-13T03:16:39.186389Z","steps":["trace[1175251823] 'agreement among raft nodes before linearized reading' (duration: 639.422526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:39.186427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:38.546116Z","time spent":"640.300822ms","remote":"127.0.0.1:33938","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":"warn","ts":"2026-10-13T03:16:39.186227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:38.567965Z","time spent":"618.25332ms","remote":"127.0.0.1:33840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-13T03:16:39.18658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.686277059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-13T03:16:39.186678Z","caller":"traceutil/trace.go:171","msg":"trace[570650254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26710; }","duration":"1.686425631s","start":"2026-10-13T03:16:37.50023Z","end":"2026-10-13T03:16:39.186656Z","steps":["trace[570650254] 'agreement among raft nodes before linearized reading' (duration: 1.671766127s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:39.186723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:37.500219Z","time spent":"1.686494313s","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-13T03:16:46.413677Z","caller":"traceutil/trace.go:171","msg":"trace[1411421346] transaction","detail":"{read_only:false; response_revision:26818; number_of_response:1; }","duration":"251.467212ms","start":"2026-10-13T03:16:46.162186Z","end":"2026-10-13T03:16:46.413653Z","steps":["trace[1411421346] 'process raft request' (duration: 251.329929ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:46.426113Z","caller":"traceutil/trace.go:171","msg":"trace[1468874505] linearizableReadLoop","detail":"{readStateIndex:27700; appliedIndex:27698; }","duration":"199.50734ms","start":"2026-10-13T03:16:46.226581Z","end":"2026-10-13T03:16:46.426088Z","steps":["trace[1468874505] 'read index received' (duration: 186.969596ms)","trace[1468874505] 'applied index is now lower than readState.Index' (duration: 12.536854ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:16:46.426224Z","caller":"traceutil/trace.go:171","msg":"trace[498599024] transaction","detail":"{read_only:false; response_revision:26819; number_of_response:1; }","duration":"261.908017ms","start":"2026-10-13T03:16:46.164264Z","end":"2026-10-13T03:16:46.426172Z","steps":["trace[498599024] 'process raft request' (duration: 261.672152ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:46.426344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.752134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5867da94-5099-4f91-ad4f-6b77e364006f\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-10-13T03:16:46.426403Z","caller":"traceutil/trace.go:171","msg":"trace[953326063] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5867da94-5099-4f91-ad4f-6b77e364006f; range_end:; response_count:1; response_revision:26819; }","duration":"199.864217ms","start":"2026-10-13T03:16:46.226522Z","end":"2026-10-13T03:16:46.426386Z","steps":["trace[953326063] 'agreement among raft nodes before linearized reading' (duration: 199.678683ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:46.660872Z","caller":"traceutil/trace.go:171","msg":"trace[75959909] linearizableReadLoop","detail":"{readStateIndex:27701; appliedIndex:27700; }","duration":"226.232061ms","start":"2026-10-13T03:16:46.434613Z","end":"2026-10-13T03:16:46.660845Z","steps":["trace[75959909] 'read index received' (duration: 226.024977ms)","trace[75959909] 'applied index is now lower than readState.Index' (duration: 205.984µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:16:46.660933Z","caller":"traceutil/trace.go:171","msg":"trace[63318994] transaction","detail":"{read_only:false; response_revision:26820; number_of_response:1; }","duration":"229.621533ms","start":"2026-10-13T03:16:46.431267Z","end":"2026-10-13T03:16:46.660888Z","steps":["trace[63318994] 'process raft request' (duration: 229.4217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:46.661078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.804526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5867da94-5099-4f91-ad4f-6b77e364006f\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-10-13T03:16:46.661119Z","caller":"traceutil/trace.go:171","msg":"trace[274238930] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5867da94-5099-4f91-ad4f-6b77e364006f; range_end:; response_count:1; response_revision:26820; }","duration":"168.932878ms","start":"2026-10-13T03:16:46.492176Z","end":"2026-10-13T03:16:46.661109Z","steps":["trace[274238930] 'agreement among raft nodes before linearized reading' (duration: 168.809226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:46.661149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.505896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-10-13T03:16:46.661216Z","caller":"traceutil/trace.go:171","msg":"trace[62985955] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:26820; }","duration":"226.617237ms","start":"2026-10-13T03:16:46.434584Z","end":"2026-10-13T03:16:46.661201Z","steps":["trace[62985955] 'agreement among raft nodes before linearized reading' (duration: 226.385483ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:47.174942Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2408758616430133917,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-13T03:16:47.177413Z","caller":"traceutil/trace.go:171","msg":"trace[1436582087] transaction","detail":"{read_only:false; response_revision:26821; number_of_response:1; }","duration":"506.621871ms","start":"2026-10-13T03:16:46.670763Z","end":"2026-10-13T03:16:47.177385Z","steps":["trace[1436582087] 'process raft request' (duration: 506.446058ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:47.17757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:46.670748Z","time spent":"506.739334ms","remote":"127.0.0.1:33724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":724,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:16:47.609027Z","caller":"traceutil/trace.go:171","msg":"trace[146068233] transaction","detail":"{read_only:false; response_revision:26824; number_of_response:1; }","duration":"912.762456ms","start":"2026-10-13T03:16:46.696237Z","end":"2026-10-13T03:16:47.609Z","steps":["trace[146068233] 'process raft request' (duration: 912.656734ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:47.609069Z","caller":"traceutil/trace.go:171","msg":"trace[1642475864] transaction","detail":"{read_only:false; response_revision:26823; number_of_response:1; }","duration":"913.366938ms","start":"2026-10-13T03:16:46.695642Z","end":"2026-10-13T03:16:47.609009Z","steps":["trace[1642475864] 'process raft request' (duration: 913.207825ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:47.609131Z","caller":"traceutil/trace.go:171","msg":"trace[1375406446] transaction","detail":"{read_only:false; response_revision:26826; number_of_response:1; }","duration":"905.866457ms","start":"2026-10-13T03:16:46.703254Z","end":"2026-10-13T03:16:47.609121Z","steps":["trace[1375406446] 'process raft request' (duration: 905.799326ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:47.609131Z","caller":"traceutil/trace.go:171","msg":"trace[1118184336] linearizableReadLoop","detail":"{readStateIndex:27707; appliedIndex:27701; }","duration":"934.518733ms","start":"2026-10-13T03:16:46.674596Z","end":"2026-10-13T03:16:47.609115Z","steps":["trace[1118184336] 'read index received' (duration: 502.668158ms)","trace[1118184336] 'applied index is now lower than readState.Index' (duration: 431.849925ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:16:47.609195Z","caller":"traceutil/trace.go:171","msg":"trace[1646891684] transaction","detail":"{read_only:false; response_revision:26825; number_of_response:1; }","duration":"912.336968ms","start":"2026-10-13T03:16:46.696812Z","end":"2026-10-13T03:16:47.609149Z","steps":["trace[1646891684] 'process raft request' (duration: 912.154375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:47.609253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:46.695618Z","time spent":"913.535212ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:47.609271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:46.703231Z","time spent":"905.94343ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:47.609281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.673446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-10-13T03:16:47.609047Z","caller":"traceutil/trace.go:171","msg":"trace[1154532639] transaction","detail":"{read_only:false; response_revision:26822; number_of_response:1; }","duration":"918.301169ms","start":"2026-10-13T03:16:46.69067Z","end":"2026-10-13T03:16:47.608971Z","steps":["trace[1154532639] 'process raft request' (duration: 918.023654ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:47.609306Z","caller":"traceutil/trace.go:171","msg":"trace[13796492] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26826; }","duration":"934.744918ms","start":"2026-10-13T03:16:46.674555Z","end":"2026-10-13T03:16:47.609299Z","steps":["trace[13796492] 'agreement among raft nodes before linearized reading' (duration: 934.642146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:47.609325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:46.674535Z","time spent":"934.785528ms","remote":"127.0.0.1:34162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9628,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" "} {"level":"warn","ts":"2026-10-13T03:16:47.609411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:46.696786Z","time spent":"912.514162ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:47.609525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:46.69622Z","time spent":"913.073262ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:47.609515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:46.690636Z","time spent":"918.691908ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:47.612203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.366886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18ddf87424f2b304\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-10-13T03:16:47.612271Z","caller":"traceutil/trace.go:171","msg":"trace[1822621342] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18ddf87424f2b304; range_end:; response_count:1; response_revision:26829; }","duration":"431.476028ms","start":"2026-10-13T03:16:47.180778Z","end":"2026-10-13T03:16:47.612254Z","steps":["trace[1822621342] 'agreement among raft nodes before linearized reading' (duration: 431.246414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:47.61231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:47.180761Z","time spent":"431.539529ms","remote":"127.0.0.1:33724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":760,"request content":"key:\"/registry/events/openstack/rabbitmq-manila.18ddf87424f2b304\" "} {"level":"warn","ts":"2026-10-13T03:16:47.612488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.54089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-13T03:16:47.612528Z","caller":"traceutil/trace.go:171","msg":"trace[56354266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26829; }","duration":"292.592972ms","start":"2026-10-13T03:16:47.319925Z","end":"2026-10-13T03:16:47.612517Z","steps":["trace[56354266] 'agreement among raft nodes before linearized reading' (duration: 292.484ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:47.612576Z","caller":"traceutil/trace.go:171","msg":"trace[35334144] transaction","detail":"{read_only:false; response_revision:26827; number_of_response:1; }","duration":"402.739131ms","start":"2026-10-13T03:16:47.20982Z","end":"2026-10-13T03:16:47.612559Z","steps":["trace[35334144] 'process raft request' (duration: 401.977586ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:47.612659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:47.209791Z","time spent":"402.819072ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:16:47.612765Z","caller":"traceutil/trace.go:171","msg":"trace[691454024] transaction","detail":"{read_only:false; response_revision:26828; number_of_response:1; }","duration":"399.635942ms","start":"2026-10-13T03:16:47.21311Z","end":"2026-10-13T03:16:47.612746Z","steps":["trace[691454024] 'process raft request' (duration: 398.803246ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:47.612789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.864977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-13T03:16:47.612824Z","caller":"traceutil/trace.go:171","msg":"trace[526231663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26829; }","duration":"292.911397ms","start":"2026-10-13T03:16:47.319901Z","end":"2026-10-13T03:16:47.612812Z","steps":["trace[526231663] 'agreement among raft nodes before linearized reading' (duration: 292.808626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:47.612873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:16:47.213101Z","time spent":"399.700634ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:16:47.613013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.547564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-13T03:16:47.612986Z","caller":"traceutil/trace.go:171","msg":"trace[51046866] transaction","detail":"{read_only:false; response_revision:26829; number_of_response:1; }","duration":"267.271868ms","start":"2026-10-13T03:16:47.34568Z","end":"2026-10-13T03:16:47.612952Z","steps":["trace[51046866] 'process raft request' (duration: 266.2869ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:47.613073Z","caller":"traceutil/trace.go:171","msg":"trace[1702241264] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26829; }","duration":"297.640185ms","start":"2026-10-13T03:16:47.31542Z","end":"2026-10-13T03:16:47.613061Z","steps":["trace[1702241264] 'agreement among raft nodes before linearized reading' (duration: 297.523533ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:47.613142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.257953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-13T03:16:47.613244Z","caller":"traceutil/trace.go:171","msg":"trace[578093536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26829; }","duration":"293.302455ms","start":"2026-10-13T03:16:47.319858Z","end":"2026-10-13T03:16:47.61316Z","steps":["trace[578093536] 'agreement among raft nodes before linearized reading' (duration: 293.232163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:47.61334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.304361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-13T03:16:47.613416Z","caller":"traceutil/trace.go:171","msg":"trace[1344813771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26829; }","duration":"295.401093ms","start":"2026-10-13T03:16:47.317998Z","end":"2026-10-13T03:16:47.613399Z","steps":["trace[1344813771] 'agreement among raft nodes before linearized reading' (duration: 295.188019ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:49.725571Z","caller":"traceutil/trace.go:171","msg":"trace[286249881] transaction","detail":"{read_only:false; response_revision:26843; number_of_response:1; }","duration":"109.956666ms","start":"2026-10-13T03:16:49.615525Z","end":"2026-10-13T03:16:49.725482Z","steps":["trace[286249881] 'process raft request' (duration: 109.64898ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:49.739441Z","caller":"traceutil/trace.go:171","msg":"trace[1615592361] transaction","detail":"{read_only:false; response_revision:26844; number_of_response:1; }","duration":"123.799114ms","start":"2026-10-13T03:16:49.61562Z","end":"2026-10-13T03:16:49.739419Z","steps":["trace[1615592361] 'process raft request' (duration: 123.238864ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:49.739675Z","caller":"traceutil/trace.go:171","msg":"trace[367534350] transaction","detail":"{read_only:false; response_revision:26845; number_of_response:1; }","duration":"124.025949ms","start":"2026-10-13T03:16:49.615639Z","end":"2026-10-13T03:16:49.739665Z","steps":["trace[367534350] 'process raft request' (duration: 123.353417ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:49.739806Z","caller":"traceutil/trace.go:171","msg":"trace[1740888673] transaction","detail":"{read_only:false; response_revision:26846; number_of_response:1; }","duration":"124.068849ms","start":"2026-10-13T03:16:49.615729Z","end":"2026-10-13T03:16:49.739798Z","steps":["trace[1740888673] 'process raft request' (duration: 123.325186ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:49.739904Z","caller":"traceutil/trace.go:171","msg":"trace[1664171263] transaction","detail":"{read_only:false; response_revision:26847; number_of_response:1; }","duration":"122.291237ms","start":"2026-10-13T03:16:49.617605Z","end":"2026-10-13T03:16:49.739896Z","steps":["trace[1664171263] 'process raft request' (duration: 121.486682ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:49.739976Z","caller":"traceutil/trace.go:171","msg":"trace[1580452857] transaction","detail":"{read_only:false; response_revision:26848; number_of_response:1; }","duration":"120.432241ms","start":"2026-10-13T03:16:49.619535Z","end":"2026-10-13T03:16:49.739967Z","steps":["trace[1580452857] 'process raft request' (duration: 119.594776ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:49.740084Z","caller":"traceutil/trace.go:171","msg":"trace[2060428513] transaction","detail":"{read_only:false; response_revision:26849; number_of_response:1; }","duration":"117.640949ms","start":"2026-10-13T03:16:49.622433Z","end":"2026-10-13T03:16:49.740074Z","steps":["trace[2060428513] 'process raft request' (duration: 116.732963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:16:53.353509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.006418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-13T03:16:53.353587Z","caller":"traceutil/trace.go:171","msg":"trace[904701387] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:26888; }","duration":"147.168141ms","start":"2026-10-13T03:16:53.206403Z","end":"2026-10-13T03:16:53.353571Z","steps":["trace[904701387] 'count revisions from in-memory index tree' (duration: 146.897426ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:55.875921Z","caller":"traceutil/trace.go:171","msg":"trace[551588659] transaction","detail":"{read_only:false; response_revision:26916; number_of_response:1; }","duration":"117.271682ms","start":"2026-10-13T03:16:55.758611Z","end":"2026-10-13T03:16:55.875882Z","steps":["trace[551588659] 'process raft request' (duration: 116.999467ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:55.876302Z","caller":"traceutil/trace.go:171","msg":"trace[1194301] transaction","detail":"{read_only:false; response_revision:26917; number_of_response:1; }","duration":"112.405992ms","start":"2026-10-13T03:16:55.76388Z","end":"2026-10-13T03:16:55.876286Z","steps":["trace[1194301] 'process raft request' (duration: 111.908152ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:55.876606Z","caller":"traceutil/trace.go:171","msg":"trace[1413796562] transaction","detail":"{read_only:false; response_revision:26918; number_of_response:1; }","duration":"111.137838ms","start":"2026-10-13T03:16:55.765452Z","end":"2026-10-13T03:16:55.87659Z","steps":["trace[1413796562] 'process raft request' (duration: 110.739891ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:55.87672Z","caller":"traceutil/trace.go:171","msg":"trace[944994732] transaction","detail":"{read_only:false; response_revision:26921; number_of_response:1; }","duration":"106.98449ms","start":"2026-10-13T03:16:55.769723Z","end":"2026-10-13T03:16:55.876707Z","steps":["trace[944994732] 'process raft request' (duration: 106.908049ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:55.876806Z","caller":"traceutil/trace.go:171","msg":"trace[112496773] transaction","detail":"{read_only:false; response_revision:26920; number_of_response:1; }","duration":"109.63151ms","start":"2026-10-13T03:16:55.767165Z","end":"2026-10-13T03:16:55.876797Z","steps":["trace[112496773] 'process raft request' (duration: 109.421156ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:16:55.876711Z","caller":"traceutil/trace.go:171","msg":"trace[1271475879] transaction","detail":"{read_only:false; response_revision:26919; number_of_response:1; }","duration":"110.936825ms","start":"2026-10-13T03:16:55.76575Z","end":"2026-10-13T03:16:55.876687Z","steps":["trace[1271475879] 'process raft request' (duration: 110.69369ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:17.458235Z","caller":"traceutil/trace.go:171","msg":"trace[173343844] transaction","detail":"{read_only:false; response_revision:27092; number_of_response:1; }","duration":"179.391575ms","start":"2026-10-13T03:17:17.278815Z","end":"2026-10-13T03:17:17.458206Z","steps":["trace[173343844] 'process raft request' (duration: 178.989527ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:17.786142Z","caller":"traceutil/trace.go:171","msg":"trace[1638756322] linearizableReadLoop","detail":"{readStateIndex:27980; appliedIndex:27979; }","duration":"148.947365ms","start":"2026-10-13T03:17:17.637162Z","end":"2026-10-13T03:17:17.786109Z","steps":["trace[1638756322] 'read index received' (duration: 108.905335ms)","trace[1638756322] 'applied index is now lower than readState.Index' (duration: 40.04072ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:17:17.786193Z","caller":"traceutil/trace.go:171","msg":"trace[597859121] transaction","detail":"{read_only:false; response_revision:27093; number_of_response:1; }","duration":"158.903672ms","start":"2026-10-13T03:17:17.627257Z","end":"2026-10-13T03:17:17.78616Z","steps":["trace[597859121] 'process raft request' (duration: 118.888462ms)","trace[597859121] 'compare' (duration: 39.758145ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:17:17.786323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.136469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:17:17.786396Z","caller":"traceutil/trace.go:171","msg":"trace[1189510561] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:27093; }","duration":"149.25693ms","start":"2026-10-13T03:17:17.637116Z","end":"2026-10-13T03:17:17.786373Z","steps":["trace[1189510561] 'agreement among raft nodes before linearized reading' (duration: 149.137838ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:18.311654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.806572ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-13T03:17:18.311943Z","caller":"traceutil/trace.go:171","msg":"trace[2133892630] linearizableReadLoop","detail":"{readStateIndex:27981; appliedIndex:27980; }","duration":"525.698798ms","start":"2026-10-13T03:17:17.786223Z","end":"2026-10-13T03:17:18.311922Z","steps":["trace[2133892630] 'read index received' (duration: 115.751054ms)","trace[2133892630] 'applied index is now lower than readState.Index' (duration: 409.946464ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:17:18.312051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.401365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-13T03:17:18.312072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.542959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:17:18.312149Z","caller":"traceutil/trace.go:171","msg":"trace[203173102] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:27093; }","duration":"578.568148ms","start":"2026-10-13T03:17:17.733558Z","end":"2026-10-13T03:17:18.312126Z","steps":["trace[203173102] 'agreement among raft nodes before linearized reading' (duration: 578.366693ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:18.312151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.768881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-10-13T03:17:18.312164Z","caller":"traceutil/trace.go:171","msg":"trace[847843170] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:27093; }","duration":"592.715113ms","start":"2026-10-13T03:17:17.719423Z","end":"2026-10-13T03:17:18.312138Z","steps":["trace[847843170] 'agreement among raft nodes before linearized reading' (duration: 592.524099ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:18.312191Z","caller":"traceutil/trace.go:171","msg":"trace[761664939] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:27093; }","duration":"609.853523ms","start":"2026-10-13T03:17:17.702322Z","end":"2026-10-13T03:17:18.312176Z","steps":["trace[761664939] 'agreement among raft nodes before linearized reading' (duration: 609.689659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:18.31219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:17.733545Z","time spent":"578.63388ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":32,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-10-13T03:17:18.312208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:17.719403Z","time spent":"592.793984ms","remote":"127.0.0.1:55928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"warn","ts":"2026-10-13T03:17:18.312236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:17.702304Z","time spent":"609.923554ms","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3332,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-10-13T03:17:18.312553Z","caller":"traceutil/trace.go:171","msg":"trace[1928474069] transaction","detail":"{read_only:false; response_revision:27094; number_of_response:1; }","duration":"422.875377ms","start":"2026-10-13T03:17:17.889637Z","end":"2026-10-13T03:17:18.312513Z","steps":["trace[1928474069] 'process raft request' (duration: 422.142753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:18.313339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:17.889614Z","time spent":"423.652741ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:17:18.631489Z","caller":"traceutil/trace.go:171","msg":"trace[394516884] linearizableReadLoop","detail":"{readStateIndex:27988; appliedIndex:27982; }","duration":"319.467264ms","start":"2026-10-13T03:17:18.311982Z","end":"2026-10-13T03:17:18.631449Z","steps":["trace[394516884] 'read index received' (duration: 276.080102ms)","trace[394516884] 'applied index is now lower than readState.Index' (duration: 43.384962ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:17:18.63153Z","caller":"traceutil/trace.go:171","msg":"trace[1563670982] transaction","detail":"{read_only:false; response_revision:27096; number_of_response:1; }","duration":"650.960152ms","start":"2026-10-13T03:17:17.980512Z","end":"2026-10-13T03:17:18.631472Z","steps":["trace[1563670982] 'process raft request' (duration: 650.051144ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:18.631555Z","caller":"traceutil/trace.go:171","msg":"trace[777289543] transaction","detail":"{read_only:false; response_revision:27095; number_of_response:1; }","duration":"660.893327ms","start":"2026-10-13T03:17:17.970625Z","end":"2026-10-13T03:17:18.631518Z","steps":["trace[777289543] 'process raft request' (duration: 617.278101ms)","trace[777289543] 'compare' (duration: 42.099647ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:17:18.631619Z","caller":"traceutil/trace.go:171","msg":"trace[1639643856] transaction","detail":"{read_only:false; response_revision:27098; number_of_response:1; }","duration":"641.940504ms","start":"2026-10-13T03:17:17.98967Z","end":"2026-10-13T03:17:18.63161Z","steps":["trace[1639643856] 'process raft request' (duration: 641.036256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:18.631731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:17.970588Z","time spent":"661.03846ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:18.631739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:17.989656Z","time spent":"641.998314ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:18.63173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.891107ms","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":"warn","ts":"2026-10-13T03:17:18.631758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:17.9805Z","time spent":"651.127235ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:17:18.631801Z","caller":"traceutil/trace.go:171","msg":"trace[836686775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27102; }","duration":"615.00605ms","start":"2026-10-13T03:17:18.016777Z","end":"2026-10-13T03:17:18.631783Z","steps":["trace[836686775] 'agreement among raft nodes before linearized reading' (duration: 614.726185ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:18.631895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.152336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:17:18.631575Z","caller":"traceutil/trace.go:171","msg":"trace[1133199160] transaction","detail":"{read_only:false; response_revision:27097; number_of_response:1; }","duration":"646.54716ms","start":"2026-10-13T03:17:17.985014Z","end":"2026-10-13T03:17:18.631561Z","steps":["trace[1133199160] 'process raft request' (duration: 645.647672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:18.631935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.670515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-10-13T03:17:18.631947Z","caller":"traceutil/trace.go:171","msg":"trace[411481026] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:27102; }","duration":"344.255017ms","start":"2026-10-13T03:17:18.287677Z","end":"2026-10-13T03:17:18.631932Z","steps":["trace[411481026] 'agreement among raft nodes before linearized reading' (duration: 344.180796ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:18.63197Z","caller":"traceutil/trace.go:171","msg":"trace[703702740] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:27102; }","duration":"313.736846ms","start":"2026-10-13T03:17:18.318219Z","end":"2026-10-13T03:17:18.631956Z","steps":["trace[703702740] 'agreement among raft nodes before linearized reading' (duration: 313.653924ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:18.631977Z","caller":"traceutil/trace.go:171","msg":"trace[1723752789] transaction","detail":"{read_only:false; response_revision:27101; number_of_response:1; }","duration":"247.974217ms","start":"2026-10-13T03:17:18.38399Z","end":"2026-10-13T03:17:18.631965Z","steps":["trace[1723752789] 'process raft request' (duration: 246.828895ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:18.631983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:18.287657Z","time spent":"344.317528ms","remote":"127.0.0.1:56058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true "} {"level":"warn","ts":"2026-10-13T03:17:18.63199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.376452ms","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-13T03:17:18.632005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:18.318201Z","time spent":"313.795728ms","remote":"127.0.0.1:33750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1336,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"info","ts":"2026-10-13T03:17:18.632022Z","caller":"traceutil/trace.go:171","msg":"trace[1951852313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27102; }","duration":"602.424744ms","start":"2026-10-13T03:17:18.029587Z","end":"2026-10-13T03:17:18.632012Z","steps":["trace[1951852313] 'agreement among raft nodes before linearized reading' (duration: 602.330462ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:18.632012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:17.984985Z","time spent":"646.970407ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:18.632072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:18.029576Z","time spent":"602.489095ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-13T03:17:18.631828Z","caller":"traceutil/trace.go:171","msg":"trace[2098387489] transaction","detail":"{read_only:false; response_revision:27100; number_of_response:1; }","duration":"567.294658ms","start":"2026-10-13T03:17:18.064491Z","end":"2026-10-13T03:17:18.631786Z","steps":["trace[2098387489] 'process raft request' (duration: 566.285578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:18.632137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:18.064471Z","time spent":"567.626783ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:18.631739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.773373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-13T03:17:18.631891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:18.016761Z","time spent":"615.113961ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-13T03:17:18.632189Z","caller":"traceutil/trace.go:171","msg":"trace[1162759331] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27102; }","duration":"616.254073ms","start":"2026-10-13T03:17:18.015919Z","end":"2026-10-13T03:17:18.632173Z","steps":["trace[1162759331] 'agreement among raft nodes before linearized reading' (duration: 615.689902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:18.631842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.031326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-13T03:17:18.632238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:18.015908Z","time spent":"616.316454ms","remote":"127.0.0.1:33938","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-13T03:17:18.632272Z","caller":"traceutil/trace.go:171","msg":"trace[506963960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27102; }","duration":"626.485344ms","start":"2026-10-13T03:17:18.005775Z","end":"2026-10-13T03:17:18.63226Z","steps":["trace[506963960] 'agreement among raft nodes before linearized reading' (duration: 625.990165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:18.632303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:18.005763Z","time spent":"626.530805ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-13T03:17:18.631495Z","caller":"traceutil/trace.go:171","msg":"trace[195174333] transaction","detail":"{read_only:false; response_revision:27099; number_of_response:1; }","duration":"639.022739ms","start":"2026-10-13T03:17:17.992431Z","end":"2026-10-13T03:17:18.631454Z","steps":["trace[195174333] 'process raft request' (duration: 638.315185ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:18.632395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.521342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19028"} {"level":"info","ts":"2026-10-13T03:17:18.632431Z","caller":"traceutil/trace.go:171","msg":"trace[175905060] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27102; }","duration":"114.584102ms","start":"2026-10-13T03:17:18.517835Z","end":"2026-10-13T03:17:18.632419Z","steps":["trace[175905060] 'agreement among raft nodes before linearized reading' (duration: 114.47418ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:18.63245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:17.992404Z","time spent":"639.962806ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:18.631713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.397195ms","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-13T03:17:18.63263Z","caller":"traceutil/trace.go:171","msg":"trace[1042337155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27102; }","duration":"602.352153ms","start":"2026-10-13T03:17:18.030259Z","end":"2026-10-13T03:17:18.632611Z","steps":["trace[1042337155] 'agreement among raft nodes before linearized reading' (duration: 601.229812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:18.632685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:18.030249Z","time spent":"602.419464ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-13T03:17:18.915608Z","caller":"traceutil/trace.go:171","msg":"trace[648212042] transaction","detail":"{read_only:false; response_revision:27103; number_of_response:1; }","duration":"278.60619ms","start":"2026-10-13T03:17:18.636959Z","end":"2026-10-13T03:17:18.915565Z","steps":["trace[648212042] 'process raft request' (duration: 189.497973ms)","trace[648212042] 'compare' (duration: 88.72989ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:17:19.248277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.356185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T03:17:19.248738Z","caller":"traceutil/trace.go:171","msg":"trace[1586263878] transaction","detail":"{read_only:false; response_revision:27104; number_of_response:1; }","duration":"611.426962ms","start":"2026-10-13T03:17:18.637262Z","end":"2026-10-13T03:17:19.248689Z","steps":["trace[1586263878] 'process raft request' (duration: 401.533427ms)","trace[1586263878] 'compare' (duration: 208.904406ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:17:19.248777Z","caller":"traceutil/trace.go:171","msg":"trace[398986127] transaction","detail":"{read_only:false; response_revision:27106; number_of_response:1; }","duration":"610.2667ms","start":"2026-10-13T03:17:18.638486Z","end":"2026-10-13T03:17:19.248752Z","steps":["trace[398986127] 'process raft request' (duration: 610.158578ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:19.248789Z","caller":"traceutil/trace.go:171","msg":"trace[1995936454] transaction","detail":"{read_only:false; response_revision:27105; number_of_response:1; }","duration":"610.350302ms","start":"2026-10-13T03:17:18.638378Z","end":"2026-10-13T03:17:19.248728Z","steps":["trace[1995936454] 'process raft request' (duration: 610.017296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:19.248884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:18.638475Z","time spent":"610.352872ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:19.248905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:18.637238Z","time spent":"611.577474ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:17:19.248946Z","caller":"traceutil/trace.go:171","msg":"trace[1937708744] linearizableReadLoop","detail":"{readStateIndex:27994; appliedIndex:27990; }","duration":"608.159151ms","start":"2026-10-13T03:17:18.640756Z","end":"2026-10-13T03:17:19.248915Z","steps":["trace[1937708744] 'read index received' (duration: 185.700292ms)","trace[1937708744] 'applied index is now lower than readState.Index' (duration: 422.457239ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:17:19.248968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:18.638359Z","time spent":"610.518586ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:19.249122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.664032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:17:19.249093Z","caller":"traceutil/trace.go:171","msg":"trace[1160873548] transaction","detail":"{read_only:false; response_revision:27107; number_of_response:1; }","duration":"605.842278ms","start":"2026-10-13T03:17:18.643198Z","end":"2026-10-13T03:17:19.249023Z","steps":["trace[1160873548] 'process raft request' (duration: 605.498861ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:19.249169Z","caller":"traceutil/trace.go:171","msg":"trace[1490734409] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:27107; }","duration":"607.729373ms","start":"2026-10-13T03:17:18.641421Z","end":"2026-10-13T03:17:19.24915Z","steps":["trace[1490734409] 'agreement among raft nodes before linearized reading' (duration: 607.657281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:19.249186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.433386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19028"} {"level":"warn","ts":"2026-10-13T03:17:19.249186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.386686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-13T03:17:19.249166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.883733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T03:17:19.249206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:18.641408Z","time spent":"607.790114ms","remote":"127.0.0.1:33738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":30,"request content":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" "} {"level":"info","ts":"2026-10-13T03:17:19.249217Z","caller":"traceutil/trace.go:171","msg":"trace[325456595] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27107; }","duration":"608.495917ms","start":"2026-10-13T03:17:18.640711Z","end":"2026-10-13T03:17:19.249207Z","steps":["trace[325456595] 'agreement among raft nodes before linearized reading' (duration: 608.344394ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:19.249235Z","caller":"traceutil/trace.go:171","msg":"trace[510598216] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:27107; }","duration":"562.466207ms","start":"2026-10-13T03:17:18.686751Z","end":"2026-10-13T03:17:19.249217Z","steps":["trace[510598216] 'agreement among raft nodes before linearized reading' (duration: 562.374145ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:19.249248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:18.640699Z","time spent":"608.543048ms","remote":"127.0.0.1:33826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19052,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-10-13T03:17:19.249253Z","caller":"traceutil/trace.go:171","msg":"trace[1455907701] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:27107; }","duration":"380.040806ms","start":"2026-10-13T03:17:18.86919Z","end":"2026-10-13T03:17:19.249231Z","steps":["trace[1455907701] 'agreement among raft nodes before linearized reading' (duration: 379.895913ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:19.249284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:18.686732Z","time spent":"562.539239ms","remote":"127.0.0.1:47098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":4,"response size":32,"request content":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true "} {"level":"warn","ts":"2026-10-13T03:17:19.249297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:18.869163Z","time spent":"380.121238ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":30,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-10-13T03:17:19.249377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:18.643185Z","time spent":"606.00961ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:19.545668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.614171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T03:17:19.545785Z","caller":"traceutil/trace.go:171","msg":"trace[1663520186] linearizableReadLoop","detail":"{readStateIndex:27996; appliedIndex:27995; }","duration":"287.799292ms","start":"2026-10-13T03:17:19.257966Z","end":"2026-10-13T03:17:19.545766Z","steps":["trace[1663520186] 'read index received' (duration: 148.016888ms)","trace[1663520186] 'applied index is now lower than readState.Index' (duration: 139.780984ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:17:19.545853Z","caller":"traceutil/trace.go:171","msg":"trace[98128226] transaction","detail":"{read_only:false; response_revision:27108; number_of_response:1; }","duration":"288.665998ms","start":"2026-10-13T03:17:19.257162Z","end":"2026-10-13T03:17:19.545828Z","steps":["trace[98128226] 'process raft request' (duration: 148.783112ms)","trace[98128226] 'compare' (duration: 138.937019ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:17:19.545939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.974825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1442"} {"level":"info","ts":"2026-10-13T03:17:19.545973Z","caller":"traceutil/trace.go:171","msg":"trace[1872843874] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:27108; }","duration":"288.043566ms","start":"2026-10-13T03:17:19.257918Z","end":"2026-10-13T03:17:19.545961Z","steps":["trace[1872843874] 'agreement among raft nodes before linearized reading' (duration: 287.895054ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:20.896261Z","caller":"traceutil/trace.go:171","msg":"trace[280400744] transaction","detail":"{read_only:false; response_revision:27112; number_of_response:1; }","duration":"256.282942ms","start":"2026-10-13T03:17:20.639952Z","end":"2026-10-13T03:17:20.896235Z","steps":["trace[280400744] 'process raft request' (duration: 213.996061ms)","trace[280400744] 'compare' (duration: 41.829003ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:17:20.896326Z","caller":"traceutil/trace.go:171","msg":"trace[1212216398] transaction","detail":"{read_only:false; response_revision:27114; number_of_response:1; }","duration":"256.127688ms","start":"2026-10-13T03:17:20.640167Z","end":"2026-10-13T03:17:20.896294Z","steps":["trace[1212216398] 'process raft request' (duration: 256.031296ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:20.896338Z","caller":"traceutil/trace.go:171","msg":"trace[889421847] transaction","detail":"{read_only:false; response_revision:27113; number_of_response:1; }","duration":"256.21348ms","start":"2026-10-13T03:17:20.640073Z","end":"2026-10-13T03:17:20.896287Z","steps":["trace[889421847] 'process raft request' (duration: 255.901354ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:20.896586Z","caller":"traceutil/trace.go:171","msg":"trace[56535865] transaction","detail":"{read_only:false; response_revision:27115; number_of_response:1; }","duration":"256.342692ms","start":"2026-10-13T03:17:20.640231Z","end":"2026-10-13T03:17:20.896574Z","steps":["trace[56535865] 'process raft request' (duration: 255.997656ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:20.896749Z","caller":"traceutil/trace.go:171","msg":"trace[578458082] transaction","detail":"{read_only:false; response_revision:27117; number_of_response:1; }","duration":"253.033581ms","start":"2026-10-13T03:17:20.6437Z","end":"2026-10-13T03:17:20.896733Z","steps":["trace[578458082] 'process raft request' (duration: 252.845298ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:20.896738Z","caller":"traceutil/trace.go:171","msg":"trace[94183601] transaction","detail":"{read_only:false; response_revision:27116; number_of_response:1; }","duration":"253.762154ms","start":"2026-10-13T03:17:20.642945Z","end":"2026-10-13T03:17:20.896707Z","steps":["trace[94183601] 'process raft request' (duration: 253.516639ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:22.450185Z","caller":"traceutil/trace.go:171","msg":"trace[1493950518] transaction","detail":"{read_only:false; response_revision:27123; number_of_response:1; }","duration":"126.996425ms","start":"2026-10-13T03:17:22.323155Z","end":"2026-10-13T03:17:22.450151Z","steps":["trace[1493950518] 'process raft request' (duration: 126.771911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:22.745163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.075913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T03:17:22.745283Z","caller":"traceutil/trace.go:171","msg":"trace[1044551279] linearizableReadLoop","detail":"{readStateIndex:28012; appliedIndex:28011; }","duration":"197.304938ms","start":"2026-10-13T03:17:22.547956Z","end":"2026-10-13T03:17:22.745261Z","steps":["trace[1044551279] 'read index received' (duration: 36.963141ms)","trace[1044551279] 'applied index is now lower than readState.Index' (duration: 160.340347ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:17:22.745356Z","caller":"traceutil/trace.go:171","msg":"trace[268112883] transaction","detail":"{read_only:false; response_revision:27124; number_of_response:1; }","duration":"339.450027ms","start":"2026-10-13T03:17:22.405895Z","end":"2026-10-13T03:17:22.745345Z","steps":["trace[268112883] 'process raft request' (duration: 179.085879ms)","trace[268112883] 'compare' (duration: 159.616024ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:17:22.745415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:22.40586Z","time spent":"339.525089ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:22.746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.050433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.104\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-13T03:17:22.746065Z","caller":"traceutil/trace.go:171","msg":"trace[1990394931] range","detail":"{range_begin:/registry/masterleases/162.253.55.104; range_end:; response_count:1; response_revision:27124; }","duration":"198.159525ms","start":"2026-10-13T03:17:22.547891Z","end":"2026-10-13T03:17:22.74605Z","steps":["trace[1990394931] 'agreement among raft nodes before linearized reading' (duration: 197.735497ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:23.004704Z","caller":"traceutil/trace.go:171","msg":"trace[432258576] transaction","detail":"{read_only:false; response_revision:27130; number_of_response:1; }","duration":"102.12198ms","start":"2026-10-13T03:17:22.902552Z","end":"2026-10-13T03:17:23.004674Z","steps":["trace[432258576] 'process raft request' (duration: 94.756072ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:23.005193Z","caller":"traceutil/trace.go:171","msg":"trace[1195955261] transaction","detail":"{read_only:false; response_revision:27131; number_of_response:1; }","duration":"101.504767ms","start":"2026-10-13T03:17:22.903677Z","end":"2026-10-13T03:17:23.005182Z","steps":["trace[1195955261] 'process raft request' (duration: 100.902146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:38.592053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.636642ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"warn","ts":"2026-10-13T03:17:38.808222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.794589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:17:38.808318Z","caller":"traceutil/trace.go:171","msg":"trace[610988672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27240; }","duration":"235.940532ms","start":"2026-10-13T03:17:38.572353Z","end":"2026-10-13T03:17:38.808293Z","steps":["trace[610988672] 'agreement among raft nodes before linearized reading' (duration: 20.629586ms)","trace[610988672] 'range keys from in-memory index tree' (duration: 215.172344ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:17:38.952472Z","caller":"traceutil/trace.go:171","msg":"trace[1093393074] linearizableReadLoop","detail":"{readStateIndex:28133; appliedIndex:28132; }","duration":"142.740488ms","start":"2026-10-13T03:17:38.809701Z","end":"2026-10-13T03:17:38.952441Z","steps":["trace[1093393074] 'read index received' (duration: 142.476023ms)","trace[1093393074] 'applied index is now lower than readState.Index' (duration: 263.625µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:17:38.952526Z","caller":"traceutil/trace.go:171","msg":"trace[1215535822] transaction","detail":"{read_only:false; response_revision:27241; number_of_response:1; }","duration":"153.206494ms","start":"2026-10-13T03:17:38.799294Z","end":"2026-10-13T03:17:38.9525Z","steps":["trace[1215535822] 'process raft request' (duration: 152.98314ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:38.952622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.910542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:17:38.952655Z","caller":"traceutil/trace.go:171","msg":"trace[105741218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27241; }","duration":"142.996543ms","start":"2026-10-13T03:17:38.809647Z","end":"2026-10-13T03:17:38.952643Z","steps":["trace[105741218] 'agreement among raft nodes before linearized reading' (duration: 142.922162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:39.39242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.058016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T03:17:39.392541Z","caller":"traceutil/trace.go:171","msg":"trace[282768057] transaction","detail":"{read_only:false; response_revision:27242; number_of_response:1; }","duration":"350.707969ms","start":"2026-10-13T03:17:39.041809Z","end":"2026-10-13T03:17:39.392517Z","steps":["trace[282768057] 'process raft request' (duration: 247.456578ms)","trace[282768057] 'compare' (duration: 102.913474ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:17:39.392597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:39.041775Z","time spent":"350.79532ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:39.795515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.037226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T03:17:39.795908Z","caller":"traceutil/trace.go:171","msg":"trace[968437156] transaction","detail":"{read_only:false; response_revision:27249; number_of_response:1; }","duration":"218.053337ms","start":"2026-10-13T03:17:39.577835Z","end":"2026-10-13T03:17:39.795889Z","steps":["trace[968437156] 'process raft request' (duration: 218.010866ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:39.796016Z","caller":"traceutil/trace.go:171","msg":"trace[1279496625] transaction","detail":"{read_only:false; response_revision:27245; number_of_response:1; }","duration":"725.988385ms","start":"2026-10-13T03:17:39.06996Z","end":"2026-10-13T03:17:39.795948Z","steps":["trace[1279496625] 'process raft request' (duration: 725.720599ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:39.796152Z","caller":"traceutil/trace.go:171","msg":"trace[650454419] transaction","detail":"{read_only:false; response_revision:27246; number_of_response:1; }","duration":"724.342444ms","start":"2026-10-13T03:17:39.071799Z","end":"2026-10-13T03:17:39.796141Z","steps":["trace[650454419] 'process raft request' (duration: 723.914045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:39.796217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:39.071779Z","time spent":"724.397135ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:17:39.796213Z","caller":"traceutil/trace.go:171","msg":"trace[220015276] transaction","detail":"{read_only:false; response_revision:27244; number_of_response:1; }","duration":"730.61346ms","start":"2026-10-13T03:17:39.065578Z","end":"2026-10-13T03:17:39.796191Z","steps":["trace[220015276] 'process raft request' (duration: 730.037579ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:39.796248Z","caller":"traceutil/trace.go:171","msg":"trace[1221254250] transaction","detail":"{read_only:false; response_revision:27248; number_of_response:1; }","duration":"719.260299ms","start":"2026-10-13T03:17:39.076953Z","end":"2026-10-13T03:17:39.796213Z","steps":["trace[1221254250] 'process raft request' (duration: 718.817481ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:39.796019Z","caller":"traceutil/trace.go:171","msg":"trace[344997144] transaction","detail":"{read_only:false; response_revision:27243; number_of_response:1; }","duration":"732.033948ms","start":"2026-10-13T03:17:39.063946Z","end":"2026-10-13T03:17:39.79598Z","steps":["trace[344997144] 'process raft request' (duration: 513.444771ms)","trace[344997144] 'compare' (duration: 217.394954ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:17:39.7963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:39.065567Z","time spent":"730.685463ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:17:39.796289Z","caller":"traceutil/trace.go:171","msg":"trace[1776834077] transaction","detail":"{read_only:false; response_revision:27247; number_of_response:1; }","duration":"722.154323ms","start":"2026-10-13T03:17:39.074097Z","end":"2026-10-13T03:17:39.796251Z","steps":["trace[1776834077] 'process raft request' (duration: 721.644303ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:39.796291Z","caller":"traceutil/trace.go:171","msg":"trace[621399976] linearizableReadLoop","detail":"{readStateIndex:28140; appliedIndex:28133; }","duration":"713.888749ms","start":"2026-10-13T03:17:39.082363Z","end":"2026-10-13T03:17:39.796252Z","steps":["trace[621399976] 'read index received' (duration: 206.924959ms)","trace[621399976] 'applied index is now lower than readState.Index' (duration: 506.958749ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:17:39.796364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:39.063915Z","time spent":"732.404035ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:39.796399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:39.076915Z","time spent":"719.408602ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:39.796469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:39.074083Z","time spent":"722.279896ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:39.796263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:39.069929Z","time spent":"726.200149ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:39.796495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.139763ms","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-13T03:17:39.796514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.012644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-13T03:17:39.796529Z","caller":"traceutil/trace.go:171","msg":"trace[1991033610] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27249; }","duration":"714.338126ms","start":"2026-10-13T03:17:39.08218Z","end":"2026-10-13T03:17:39.796518Z","steps":["trace[1991033610] 'agreement among raft nodes before linearized reading' (duration: 714.203614ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:39.796547Z","caller":"traceutil/trace.go:171","msg":"trace[335193141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27249; }","duration":"358.061175ms","start":"2026-10-13T03:17:39.438473Z","end":"2026-10-13T03:17:39.796534Z","steps":["trace[335193141] 'agreement among raft nodes before linearized reading' (duration: 357.961923ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:39.796562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:39.082158Z","time spent":"714.396107ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-13T03:17:39.796545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.053611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T03:17:39.796572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:39.438462Z","time spent":"358.103056ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-13T03:17:39.796598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.207061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-13T03:17:39.796609Z","caller":"traceutil/trace.go:171","msg":"trace[363163499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27249; }","duration":"223.161233ms","start":"2026-10-13T03:17:39.573431Z","end":"2026-10-13T03:17:39.796592Z","steps":["trace[363163499] 'agreement among raft nodes before linearized reading' (duration: 223.0288ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:39.796636Z","caller":"traceutil/trace.go:171","msg":"trace[159720139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27249; }","duration":"386.320173ms","start":"2026-10-13T03:17:39.410303Z","end":"2026-10-13T03:17:39.796623Z","steps":["trace[159720139] 'agreement among raft nodes before linearized reading' (duration: 386.12264ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:39.796648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.554112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-13T03:17:39.796672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:39.410278Z","time spent":"386.385904ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-13T03:17:39.796661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.260895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-13T03:17:39.796698Z","caller":"traceutil/trace.go:171","msg":"trace[1285985279] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:27249; }","duration":"336.664644ms","start":"2026-10-13T03:17:39.460018Z","end":"2026-10-13T03:17:39.796682Z","steps":["trace[1285985279] 'agreement among raft nodes before linearized reading' (duration: 336.566803ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:39.796728Z","caller":"traceutil/trace.go:171","msg":"trace[720838180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27249; }","duration":"369.373257ms","start":"2026-10-13T03:17:39.427334Z","end":"2026-10-13T03:17:39.796707Z","steps":["trace[720838180] 'agreement among raft nodes before linearized reading' (duration: 369.144893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:39.796741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:39.460006Z","time spent":"336.725135ms","remote":"127.0.0.1:48662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":32,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-10-13T03:17:39.796754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.886317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-13T03:17:39.796773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:39.427314Z","time spent":"369.446418ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-13T03:17:39.796804Z","caller":"traceutil/trace.go:171","msg":"trace[645887784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27249; }","duration":"369.978757ms","start":"2026-10-13T03:17:39.426811Z","end":"2026-10-13T03:17:39.796789Z","steps":["trace[645887784] 'agreement among raft nodes before linearized reading' (duration: 369.770504ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:39.796844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:39.426788Z","time spent":"370.044829ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-13T03:17:43.915Z","caller":"traceutil/trace.go:171","msg":"trace[288312105] transaction","detail":"{read_only:false; response_revision:27278; number_of_response:1; }","duration":"104.604525ms","start":"2026-10-13T03:17:43.810362Z","end":"2026-10-13T03:17:43.914966Z","steps":["trace[288312105] 'process raft request' (duration: 104.34237ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:43.915101Z","caller":"traceutil/trace.go:171","msg":"trace[377663792] transaction","detail":"{read_only:false; response_revision:27279; number_of_response:1; }","duration":"104.721028ms","start":"2026-10-13T03:17:43.810361Z","end":"2026-10-13T03:17:43.915082Z","steps":["trace[377663792] 'process raft request' (duration: 104.541175ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:44.239938Z","caller":"traceutil/trace.go:171","msg":"trace[1057106327] transaction","detail":"{read_only:false; response_revision:27280; number_of_response:1; }","duration":"429.281597ms","start":"2026-10-13T03:17:43.810625Z","end":"2026-10-13T03:17:44.239907Z","steps":["trace[1057106327] 'process raft request' (duration: 429.025452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:44.240165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:43.810603Z","time spent":"429.443989ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:17:44.240362Z","caller":"traceutil/trace.go:171","msg":"trace[712980970] transaction","detail":"{read_only:false; response_revision:27281; number_of_response:1; }","duration":"424.122029ms","start":"2026-10-13T03:17:43.816196Z","end":"2026-10-13T03:17:44.240318Z","steps":["trace[712980970] 'process raft request' (duration: 423.660781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:44.240974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:43.816178Z","time spent":"424.275433ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:44.430776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.38231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T03:17:44.430979Z","caller":"traceutil/trace.go:171","msg":"trace[76795115] linearizableReadLoop","detail":"{readStateIndex:28176; appliedIndex:28170; }","duration":"585.739132ms","start":"2026-10-13T03:17:43.845221Z","end":"2026-10-13T03:17:44.43096Z","steps":["trace[76795115] 'read index received' (duration: 69.5094ms)","trace[76795115] 'applied index is now lower than readState.Index' (duration: 516.228812ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:17:44.431118Z","caller":"traceutil/trace.go:171","msg":"trace[772481850] transaction","detail":"{read_only:false; response_revision:27283; number_of_response:1; }","duration":"611.484813ms","start":"2026-10-13T03:17:43.819622Z","end":"2026-10-13T03:17:44.431107Z","steps":["trace[772481850] 'process raft request' (duration: 611.274639ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:44.431192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:43.819598Z","time spent":"611.554214ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T03:17:44.431202Z","caller":"traceutil/trace.go:171","msg":"trace[1502681402] transaction","detail":"{read_only:false; response_revision:27282; number_of_response:1; }","duration":"613.146345ms","start":"2026-10-13T03:17:43.818014Z","end":"2026-10-13T03:17:44.43116Z","steps":["trace[1502681402] 'process raft request' (duration: 422.283256ms)","trace[1502681402] 'compare' (duration: 190.223007ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:17:44.431384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:43.81801Z","time spent":"613.278827ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:44.431572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.345783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-13T03:17:44.431626Z","caller":"traceutil/trace.go:171","msg":"trace[933506406] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27283; }","duration":"586.483336ms","start":"2026-10-13T03:17:43.84513Z","end":"2026-10-13T03:17:44.431613Z","steps":["trace[933506406] 'agreement among raft nodes before linearized reading' (duration: 586.322403ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:44.431662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:43.8451Z","time spent":"586.553567ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-13T03:17:44.431805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.104038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-13T03:17:44.431872Z","caller":"traceutil/trace.go:171","msg":"trace[1020952124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27283; }","duration":"586.21871ms","start":"2026-10-13T03:17:43.845642Z","end":"2026-10-13T03:17:44.43186Z","steps":["trace[1020952124] 'agreement among raft nodes before linearized reading' (duration: 586.100488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:44.43191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:43.845621Z","time spent":"586.279812ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-13T03:17:44.432075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.810923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:17:44.432144Z","caller":"traceutil/trace.go:171","msg":"trace[2075639121] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:27283; }","duration":"478.939755ms","start":"2026-10-13T03:17:43.953186Z","end":"2026-10-13T03:17:44.432126Z","steps":["trace[2075639121] 'agreement among raft nodes before linearized reading' (duration: 478.800343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:44.432182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:43.953155Z","time spent":"479.015826ms","remote":"127.0.0.1:34274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-13T03:17:44.432204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.548677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-13T03:17:44.432238Z","caller":"traceutil/trace.go:171","msg":"trace[807528500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27283; }","duration":"579.602227ms","start":"2026-10-13T03:17:43.852625Z","end":"2026-10-13T03:17:44.432228Z","steps":["trace[807528500] 'agreement among raft nodes before linearized reading' (duration: 579.498765ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:44.432263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:43.852618Z","time spent":"579.638478ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-13T03:17:44.432422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.816846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:17:44.432471Z","caller":"traceutil/trace.go:171","msg":"trace[1733995614] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:27283; }","duration":"414.869607ms","start":"2026-10-13T03:17:44.017588Z","end":"2026-10-13T03:17:44.432458Z","steps":["trace[1733995614] 'agreement among raft nodes before linearized reading' (duration: 414.790106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:44.432488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.883973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-13T03:17:44.43251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:44.017505Z","time spent":"414.993329ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-10-13T03:17:44.432524Z","caller":"traceutil/trace.go:171","msg":"trace[399018888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27283; }","duration":"579.947693ms","start":"2026-10-13T03:17:43.852567Z","end":"2026-10-13T03:17:44.432515Z","steps":["trace[399018888] 'agreement among raft nodes before linearized reading' (duration: 579.861332ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:44.432548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:43.852553Z","time spent":"579.989454ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-13T03:17:44.43271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.827661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-13T03:17:44.432742Z","caller":"traceutil/trace.go:171","msg":"trace[625733627] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27283; }","duration":"580.879101ms","start":"2026-10-13T03:17:43.851855Z","end":"2026-10-13T03:17:44.432734Z","steps":["trace[625733627] 'agreement among raft nodes before linearized reading' (duration: 580.79758ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:44.432769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:43.851846Z","time spent":"580.914282ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-13T03:17:44.70725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.947938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T03:17:44.707468Z","caller":"traceutil/trace.go:171","msg":"trace[1607620949] transaction","detail":"{read_only:false; response_revision:27284; number_of_response:1; }","duration":"270.451847ms","start":"2026-10-13T03:17:44.436995Z","end":"2026-10-13T03:17:44.707446Z","steps":["trace[1607620949] 'process raft request' (duration: 131.204083ms)","trace[1607620949] 'compare' (duration: 138.735584ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:17:44.722223Z","caller":"traceutil/trace.go:171","msg":"trace[82153285] transaction","detail":"{read_only:false; response_revision:27285; number_of_response:1; }","duration":"284.479799ms","start":"2026-10-13T03:17:44.437704Z","end":"2026-10-13T03:17:44.722184Z","steps":["trace[82153285] 'process raft request' (duration: 284.134083ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:44.722296Z","caller":"traceutil/trace.go:171","msg":"trace[282652548] linearizableReadLoop","detail":"{readStateIndex:28181; appliedIndex:28176; }","duration":"237.558481ms","start":"2026-10-13T03:17:44.484706Z","end":"2026-10-13T03:17:44.722265Z","steps":["trace[282652548] 'read index received' (duration: 83.511781ms)","trace[282652548] 'applied index is now lower than readState.Index' (duration: 154.0455ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:17:44.722365Z","caller":"traceutil/trace.go:171","msg":"trace[4284074] transaction","detail":"{read_only:false; response_revision:27287; number_of_response:1; }","duration":"284.414307ms","start":"2026-10-13T03:17:44.437932Z","end":"2026-10-13T03:17:44.722347Z","steps":["trace[4284074] 'process raft request' (duration: 284.223393ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:44.722431Z","caller":"traceutil/trace.go:171","msg":"trace[1277422267] transaction","detail":"{read_only:false; response_revision:27286; number_of_response:1; }","duration":"284.57532ms","start":"2026-10-13T03:17:44.437817Z","end":"2026-10-13T03:17:44.722393Z","steps":["trace[1277422267] 'process raft request' (duration: 284.265274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:44.722558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.822846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-92t89\" ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-10-13T03:17:44.722452Z","caller":"traceutil/trace.go:171","msg":"trace[219526771] transaction","detail":"{read_only:false; response_revision:27288; number_of_response:1; }","duration":"284.453888ms","start":"2026-10-13T03:17:44.437973Z","end":"2026-10-13T03:17:44.722427Z","steps":["trace[219526771] 'process raft request' (duration: 284.229524ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:44.722617Z","caller":"traceutil/trace.go:171","msg":"trace[1637891490] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-92t89; range_end:; response_count:1; response_revision:27288; }","duration":"237.900949ms","start":"2026-10-13T03:17:44.4847Z","end":"2026-10-13T03:17:44.722601Z","steps":["trace[1637891490] 'agreement among raft nodes before linearized reading' (duration: 237.705045ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:44.911563Z","caller":"traceutil/trace.go:171","msg":"trace[135442447] linearizableReadLoop","detail":"{readStateIndex:28182; appliedIndex:28181; }","duration":"189.152078ms","start":"2026-10-13T03:17:44.722378Z","end":"2026-10-13T03:17:44.91153Z","steps":["trace[135442447] 'read index received' (duration: 112.167818ms)","trace[135442447] 'applied index is now lower than readState.Index' (duration: 76.98331ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:17:44.911677Z","caller":"traceutil/trace.go:171","msg":"trace[349778917] transaction","detail":"{read_only:false; response_revision:27289; number_of_response:1; }","duration":"290.552993ms","start":"2026-10-13T03:17:44.621086Z","end":"2026-10-13T03:17:44.911639Z","steps":["trace[349778917] 'process raft request' (duration: 213.388739ms)","trace[349778917] 'compare' (duration: 76.893589ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:17:44.911717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.103088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T03:17:44.911775Z","caller":"traceutil/trace.go:171","msg":"trace[389184103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27289; }","duration":"325.235541ms","start":"2026-10-13T03:17:44.586521Z","end":"2026-10-13T03:17:44.911757Z","steps":["trace[389184103] 'agreement among raft nodes before linearized reading' (duration: 325.107808ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:44.91184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:44.586501Z","time spent":"325.303403ms","remote":"127.0.0.1:33670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-13T03:17:44.932091Z","caller":"traceutil/trace.go:171","msg":"trace[1536385949] transaction","detail":"{read_only:false; response_revision:27291; number_of_response:1; }","duration":"129.157536ms","start":"2026-10-13T03:17:44.802911Z","end":"2026-10-13T03:17:44.932068Z","steps":["trace[1536385949] 'process raft request' (duration: 128.812269ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:44.932096Z","caller":"traceutil/trace.go:171","msg":"trace[398210770] transaction","detail":"{read_only:false; response_revision:27290; number_of_response:1; }","duration":"201.719251ms","start":"2026-10-13T03:17:44.730342Z","end":"2026-10-13T03:17:44.932061Z","steps":["trace[398210770] 'process raft request' (duration: 201.253483ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:45.107944Z","caller":"traceutil/trace.go:171","msg":"trace[2016741942] transaction","detail":"{read_only:false; response_revision:27293; number_of_response:1; }","duration":"170.022379ms","start":"2026-10-13T03:17:44.937894Z","end":"2026-10-13T03:17:45.107916Z","steps":["trace[2016741942] 'process raft request' (duration: 169.794185ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:45.108063Z","caller":"traceutil/trace.go:171","msg":"trace[235407127] transaction","detail":"{read_only:false; response_revision:27294; number_of_response:1; }","duration":"168.922568ms","start":"2026-10-13T03:17:44.939122Z","end":"2026-10-13T03:17:45.108045Z","steps":["trace[235407127] 'process raft request' (duration: 168.727884ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:45.164539Z","caller":"traceutil/trace.go:171","msg":"trace[597788524] transaction","detail":"{read_only:false; response_revision:27295; number_of_response:1; }","duration":"191.200145ms","start":"2026-10-13T03:17:44.973286Z","end":"2026-10-13T03:17:45.164486Z","steps":["trace[597788524] 'process raft request' (duration: 190.93067ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:45.42841Z","caller":"traceutil/trace.go:171","msg":"trace[1050673476] linearizableReadLoop","detail":"{readStateIndex:28189; appliedIndex:28188; }","duration":"149.76696ms","start":"2026-10-13T03:17:45.278614Z","end":"2026-10-13T03:17:45.428381Z","steps":["trace[1050673476] 'read index received' (duration: 101.63039ms)","trace[1050673476] 'applied index is now lower than readState.Index' (duration: 48.13566ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:17:45.428442Z","caller":"traceutil/trace.go:171","msg":"trace[1381223237] transaction","detail":"{read_only:false; response_revision:27296; number_of_response:1; }","duration":"309.255711ms","start":"2026-10-13T03:17:45.11914Z","end":"2026-10-13T03:17:45.428396Z","steps":["trace[1381223237] 'process raft request' (duration: 261.182423ms)","trace[1381223237] 'compare' (duration: 47.961416ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T03:17:45.428589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T03:17:45.119128Z","time spent":"309.388805ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3020,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T03:17:45.428663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.007624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-13T03:17:45.428762Z","caller":"traceutil/trace.go:171","msg":"trace[1376462073] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:27296; }","duration":"150.164688ms","start":"2026-10-13T03:17:45.278567Z","end":"2026-10-13T03:17:45.428732Z","steps":["trace[1376462073] 'agreement among raft nodes before linearized reading' (duration: 149.899063ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:46.050064Z","caller":"traceutil/trace.go:171","msg":"trace[1070606627] linearizableReadLoop","detail":"{readStateIndex:28191; appliedIndex:28190; }","duration":"128.700156ms","start":"2026-10-13T03:17:45.921297Z","end":"2026-10-13T03:17:46.049997Z","steps":["trace[1070606627] 'read index received' (duration: 18.293742ms)","trace[1070606627] 'applied index is now lower than readState.Index' (duration: 110.405164ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:17:46.05033Z","caller":"traceutil/trace.go:171","msg":"trace[686132430] transaction","detail":"{read_only:false; response_revision:27298; number_of_response:1; }","duration":"129.523281ms","start":"2026-10-13T03:17:45.920771Z","end":"2026-10-13T03:17:46.050294Z","steps":["trace[686132430] 'process raft request' (duration: 125.073289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T03:17:46.050413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.093113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-13T03:17:46.051795Z","caller":"traceutil/trace.go:171","msg":"trace[138582477] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27298; }","duration":"130.45373ms","start":"2026-10-13T03:17:45.921272Z","end":"2026-10-13T03:17:46.051725Z","steps":["trace[138582477] 'agreement among raft nodes before linearized reading' (duration: 128.87061ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:17:59.174717Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24603} {"level":"info","ts":"2026-10-13T03:17:59.333781Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24603,"took":"154.655141ms","hash":1026354892,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":36073472,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-13T03:17:59.333862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1026354892,"revision":24603,"compact-revision":21647} {"level":"info","ts":"2026-10-13T03:18:08.306265Z","caller":"traceutil/trace.go:171","msg":"trace[519034919] transaction","detail":"{read_only:false; response_revision:27607; number_of_response:1; }","duration":"155.784423ms","start":"2026-10-13T03:18:08.150439Z","end":"2026-10-13T03:18:08.306223Z","steps":["trace[519034919] 'process raft request' (duration: 113.538413ms)","trace[519034919] 'compare' (duration: 42.066197ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T03:19:36.863272Z","caller":"traceutil/trace.go:171","msg":"trace[769350296] transaction","detail":"{read_only:false; response_revision:28395; number_of_response:1; }","duration":"185.929167ms","start":"2026-10-13T03:19:36.677322Z","end":"2026-10-13T03:19:36.863251Z","steps":["trace[769350296] 'process raft request' (duration: 185.814954ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T03:20:50.775086Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8572cc3a5351216d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-13T03:20:50.782996Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-13T03:20:50.783118Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-13T03:22:59.18314Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27400} {"level":"info","ts":"2026-10-13T03:22:59.311211Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27400,"took":"123.687262ms","hash":2149298024,"current-db-size-bytes":58257408,"current-db-size":"58 MB","current-db-size-in-use-bytes":37969920,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-13T03:22:59.311292Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2149298024,"revision":27400,"compact-revision":24603} {"level":"info","ts":"2026-10-13T03:27:59.190774Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30283} {"level":"info","ts":"2026-10-13T03:27:59.347474Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30283,"took":"152.247388ms","hash":3849439791,"current-db-size-bytes":58257408,"current-db-size":"58 MB","current-db-size-in-use-bytes":31383552,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-13T03:27:59.347551Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3849439791,"revision":30283,"compact-revision":27400} {"level":"info","ts":"2026-10-13T03:32:59.200673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32539} {"level":"info","ts":"2026-10-13T03:32:59.37439Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32539,"took":"169.187497ms","hash":3756031022,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":30441472,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-13T03:32:59.374455Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3756031022,"revision":32539,"compact-revision":30283} {"level":"info","ts":"2026-10-13T03:37:59.207709Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34764} {"level":"info","ts":"2026-10-13T03:37:59.33858Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34764,"took":"127.686128ms","hash":3357651583,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":30920704,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-13T03:37:59.338666Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3357651583,"revision":34764,"compact-revision":32539} {"level":"info","ts":"2026-10-13T03:41:43.59381Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8572cc3a5351216d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-13T03:41:43.604419Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-13T03:41:43.604784Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-13T03:42:59.214989Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37054} {"level":"info","ts":"2026-10-13T03:42:59.3458Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37054,"took":"126.228291ms","hash":2649811270,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":32747520,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-13T03:42:59.345878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2649811270,"revision":37054,"compact-revision":34764} {"level":"info","ts":"2026-10-13T03:47:59.223071Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39345} {"level":"info","ts":"2026-10-13T03:47:59.371007Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39345,"took":"141.157621ms","hash":406051528,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":29986816,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-13T03:47:59.371084Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":406051528,"revision":39345,"compact-revision":37054} {"level":"info","ts":"2026-10-13T03:52:59.230598Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41571} {"level":"info","ts":"2026-10-13T03:52:59.349996Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41571,"took":"115.585281ms","hash":948423897,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":29147136,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-13T03:52:59.350063Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":948423897,"revision":41571,"compact-revision":39345} {"level":"info","ts":"2026-10-13T03:57:59.238113Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43799} {"level":"info","ts":"2026-10-13T03:57:59.36279Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43799,"took":"121.052267ms","hash":3764031652,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":28966912,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-13T03:57:59.362854Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3764031652,"revision":43799,"compact-revision":41571}