{"level":"warn","ts":"2026-04-08T22:59:03.293233Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T22:59:03.293336Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.114:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.114:2380","--initial-cluster=instance=https://199.19.213.114:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.114:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.114: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-04-08T22:59:03.293431Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T22:59:03.29344Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.114:2380"]} {"level":"info","ts":"2026-04-08T22:59:03.293462Z","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-04-08T22:59:03.294109Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.114:2379"]} {"level":"info","ts":"2026-04-08T22:59:03.294178Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.114:2380"],"listen-peer-urls":["https://199.19.213.114:2380"],"advertise-client-urls":["https://199.19.213.114:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.114:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.114: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-04-08T22:59:03.298194Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.801195ms"} {"level":"info","ts":"2026-04-08T22:59:03.303061Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e1d03a09d7253a9d","cluster-id":"bb9e539fe2ac80b2"} {"level":"info","ts":"2026-04-08T22:59:03.303142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T22:59:03.303182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d became follower at term 0"} {"level":"info","ts":"2026-04-08T22:59:03.303212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e1d03a09d7253a9d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T22:59:03.303235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d became follower at term 1"} {"level":"info","ts":"2026-04-08T22:59:03.303284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d switched to configuration voters=(16271569267628259997)"} {"level":"warn","ts":"2026-04-08T22:59:03.308637Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T22:59:03.309914Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T22:59:03.311014Z","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-04-08T22:59:03.313109Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e1d03a09d7253a9d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T22:59:03.313426Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e1d03a09d7253a9d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T22:59:03.313948Z","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-04-08T22:59:03.314088Z","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-04-08T22:59:03.314212Z","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-04-08T22:59:03.316904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d switched to configuration voters=(16271569267628259997)"} {"level":"info","ts":"2026-04-08T22:59:03.317201Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bb9e539fe2ac80b2","local-member-id":"e1d03a09d7253a9d","added-peer-id":"e1d03a09d7253a9d","added-peer-peer-urls":["https://199.19.213.114:2380"]} {"level":"info","ts":"2026-04-08T22:59:03.319221Z","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-04-08T22:59:03.31948Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.114:2380"} {"level":"info","ts":"2026-04-08T22:59:03.319523Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.114:2380"} {"level":"info","ts":"2026-04-08T22:59:03.319563Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e1d03a09d7253a9d","initial-advertise-peer-urls":["https://199.19.213.114:2380"],"listen-peer-urls":["https://199.19.213.114:2380"],"advertise-client-urls":["https://199.19.213.114:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.114:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T22:59:03.3196Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T22:59:03.804032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T22:59:03.804093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T22:59:03.804131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d received MsgPreVoteResp from e1d03a09d7253a9d at term 1"} {"level":"info","ts":"2026-04-08T22:59:03.804145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d became candidate at term 2"} {"level":"info","ts":"2026-04-08T22:59:03.804152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d received MsgVoteResp from e1d03a09d7253a9d at term 2"} {"level":"info","ts":"2026-04-08T22:59:03.804162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d became leader at term 2"} {"level":"info","ts":"2026-04-08T22:59:03.804176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e1d03a09d7253a9d elected leader e1d03a09d7253a9d at term 2"} {"level":"info","ts":"2026-04-08T22:59:03.805161Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e1d03a09d7253a9d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.114:2379]}","request-path":"/0/members/e1d03a09d7253a9d/attributes","cluster-id":"bb9e539fe2ac80b2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T22:59:03.805361Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T22:59:03.805478Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T22:59:03.805496Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:59:03.805542Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T22:59:03.805416Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T22:59:03.806289Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bb9e539fe2ac80b2","local-member-id":"e1d03a09d7253a9d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:59:03.806388Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:59:03.806416Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:59:03.808605Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.114:2379"} {"level":"info","ts":"2026-04-08T22:59:03.814429Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T22:59:06.427443Z","caller":"traceutil/trace.go:171","msg":"trace[1603732711] linearizableReadLoop","detail":"{readStateIndex:90; appliedIndex:89; }","duration":"258.322772ms","start":"2026-04-08T22:59:06.169091Z","end":"2026-04-08T22:59:06.427414Z","steps":["trace[1603732711] 'read index received' (duration: 256.440795ms)","trace[1603732711] 'applied index is now lower than readState.Index' (duration: 1.880757ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:06.42754Z","caller":"traceutil/trace.go:171","msg":"trace[757679648] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"287.89942ms","start":"2026-04-08T22:59:06.139586Z","end":"2026-04-08T22:59:06.427485Z","steps":["trace[757679648] 'process raft request' (duration: 285.933131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:06.42779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.63352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T22:59:06.42801Z","caller":"traceutil/trace.go:171","msg":"trace[273173206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:86; }","duration":"258.854085ms","start":"2026-04-08T22:59:06.169052Z","end":"2026-04-08T22:59:06.427906Z","steps":["trace[273173206] 'agreement among raft nodes before linearized reading' (duration: 258.466505ms)"],"step_count":1} 2026/04/08 22:59:08 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-04-08T22:59:20.947056Z","caller":"traceutil/trace.go:171","msg":"trace[1417760673] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"254.047746ms","start":"2026-04-08T22:59:20.692989Z","end":"2026-04-08T22:59:20.947037Z","steps":["trace[1417760673] 'process raft request' (duration: 253.928973ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:33.36071Z","caller":"traceutil/trace.go:171","msg":"trace[331548786] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"379.829578ms","start":"2026-04-08T22:59:32.980845Z","end":"2026-04-08T22:59:33.360674Z","steps":["trace[331548786] 'process raft request' (duration: 379.590453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:33.361455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:32.980797Z","time spent":"380.032273ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:59:33.372178Z","caller":"traceutil/trace.go:171","msg":"trace[427870120] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"383.365891ms","start":"2026-04-08T22:59:32.988789Z","end":"2026-04-08T22:59:33.372155Z","steps":["trace[427870120] 'process raft request' (duration: 382.977902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:33.372271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:32.988754Z","time spent":"383.471214ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:59:33.372284Z","caller":"traceutil/trace.go:171","msg":"trace[2039791247] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"375.906236ms","start":"2026-04-08T22:59:32.996335Z","end":"2026-04-08T22:59:33.372241Z","steps":["trace[2039791247] 'process raft request' (duration: 375.713332ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:33.372361Z","caller":"traceutil/trace.go:171","msg":"trace[893599702] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"351.4165ms","start":"2026-04-08T22:59:33.020935Z","end":"2026-04-08T22:59:33.372352Z","steps":["trace[893599702] 'process raft request' (duration: 351.253346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:33.372403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:33.020919Z","time spent":"351.463161ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T22:59:33.372484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:32.996282Z","time spent":"376.104081ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:59:33.372513Z","caller":"traceutil/trace.go:171","msg":"trace[1750818576] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"351.134814ms","start":"2026-04-08T22:59:33.021372Z","end":"2026-04-08T22:59:33.372507Z","steps":["trace[1750818576] 'process raft request' (duration: 350.96102ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:33.372496Z","caller":"traceutil/trace.go:171","msg":"trace[960141190] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"351.527403ms","start":"2026-04-08T22:59:33.020937Z","end":"2026-04-08T22:59:33.372464Z","steps":["trace[960141190] 'process raft request' (duration: 351.342278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:33.372553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:33.02136Z","time spent":"351.176524ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:59:33.372301Z","caller":"traceutil/trace.go:171","msg":"trace[511360352] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"354.37668ms","start":"2026-04-08T22:59:33.017883Z","end":"2026-04-08T22:59:33.372259Z","steps":["trace[511360352] 'process raft request' (duration: 354.256417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:33.372628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:33.020906Z","time spent":"351.672226ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T22:59:33.372711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:33.017862Z","time spent":"354.790789ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:59:33.372876Z","caller":"traceutil/trace.go:171","msg":"trace[1852950417] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"339.167012ms","start":"2026-04-08T22:59:33.033679Z","end":"2026-04-08T22:59:33.372846Z","steps":["trace[1852950417] 'process raft request' (duration: 338.828734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:33.372944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:33.033258Z","time spent":"339.655653ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:59:33.372969Z","caller":"traceutil/trace.go:171","msg":"trace[1763836987] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"335.012334ms","start":"2026-04-08T22:59:33.037944Z","end":"2026-04-08T22:59:33.372957Z","steps":["trace[1763836987] 'process raft request' (duration: 334.82958ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:33.372937Z","caller":"traceutil/trace.go:171","msg":"trace[1265273579] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"350.433606ms","start":"2026-04-08T22:59:33.022408Z","end":"2026-04-08T22:59:33.372841Z","steps":["trace[1265273579] 'process raft request' (duration: 349.966385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:33.373022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:33.037865Z","time spent":"335.136257ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:59:33.373122Z","caller":"traceutil/trace.go:171","msg":"trace[837092545] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"313.013556ms","start":"2026-04-08T22:59:33.060096Z","end":"2026-04-08T22:59:33.373109Z","steps":["trace[837092545] 'process raft request' (duration: 312.76797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:33.37318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:33.060072Z","time spent":"313.086288ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T22:59:33.373223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:33.022351Z","time spent":"350.724544ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:59:33.373932Z","caller":"traceutil/trace.go:171","msg":"trace[799403669] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"215.912421ms","start":"2026-04-08T22:59:33.158003Z","end":"2026-04-08T22:59:33.373916Z","steps":["trace[799403669] 'process raft request' (duration: 215.627854ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:34.095744Z","caller":"traceutil/trace.go:171","msg":"trace[1909063860] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"122.858891ms","start":"2026-04-08T22:59:33.97285Z","end":"2026-04-08T22:59:34.095708Z","steps":["trace[1909063860] 'process raft request' (duration: 101.99091ms)","trace[1909063860] 'compare' (duration: 20.209016ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:34.423385Z","caller":"traceutil/trace.go:171","msg":"trace[2121759429] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"103.23856ms","start":"2026-04-08T22:59:34.320108Z","end":"2026-04-08T22:59:34.423347Z","steps":["trace[2121759429] 'process raft request' (duration: 66.530366ms)","trace[2121759429] 'compare' (duration: 36.308494ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:34.718596Z","caller":"traceutil/trace.go:171","msg":"trace[896619747] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"274.311044ms","start":"2026-04-08T22:59:34.444244Z","end":"2026-04-08T22:59:34.718555Z","steps":["trace[896619747] 'process raft request' (duration: 225.431695ms)","trace[896619747] 'compare' (duration: 48.736726ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:34.718733Z","caller":"traceutil/trace.go:171","msg":"trace[1293341581] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"262.714661ms","start":"2026-04-08T22:59:34.455986Z","end":"2026-04-08T22:59:34.718701Z","steps":["trace[1293341581] 'process raft request' (duration: 262.527926ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:35.566811Z","caller":"traceutil/trace.go:171","msg":"trace[951108016] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"131.185107ms","start":"2026-04-08T22:59:35.435597Z","end":"2026-04-08T22:59:35.566782Z","steps":["trace[951108016] 'process raft request' (duration: 93.89344ms)","trace[951108016] 'compare' (duration: 36.882588ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:40.038399Z","caller":"traceutil/trace.go:171","msg":"trace[37960904] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"322.746885ms","start":"2026-04-08T22:59:39.715619Z","end":"2026-04-08T22:59:40.038366Z","steps":["trace[37960904] 'process raft request' (duration: 322.663663ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:40.038425Z","caller":"traceutil/trace.go:171","msg":"trace[1525479120] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"323.637106ms","start":"2026-04-08T22:59:39.714743Z","end":"2026-04-08T22:59:40.03838Z","steps":["trace[1525479120] 'process raft request' (duration: 253.412964ms)","trace[1525479120] 'compare' (duration: 69.906375ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:59:40.03865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:39.714707Z","time spent":"323.834971ms","remote":"127.0.0.1:58184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T22:59:40.038904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:39.715608Z","time spent":"322.91206ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:59:41.084758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.404379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:59:41.084944Z","caller":"traceutil/trace.go:171","msg":"trace[2092109667] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"1.016093282s","start":"2026-04-08T22:59:40.068825Z","end":"2026-04-08T22:59:41.084918Z","steps":["trace[2092109667] 'process raft request' (duration: 494.18635ms)","trace[2092109667] 'compare' (duration: 521.180814ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:59:41.085018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:40.068791Z","time spent":"1.016195443s","remote":"127.0.0.1:58156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:59:41.138648Z","caller":"traceutil/trace.go:171","msg":"trace[1384311587] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"471.946276ms","start":"2026-04-08T22:59:40.666671Z","end":"2026-04-08T22:59:41.138617Z","steps":["trace[1384311587] 'read index received' (duration: 471.160708ms)","trace[1384311587] 'applied index is now lower than readState.Index' (duration: 784.418µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:59:41.13879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.10611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:59:41.138831Z","caller":"traceutil/trace.go:171","msg":"trace[1427975333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"472.211142ms","start":"2026-04-08T22:59:40.666608Z","end":"2026-04-08T22:59:41.138819Z","steps":["trace[1427975333] 'agreement among raft nodes before linearized reading' (duration: 472.12284ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:41.138778Z","caller":"traceutil/trace.go:171","msg":"trace[192288285] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"683.793961ms","start":"2026-04-08T22:59:40.454935Z","end":"2026-04-08T22:59:41.138729Z","steps":["trace[192288285] 'process raft request' (duration: 683.104375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:41.138871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:40.666575Z","time spent":"472.288114ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T22:59:41.139024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:40.454881Z","time spent":"684.018227ms","remote":"127.0.0.1:58052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:59:41.817914Z","caller":"traceutil/trace.go:171","msg":"trace[358255091] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"363.851262ms","start":"2026-04-08T22:59:41.454042Z","end":"2026-04-08T22:59:41.817893Z","steps":["trace[358255091] 'read index received' (duration: 363.708119ms)","trace[358255091] 'applied index is now lower than readState.Index' (duration: 142.343µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:41.817954Z","caller":"traceutil/trace.go:171","msg":"trace[1798017412] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"618.131616ms","start":"2026-04-08T22:59:41.199795Z","end":"2026-04-08T22:59:41.817926Z","steps":["trace[1798017412] 'process raft request' (duration: 617.963442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:41.818349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.284522ms","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-04-08T22:59:41.818376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.871615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:59:41.81838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.735728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:59:41.818416Z","caller":"traceutil/trace.go:171","msg":"trace[318344615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"364.403065ms","start":"2026-04-08T22:59:41.453998Z","end":"2026-04-08T22:59:41.818402Z","steps":["trace[318344615] 'agreement among raft nodes before linearized reading' (duration: 364.015096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:41.818517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.100126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:59:41.81859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.4746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:59:41.81861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.46269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:59:41.818644Z","caller":"traceutil/trace.go:171","msg":"trace[1868351251] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:611; }","duration":"127.544282ms","start":"2026-04-08T22:59:41.69108Z","end":"2026-04-08T22:59:41.818624Z","steps":["trace[1868351251] 'agreement among raft nodes before linearized reading' (duration: 127.48593ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:41.81867Z","caller":"traceutil/trace.go:171","msg":"trace[1427102181] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:611; }","duration":"127.520411ms","start":"2026-04-08T22:59:41.691115Z","end":"2026-04-08T22:59:41.818636Z","steps":["trace[1427102181] 'agreement among raft nodes before linearized reading' (duration: 127.460709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:41.818515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.155581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T22:59:41.81878Z","caller":"traceutil/trace.go:171","msg":"trace[2112505566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:611; }","duration":"322.502009ms","start":"2026-04-08T22:59:41.496267Z","end":"2026-04-08T22:59:41.818769Z","steps":["trace[2112505566] 'agreement among raft nodes before linearized reading' (duration: 322.043478ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:41.818754Z","caller":"traceutil/trace.go:171","msg":"trace[1716677787] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:611; }","duration":"103.340112ms","start":"2026-04-08T22:59:41.715275Z","end":"2026-04-08T22:59:41.818615Z","steps":["trace[1716677787] 'agreement among raft nodes before linearized reading' (duration: 103.069206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:41.818888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:41.496239Z","time spent":"322.570261ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T22:59:41.818944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.626259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:59:41.819Z","caller":"traceutil/trace.go:171","msg":"trace[1198674312] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:611; }","duration":"103.68856ms","start":"2026-04-08T22:59:41.715296Z","end":"2026-04-08T22:59:41.818984Z","steps":["trace[1198674312] 'agreement among raft nodes before linearized reading' (duration: 103.613378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:41.81903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:41.199761Z","time spent":"619.15586ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:59:41.818438Z","caller":"traceutil/trace.go:171","msg":"trace[298035630] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:611; }","duration":"126.964647ms","start":"2026-04-08T22:59:41.691444Z","end":"2026-04-08T22:59:41.818408Z","steps":["trace[298035630] 'agreement among raft nodes before linearized reading' (duration: 126.880255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:41.818899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.576071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-08T22:59:41.818501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:41.45398Z","time spent":"364.508077ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T22:59:41.819243Z","caller":"traceutil/trace.go:171","msg":"trace[1599762417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"223.963551ms","start":"2026-04-08T22:59:41.59526Z","end":"2026-04-08T22:59:41.819224Z","steps":["trace[1599762417] 'agreement among raft nodes before linearized reading' (duration: 223.593622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:41.818531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.482261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:59:41.818436Z","caller":"traceutil/trace.go:171","msg":"trace[304160970] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:611; }","duration":"102.82438ms","start":"2026-04-08T22:59:41.715598Z","end":"2026-04-08T22:59:41.818422Z","steps":["trace[304160970] 'agreement among raft nodes before linearized reading' (duration: 102.709727ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:41.819357Z","caller":"traceutil/trace.go:171","msg":"trace[1274340846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:611; }","duration":"117.308401ms","start":"2026-04-08T22:59:41.702009Z","end":"2026-04-08T22:59:41.819317Z","steps":["trace[1274340846] 'agreement among raft nodes before linearized reading' (duration: 116.451151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:42.190256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.16943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:59:42.190529Z","caller":"traceutil/trace.go:171","msg":"trace[1612728366] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:631; }","duration":"360.655606ms","start":"2026-04-08T22:59:41.829855Z","end":"2026-04-08T22:59:42.19051Z","steps":["trace[1612728366] 'read index received' (duration: 47.233891ms)","trace[1612728366] 'applied index is now lower than readState.Index' (duration: 313.420235ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:42.190641Z","caller":"traceutil/trace.go:171","msg":"trace[1357387513] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"365.711705ms","start":"2026-04-08T22:59:41.824919Z","end":"2026-04-08T22:59:42.190631Z","steps":["trace[1357387513] 'process raft request' (duration: 365.51721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:42.190707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:41.824911Z","time spent":"365.762827ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:59:42.190725Z","caller":"traceutil/trace.go:171","msg":"trace[1315510921] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"366.433762ms","start":"2026-04-08T22:59:41.824222Z","end":"2026-04-08T22:59:42.190655Z","steps":["trace[1315510921] 'process raft request' (duration: 366.152726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:42.190824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.921772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:59:42.190964Z","caller":"traceutil/trace.go:171","msg":"trace[108718811] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:615; }","duration":"361.134907ms","start":"2026-04-08T22:59:41.82981Z","end":"2026-04-08T22:59:42.190945Z","steps":["trace[108718811] 'agreement among raft nodes before linearized reading' (duration: 360.83023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:42.191007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:41.824196Z","time spent":"366.648207ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:59:42.191044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:41.8298Z","time spent":"361.208409ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-08T22:59:42.19106Z","caller":"traceutil/trace.go:171","msg":"trace[1234240083] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"367.129259ms","start":"2026-04-08T22:59:41.823884Z","end":"2026-04-08T22:59:42.191013Z","steps":["trace[1234240083] 'process raft request' (duration: 53.076299ms)","trace[1234240083] 'compare' (duration: 312.34933ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:59:42.191232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:41.823867Z","time spent":"367.276562ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:59:42.191379Z","caller":"traceutil/trace.go:171","msg":"trace[723918050] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"365.764537ms","start":"2026-04-08T22:59:41.825027Z","end":"2026-04-08T22:59:42.190792Z","steps":["trace[723918050] 'process raft request' (duration: 365.449219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:42.191443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.025469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:59:42.191493Z","caller":"traceutil/trace.go:171","msg":"trace[747925984] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:615; }","duration":"124.093531ms","start":"2026-04-08T22:59:42.067387Z","end":"2026-04-08T22:59:42.19148Z","steps":["trace[747925984] 'agreement among raft nodes before linearized reading' (duration: 124.012449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:42.191622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:41.825008Z","time spent":"366.452802ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:59:42.191801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.229413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:59:42.191804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.877875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:59:42.191842Z","caller":"traceutil/trace.go:171","msg":"trace[1574966749] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:615; }","duration":"124.315545ms","start":"2026-04-08T22:59:42.067516Z","end":"2026-04-08T22:59:42.191831Z","steps":["trace[1574966749] 'agreement among raft nodes before linearized reading' (duration: 124.254534ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:42.19188Z","caller":"traceutil/trace.go:171","msg":"trace[1472058147] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:615; }","duration":"123.974827ms","start":"2026-04-08T22:59:42.067864Z","end":"2026-04-08T22:59:42.191839Z","steps":["trace[1472058147] 'agreement among raft nodes before linearized reading' (duration: 123.855204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:42.192007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.540303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:59:42.19208Z","caller":"traceutil/trace.go:171","msg":"trace[963394042] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:615; }","duration":"150.665465ms","start":"2026-04-08T22:59:42.041393Z","end":"2026-04-08T22:59:42.192058Z","steps":["trace[963394042] 'agreement among raft nodes before linearized reading' (duration: 150.563583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:42.192182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.85891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:59:42.192225Z","caller":"traceutil/trace.go:171","msg":"trace[702287791] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:615; }","duration":"150.933052ms","start":"2026-04-08T22:59:42.041282Z","end":"2026-04-08T22:59:42.192215Z","steps":["trace[702287791] 'agreement among raft nodes before linearized reading' (duration: 150.865871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:42.192234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.328637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:59:42.192254Z","caller":"traceutil/trace.go:171","msg":"trace[295122541] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:615; }","duration":"150.361149ms","start":"2026-04-08T22:59:42.041887Z","end":"2026-04-08T22:59:42.192248Z","steps":["trace[295122541] 'agreement among raft nodes before linearized reading' (duration: 150.326108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:42.192317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.005848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:59:42.192368Z","caller":"traceutil/trace.go:171","msg":"trace[468400302] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:615; }","duration":"274.129941ms","start":"2026-04-08T22:59:41.918226Z","end":"2026-04-08T22:59:42.192356Z","steps":["trace[468400302] 'agreement among raft nodes before linearized reading' (duration: 274.011279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:42.193142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.901049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:59:42.193248Z","caller":"traceutil/trace.go:171","msg":"trace[1677876475] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:615; }","duration":"275.061673ms","start":"2026-04-08T22:59:41.918161Z","end":"2026-04-08T22:59:42.193223Z","steps":["trace[1677876475] 'agreement among raft nodes before linearized reading' (duration: 273.102477ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:45.567692Z","caller":"traceutil/trace.go:171","msg":"trace[1083663450] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"102.943433ms","start":"2026-04-08T22:59:45.464711Z","end":"2026-04-08T22:59:45.567654Z","steps":["trace[1083663450] 'process raft request' (duration: 102.764279ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:55.314274Z","caller":"traceutil/trace.go:171","msg":"trace[997638187] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"147.86069ms","start":"2026-04-08T22:59:55.166387Z","end":"2026-04-08T22:59:55.314248Z","steps":["trace[997638187] 'process raft request' (duration: 147.663005ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:55.314425Z","caller":"traceutil/trace.go:171","msg":"trace[50699211] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"147.050311ms","start":"2026-04-08T22:59:55.16734Z","end":"2026-04-08T22:59:55.31439Z","steps":["trace[50699211] 'process raft request' (duration: 146.848436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:59.452801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.49784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:59:59.452836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.535467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-22ttl\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-08T22:59:59.452912Z","caller":"traceutil/trace.go:171","msg":"trace[1903465066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"314.663824ms","start":"2026-04-08T22:59:59.138227Z","end":"2026-04-08T22:59:59.452891Z","steps":["trace[1903465066] 'range keys from in-memory index tree' (duration: 314.381768ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:59.452941Z","caller":"traceutil/trace.go:171","msg":"trace[1814535613] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-22ttl; range_end:; response_count:1; response_revision:788; }","duration":"290.899696ms","start":"2026-04-08T22:59:59.162013Z","end":"2026-04-08T22:59:59.452913Z","steps":["trace[1814535613] 'range keys from in-memory index tree' (duration: 290.301632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:59.452954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:59.138202Z","time spent":"314.743567ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T23:00:14.363791Z","caller":"traceutil/trace.go:171","msg":"trace[638113747] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"118.706174ms","start":"2026-04-08T23:00:14.245054Z","end":"2026-04-08T23:00:14.363761Z","steps":["trace[638113747] 'process raft request' (duration: 118.501559ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:13.030133Z","caller":"traceutil/trace.go:171","msg":"trace[1783917373] linearizableReadLoop","detail":"{readStateIndex:1881; appliedIndex:1880; }","duration":"363.028146ms","start":"2026-04-08T23:01:12.667071Z","end":"2026-04-08T23:01:13.030099Z","steps":["trace[1783917373] 'read index received' (duration: 362.855892ms)","trace[1783917373] 'applied index is now lower than readState.Index' (duration: 170.494µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:01:13.030199Z","caller":"traceutil/trace.go:171","msg":"trace[312050279] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"472.03546ms","start":"2026-04-08T23:01:12.55812Z","end":"2026-04-08T23:01:13.030155Z","steps":["trace[312050279] 'process raft request' (duration: 471.763804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:13.030694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:01:12.55809Z","time spent":"472.203304ms","remote":"127.0.0.1:58156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9335,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:01:13.0304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.290912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:01:13.031224Z","caller":"traceutil/trace.go:171","msg":"trace[21576885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1829; }","duration":"364.173183ms","start":"2026-04-08T23:01:12.667019Z","end":"2026-04-08T23:01:13.031192Z","steps":["trace[21576885] 'agreement among raft nodes before linearized reading' (duration: 363.20387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:13.031329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:01:12.666992Z","time spent":"364.282816ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T23:01:13.041096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.179157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T23:01:13.041174Z","caller":"traceutil/trace.go:171","msg":"trace[1069996888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1830; }","duration":"206.267969ms","start":"2026-04-08T23:01:12.834888Z","end":"2026-04-08T23:01:13.041156Z","steps":["trace[1069996888] 'agreement among raft nodes before linearized reading' (duration: 206.037334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:13.041151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.722763ms","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-04-08T23:01:13.041192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.632343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T23:01:13.041309Z","caller":"traceutil/trace.go:171","msg":"trace[160813598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1830; }","duration":"210.799348ms","start":"2026-04-08T23:01:12.830487Z","end":"2026-04-08T23:01:13.041286Z","steps":["trace[160813598] 'agreement among raft nodes before linearized reading' (duration: 210.46714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:13.041329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.482826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T23:01:13.041362Z","caller":"traceutil/trace.go:171","msg":"trace[651124688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1830; }","duration":"209.569828ms","start":"2026-04-08T23:01:12.831785Z","end":"2026-04-08T23:01:13.041355Z","steps":["trace[651124688] 'agreement among raft nodes before linearized reading' (duration: 209.498876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:13.041173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.104961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T23:01:13.041431Z","caller":"traceutil/trace.go:171","msg":"trace[743627135] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"358.913439ms","start":"2026-04-08T23:01:12.682503Z","end":"2026-04-08T23:01:13.041416Z","steps":["trace[743627135] 'process raft request' (duration: 358.205652ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:13.04124Z","caller":"traceutil/trace.go:171","msg":"trace[471130193] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1830; }","duration":"212.888037ms","start":"2026-04-08T23:01:12.828333Z","end":"2026-04-08T23:01:13.041221Z","steps":["trace[471130193] 'agreement among raft nodes before linearized reading' (duration: 212.651081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:13.041507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.069586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T23:01:13.041542Z","caller":"traceutil/trace.go:171","msg":"trace[1475282580] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1830; }","duration":"206.121426ms","start":"2026-04-08T23:01:12.83541Z","end":"2026-04-08T23:01:13.041531Z","steps":["trace[1475282580] 'agreement among raft nodes before linearized reading' (duration: 206.058745ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:13.041448Z","caller":"traceutil/trace.go:171","msg":"trace[846243184] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1830; }","duration":"229.421459ms","start":"2026-04-08T23:01:12.812008Z","end":"2026-04-08T23:01:13.041429Z","steps":["trace[846243184] 'agreement among raft nodes before linearized reading' (duration: 229.06019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:13.041568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:01:12.682459Z","time spent":"359.012051ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:01:13.201882Z","caller":"traceutil/trace.go:171","msg":"trace[475948081] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"163.893346ms","start":"2026-04-08T23:01:13.037951Z","end":"2026-04-08T23:01:13.201844Z","steps":["trace[475948081] 'process raft request' (duration: 163.63057ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:13.202Z","caller":"traceutil/trace.go:171","msg":"trace[1949642636] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"163.791263ms","start":"2026-04-08T23:01:13.038181Z","end":"2026-04-08T23:01:13.201973Z","steps":["trace[1949642636] 'process raft request' (duration: 163.574598ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:13.202083Z","caller":"traceutil/trace.go:171","msg":"trace[1819703644] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"163.22904ms","start":"2026-04-08T23:01:13.038821Z","end":"2026-04-08T23:01:13.20205Z","steps":["trace[1819703644] 'process raft request' (duration: 163.051816ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:13.202078Z","caller":"traceutil/trace.go:171","msg":"trace[1833628244] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"163.835724ms","start":"2026-04-08T23:01:13.038201Z","end":"2026-04-08T23:01:13.202037Z","steps":["trace[1833628244] 'process raft request' (duration: 163.610249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:13.202132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.093059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:01:13.202205Z","caller":"traceutil/trace.go:171","msg":"trace[1376825181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1834; }","duration":"169.412316ms","start":"2026-04-08T23:01:13.032778Z","end":"2026-04-08T23:01:13.20219Z","steps":["trace[1376825181] 'agreement among raft nodes before linearized reading' (duration: 169.281453ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:13.201989Z","caller":"traceutil/trace.go:171","msg":"trace[264462258] linearizableReadLoop","detail":"{readStateIndex:1886; appliedIndex:1882; }","duration":"161.091089ms","start":"2026-04-08T23:01:13.040866Z","end":"2026-04-08T23:01:13.201957Z","steps":["trace[264462258] 'read index received' (duration: 160.824032ms)","trace[264462258] 'applied index is now lower than readState.Index' (duration: 265.757µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:01:13.378097Z","caller":"traceutil/trace.go:171","msg":"trace[696757688] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"332.339398ms","start":"2026-04-08T23:01:13.045687Z","end":"2026-04-08T23:01:13.378027Z","steps":["trace[696757688] 'process raft request' (duration: 309.807454ms)","trace[696757688] 'compare' (duration: 21.978061ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:01:13.378607Z","caller":"traceutil/trace.go:171","msg":"trace[1918431313] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1886; }","duration":"176.30049ms","start":"2026-04-08T23:01:13.202279Z","end":"2026-04-08T23:01:13.378579Z","steps":["trace[1918431313] 'read index received' (duration: 153.174021ms)","trace[1918431313] 'applied index is now lower than readState.Index' (duration: 23.125159ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:01:13.378845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.292023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b40a1a25-f9d8-40f6-8dbe-82ef17422c04\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-08T23:01:13.378884Z","caller":"traceutil/trace.go:171","msg":"trace[1568879012] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b40a1a25-f9d8-40f6-8dbe-82ef17422c04; range_end:; response_count:1; response_revision:1838; }","duration":"266.407175ms","start":"2026-04-08T23:01:13.112463Z","end":"2026-04-08T23:01:13.37887Z","steps":["trace[1568879012] 'agreement among raft nodes before linearized reading' (duration: 266.213611ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:13.379099Z","caller":"traceutil/trace.go:171","msg":"trace[387240777] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"333.353453ms","start":"2026-04-08T23:01:13.045734Z","end":"2026-04-08T23:01:13.379088Z","steps":["trace[387240777] 'process raft request' (duration: 332.254927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:13.3792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:01:13.045708Z","time spent":"333.427605ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:01:13.379355Z","caller":"traceutil/trace.go:171","msg":"trace[69710361] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"332.628296ms","start":"2026-04-08T23:01:13.046717Z","end":"2026-04-08T23:01:13.379345Z","steps":["trace[69710361] 'process raft request' (duration: 331.766005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:13.37941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:01:13.046699Z","time spent":"332.681877ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:01:13.379543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.313883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-04-08T23:01:13.379577Z","caller":"traceutil/trace.go:171","msg":"trace[1820603766] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1838; }","duration":"193.398925ms","start":"2026-04-08T23:01:13.186169Z","end":"2026-04-08T23:01:13.379568Z","steps":["trace[1820603766] 'agreement among raft nodes before linearized reading' (duration: 193.307503ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:13.378138Z","caller":"traceutil/trace.go:171","msg":"trace[362190499] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"332.332028ms","start":"2026-04-08T23:01:13.045716Z","end":"2026-04-08T23:01:13.378048Z","steps":["trace[362190499] 'process raft request' (duration: 332.160164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:13.37981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:01:13.045694Z","time spent":"334.035629ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:01:13.380294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:01:13.045662Z","time spent":"333.711141ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:01:22.330467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.021919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-08T23:01:22.330597Z","caller":"traceutil/trace.go:171","msg":"trace[1490215425] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1934; }","duration":"175.219964ms","start":"2026-04-08T23:01:22.155349Z","end":"2026-04-08T23:01:22.330568Z","steps":["trace[1490215425] 'range keys from in-memory index tree' (duration: 174.782224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:50.641692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.976507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-08T23:01:50.641778Z","caller":"traceutil/trace.go:171","msg":"trace[1266055353] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2137; }","duration":"116.118131ms","start":"2026-04-08T23:01:50.525643Z","end":"2026-04-08T23:01:50.641761Z","steps":["trace[1266055353] 'range keys from in-memory index tree' (duration: 115.801673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:50.642174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.683194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-08T23:01:50.64223Z","caller":"traceutil/trace.go:171","msg":"trace[910300208] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2137; }","duration":"116.783186ms","start":"2026-04-08T23:01:50.525434Z","end":"2026-04-08T23:01:50.642217Z","steps":["trace[910300208] 'range keys from in-memory index tree' (duration: 116.545081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:56.504723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.969393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T23:01:56.504932Z","caller":"traceutil/trace.go:171","msg":"trace[97424196] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"373.432997ms","start":"2026-04-08T23:01:56.131476Z","end":"2026-04-08T23:01:56.504909Z","steps":["trace[97424196] 'process raft request' (duration: 34.141996ms)","trace[97424196] 'compare' (duration: 338.809269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:01:56.505001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:01:56.131458Z","time spent":"373.510929ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":536,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:01:56.747595Z","caller":"traceutil/trace.go:171","msg":"trace[1439515702] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"239.591182ms","start":"2026-04-08T23:01:56.50797Z","end":"2026-04-08T23:01:56.747561Z","steps":["trace[1439515702] 'process raft request' (duration: 155.072909ms)","trace[1439515702] 'compare' (duration: 84.353559ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:01:56.835238Z","caller":"traceutil/trace.go:171","msg":"trace[1082637602] linearizableReadLoop","detail":"{readStateIndex:2247; appliedIndex:2246; }","duration":"167.10997ms","start":"2026-04-08T23:01:56.668099Z","end":"2026-04-08T23:01:56.835209Z","steps":["trace[1082637602] 'read index received' (duration: 166.848864ms)","trace[1082637602] 'applied index is now lower than readState.Index' (duration: 259.956µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:01:56.835431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.309475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:01:56.835418Z","caller":"traceutil/trace.go:171","msg":"trace[883100796] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"325.239602ms","start":"2026-04-08T23:01:56.510134Z","end":"2026-04-08T23:01:56.835374Z","steps":["trace[883100796] 'process raft request' (duration: 324.907364ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:56.835494Z","caller":"traceutil/trace.go:171","msg":"trace[492540402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2178; }","duration":"167.389967ms","start":"2026-04-08T23:01:56.668078Z","end":"2026-04-08T23:01:56.835468Z","steps":["trace[492540402] 'agreement among raft nodes before linearized reading' (duration: 167.282855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:56.835637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:01:56.510113Z","time spent":"325.427206ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:01:56.835712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.807653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-04-08T23:01:56.835848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.265471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-08T23:01:56.835971Z","caller":"traceutil/trace.go:171","msg":"trace[122773074] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2178; }","duration":"163.981738ms","start":"2026-04-08T23:01:56.671855Z","end":"2026-04-08T23:01:56.835837Z","steps":["trace[122773074] 'agreement among raft nodes before linearized reading' (duration: 163.65218ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:56.836019Z","caller":"traceutil/trace.go:171","msg":"trace[638577371] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2178; }","duration":"151.507796ms","start":"2026-04-08T23:01:56.68448Z","end":"2026-04-08T23:01:56.835988Z","steps":["trace[638577371] 'agreement among raft nodes before linearized reading' (duration: 151.051426ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:56.97064Z","caller":"traceutil/trace.go:171","msg":"trace[1638858369] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"127.629509ms","start":"2026-04-08T23:01:56.842963Z","end":"2026-04-08T23:01:56.970592Z","steps":["trace[1638858369] 'process raft request' (duration: 111.217756ms)","trace[1638858369] 'compare' (duration: 16.025804ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:02:31.462887Z","caller":"traceutil/trace.go:171","msg":"trace[1551416214] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"105.469302ms","start":"2026-04-08T23:02:31.357382Z","end":"2026-04-08T23:02:31.462852Z","steps":["trace[1551416214] 'process raft request' (duration: 105.218156ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:32.120679Z","caller":"traceutil/trace.go:171","msg":"trace[649630888] linearizableReadLoop","detail":"{readStateIndex:2556; appliedIndex:2555; }","duration":"300.269319ms","start":"2026-04-08T23:02:31.820388Z","end":"2026-04-08T23:02:32.120658Z","steps":["trace[649630888] 'read index received' (duration: 300.047294ms)","trace[649630888] 'applied index is now lower than readState.Index' (duration: 221.055µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:02:32.12074Z","caller":"traceutil/trace.go:171","msg":"trace[477848290] transaction","detail":"{read_only:false; response_revision:2470; number_of_response:1; }","duration":"300.996336ms","start":"2026-04-08T23:02:31.81971Z","end":"2026-04-08T23:02:32.120707Z","steps":["trace[477848290] 'process raft request' (duration: 300.810912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:32.120872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.464513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T23:02:32.120905Z","caller":"traceutil/trace.go:171","msg":"trace[1147676786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2470; }","duration":"300.537236ms","start":"2026-04-08T23:02:31.820359Z","end":"2026-04-08T23:02:32.120896Z","steps":["trace[1147676786] 'agreement among raft nodes before linearized reading' (duration: 300.416953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:32.12091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:31.819692Z","time spent":"301.127959ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:02:32.12093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:31.820347Z","time spent":"300.577336ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T23:02:32.120985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.510564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T23:02:32.121047Z","caller":"traceutil/trace.go:171","msg":"trace[1592761259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2470; }","duration":"300.589897ms","start":"2026-04-08T23:02:31.82044Z","end":"2026-04-08T23:02:32.12103Z","steps":["trace[1592761259] 'agreement among raft nodes before linearized reading' (duration: 300.378912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:32.121107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:31.82043Z","time spent":"300.664608ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T23:02:32.121087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.968558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"warn","ts":"2026-04-08T23:02:32.121115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.00975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T23:02:32.121173Z","caller":"traceutil/trace.go:171","msg":"trace[1276971797] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2470; }","duration":"251.102171ms","start":"2026-04-08T23:02:31.870048Z","end":"2026-04-08T23:02:32.121151Z","steps":["trace[1276971797] 'agreement among raft nodes before linearized reading' (duration: 250.851356ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:32.121198Z","caller":"traceutil/trace.go:171","msg":"trace[1012264023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2470; }","duration":"296.108152ms","start":"2026-04-08T23:02:31.825067Z","end":"2026-04-08T23:02:32.121175Z","steps":["trace[1012264023] 'agreement among raft nodes before linearized reading' (duration: 295.910287ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:58.56894Z","caller":"traceutil/trace.go:171","msg":"trace[1703273238] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2657; }","duration":"150.128406ms","start":"2026-04-08T23:02:58.418783Z","end":"2026-04-08T23:02:58.568912Z","steps":["trace[1703273238] 'process raft request' (duration: 144.465932ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:03:04.469628Z","caller":"traceutil/trace.go:171","msg":"trace[1333430485] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"189.992817ms","start":"2026-04-08T23:03:04.279606Z","end":"2026-04-08T23:03:04.469599Z","steps":["trace[1333430485] 'process raft request' (duration: 168.306595ms)","trace[1333430485] 'compare' (duration: 21.487587ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:03:04.469685Z","caller":"traceutil/trace.go:171","msg":"trace[1673985116] linearizableReadLoop","detail":"{readStateIndex:2794; appliedIndex:2792; }","duration":"181.933406ms","start":"2026-04-08T23:03:04.28772Z","end":"2026-04-08T23:03:04.469653Z","steps":["trace[1673985116] 'read index received' (duration: 3.003091ms)","trace[1673985116] 'applied index is now lower than readState.Index' (duration: 178.926905ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:03:04.469632Z","caller":"traceutil/trace.go:171","msg":"trace[148500163] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"167.471795ms","start":"2026-04-08T23:03:04.302138Z","end":"2026-04-08T23:03:04.46961Z","steps":["trace[148500163] 'process raft request' (duration: 167.405264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:03:04.469939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.195922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-08T23:03:04.46998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.021319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T23:03:04.470032Z","caller":"traceutil/trace.go:171","msg":"trace[1134674455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2695; }","duration":"182.113191ms","start":"2026-04-08T23:03:04.287904Z","end":"2026-04-08T23:03:04.470017Z","steps":["trace[1134674455] 'agreement among raft nodes before linearized reading' (duration: 181.951827ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:03:04.47004Z","caller":"traceutil/trace.go:171","msg":"trace[1775994214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2695; }","duration":"182.321346ms","start":"2026-04-08T23:03:04.287702Z","end":"2026-04-08T23:03:04.470023Z","steps":["trace[1775994214] 'agreement among raft nodes before linearized reading' (duration: 182.102101ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:05:22.212199Z","caller":"traceutil/trace.go:171","msg":"trace[1311217107] transaction","detail":"{read_only:false; response_revision:4320; number_of_response:1; }","duration":"167.403083ms","start":"2026-04-08T23:05:22.044762Z","end":"2026-04-08T23:05:22.212165Z","steps":["trace[1311217107] 'process raft request' (duration: 167.153437ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:05:22.212838Z","caller":"traceutil/trace.go:171","msg":"trace[49778291] linearizableReadLoop","detail":"{readStateIndex:4500; appliedIndex:4498; }","duration":"144.349457ms","start":"2026-04-08T23:05:22.068465Z","end":"2026-04-08T23:05:22.212815Z","steps":["trace[49778291] 'read index received' (duration: 143.415065ms)","trace[49778291] 'applied index is now lower than readState.Index' (duration: 933.492µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:05:22.2129Z","caller":"traceutil/trace.go:171","msg":"trace[313615550] transaction","detail":"{read_only:false; response_revision:4321; number_of_response:1; }","duration":"160.741439ms","start":"2026-04-08T23:05:22.052146Z","end":"2026-04-08T23:05:22.212888Z","steps":["trace[313615550] 'process raft request' (duration: 160.544004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:22.213015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.546661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-08T23:05:22.213045Z","caller":"traceutil/trace.go:171","msg":"trace[866643578] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4321; }","duration":"144.620243ms","start":"2026-04-08T23:05:22.068415Z","end":"2026-04-08T23:05:22.213036Z","steps":["trace[866643578] 'agreement among raft nodes before linearized reading' (duration: 144.516541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:22.213044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.455853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-08T23:05:22.213074Z","caller":"traceutil/trace.go:171","msg":"trace[1032314691] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4321; }","duration":"142.512424ms","start":"2026-04-08T23:05:22.070553Z","end":"2026-04-08T23:05:22.213065Z","steps":["trace[1032314691] 'agreement among raft nodes before linearized reading' (duration: 142.412581ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:05:26.827538Z","caller":"traceutil/trace.go:171","msg":"trace[2101866171] linearizableReadLoop","detail":"{readStateIndex:4667; appliedIndex:4666; }","duration":"102.705768ms","start":"2026-04-08T23:05:26.72476Z","end":"2026-04-08T23:05:26.827466Z","steps":["trace[2101866171] 'read index received' (duration: 64.283095ms)","trace[2101866171] 'applied index is now lower than readState.Index' (duration: 38.420733ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:05:26.827738Z","caller":"traceutil/trace.go:171","msg":"trace[2146852262] transaction","detail":"{read_only:false; response_revision:4482; number_of_response:1; }","duration":"158.613879ms","start":"2026-04-08T23:05:26.66911Z","end":"2026-04-08T23:05:26.827724Z","steps":["trace[2146852262] 'process raft request' (duration: 119.895459ms)","trace[2146852262] 'compare' (duration: 38.303481ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:05:26.828123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.337314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2ng24\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T23:05:26.828175Z","caller":"traceutil/trace.go:171","msg":"trace[1936155164] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2ng24; range_end:; response_count:1; response_revision:4482; }","duration":"103.467367ms","start":"2026-04-08T23:05:26.724695Z","end":"2026-04-08T23:05:26.828163Z","steps":["trace[1936155164] 'agreement among raft nodes before linearized reading' (duration: 103.208291ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:05:27.638328Z","caller":"traceutil/trace.go:171","msg":"trace[883035979] transaction","detail":"{read_only:false; response_revision:4491; number_of_response:1; }","duration":"196.130031ms","start":"2026-04-08T23:05:27.442169Z","end":"2026-04-08T23:05:27.638299Z","steps":["trace[883035979] 'process raft request' (duration: 167.337162ms)","trace[883035979] 'compare' (duration: 28.39463ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:05:27.63856Z","caller":"traceutil/trace.go:171","msg":"trace[942306227] transaction","detail":"{read_only:false; response_revision:4492; number_of_response:1; }","duration":"196.280945ms","start":"2026-04-08T23:05:27.442262Z","end":"2026-04-08T23:05:27.638543Z","steps":["trace[942306227] 'process raft request' (duration: 195.942887ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:05:27.638699Z","caller":"traceutil/trace.go:171","msg":"trace[1161399689] linearizableReadLoop","detail":"{readStateIndex:4679; appliedIndex:4677; }","duration":"119.905719ms","start":"2026-04-08T23:05:27.518575Z","end":"2026-04-08T23:05:27.638481Z","steps":["trace[1161399689] 'read index received' (duration: 90.941145ms)","trace[1161399689] 'applied index is now lower than readState.Index' (duration: 28.962464ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:05:27.638855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.257487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13485"} {"level":"info","ts":"2026-04-08T23:05:27.639797Z","caller":"traceutil/trace.go:171","msg":"trace[1561519196] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:4492; }","duration":"121.220199ms","start":"2026-04-08T23:05:27.51853Z","end":"2026-04-08T23:05:27.63975Z","steps":["trace[1561519196] 'agreement among raft nodes before linearized reading' (duration: 120.170845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:29.25055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.177935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:05:29.250708Z","caller":"traceutil/trace.go:171","msg":"trace[137293078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4555; }","duration":"114.422221ms","start":"2026-04-08T23:05:29.136262Z","end":"2026-04-08T23:05:29.250684Z","steps":["trace[137293078] 'range keys from in-memory index tree' (duration: 114.035552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:29.250762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.505116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T23:05:29.250899Z","caller":"traceutil/trace.go:171","msg":"trace[704310549] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:4555; }","duration":"112.713331ms","start":"2026-04-08T23:05:29.138161Z","end":"2026-04-08T23:05:29.250874Z","steps":["trace[704310549] 'count revisions from in-memory index tree' (duration: 112.402784ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:05:33.325601Z","caller":"traceutil/trace.go:171","msg":"trace[1040850040] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"100.764944ms","start":"2026-04-08T23:05:33.224788Z","end":"2026-04-08T23:05:33.325553Z","steps":["trace[1040850040] 'process raft request' (duration: 100.426886ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:05:39.741218Z","caller":"traceutil/trace.go:171","msg":"trace[865544793] linearizableReadLoop","detail":"{readStateIndex:4957; appliedIndex:4956; }","duration":"247.004575ms","start":"2026-04-08T23:05:39.494195Z","end":"2026-04-08T23:05:39.7412Z","steps":["trace[865544793] 'read index received' (duration: 246.858082ms)","trace[865544793] 'applied index is now lower than readState.Index' (duration: 145.513µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:05:39.741271Z","caller":"traceutil/trace.go:171","msg":"trace[1492508710] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"329.186766ms","start":"2026-04-08T23:05:39.412053Z","end":"2026-04-08T23:05:39.74124Z","steps":["trace[1492508710] 'process raft request' (duration: 329.010922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:39.741438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:05:39.412018Z","time spent":"329.3287ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:05:39.741399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.196629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T23:05:39.741492Z","caller":"traceutil/trace.go:171","msg":"trace[2039104182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4758; }","duration":"247.348343ms","start":"2026-04-08T23:05:39.494135Z","end":"2026-04-08T23:05:39.741484Z","steps":["trace[2039104182] 'agreement among raft nodes before linearized reading' (duration: 247.172759ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:05:39.846817Z","caller":"traceutil/trace.go:171","msg":"trace[1672729025] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"100.062826ms","start":"2026-04-08T23:05:39.746728Z","end":"2026-04-08T23:05:39.846791Z","steps":["trace[1672729025] 'process raft request' (duration: 99.731308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:46.189507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.022849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T23:05:46.189711Z","caller":"traceutil/trace.go:171","msg":"trace[192523969] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"318.867126ms","start":"2026-04-08T23:05:45.870804Z","end":"2026-04-08T23:05:46.189671Z","steps":["trace[192523969] 'process raft request' (duration: 27.593951ms)","trace[192523969] 'compare' (duration: 290.874906ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:05:46.18977Z","caller":"traceutil/trace.go:171","msg":"trace[1408215429] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"199.397048ms","start":"2026-04-08T23:05:45.990359Z","end":"2026-04-08T23:05:46.189756Z","steps":["trace[1408215429] 'process raft request' (duration: 199.353967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:46.189809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:05:45.870778Z","time spent":"318.986499ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:05:46.189891Z","caller":"traceutil/trace.go:171","msg":"trace[2037751053] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"316.499811ms","start":"2026-04-08T23:05:45.873382Z","end":"2026-04-08T23:05:46.189882Z","steps":["trace[2037751053] 'process raft request' (duration: 316.278256ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:05:46.189915Z","caller":"traceutil/trace.go:171","msg":"trace[234459291] linearizableReadLoop","detail":"{readStateIndex:5110; appliedIndex:5109; }","duration":"317.685138ms","start":"2026-04-08T23:05:45.872218Z","end":"2026-04-08T23:05:46.189903Z","steps":["trace[234459291] 'read index received' (duration: 26.197739ms)","trace[234459291] 'applied index is now lower than readState.Index' (duration: 291.486319ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:05:46.189948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:05:45.873372Z","time spent":"316.543832ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:05:46.190091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.865372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T23:05:46.190124Z","caller":"traceutil/trace.go:171","msg":"trace[434613075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4908; }","duration":"317.915154ms","start":"2026-04-08T23:05:45.872199Z","end":"2026-04-08T23:05:46.190114Z","steps":["trace[434613075] 'agreement among raft nodes before linearized reading' (duration: 317.790721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:46.190155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:05:45.87219Z","time spent":"317.955905ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T23:05:46.190213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.484663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-mfltx.18a48437f5017586\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-04-08T23:05:46.190293Z","caller":"traceutil/trace.go:171","msg":"trace[1789813260] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-mfltx.18a48437f5017586; range_end:; response_count:1; response_revision:4908; }","duration":"237.612766ms","start":"2026-04-08T23:05:45.952657Z","end":"2026-04-08T23:05:46.19027Z","steps":["trace[1789813260] 'agreement among raft nodes before linearized reading' (duration: 237.401341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:46.469784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.945136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T23:05:46.469916Z","caller":"traceutil/trace.go:171","msg":"trace[1594147675] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"277.128655ms","start":"2026-04-08T23:05:46.192768Z","end":"2026-04-08T23:05:46.469897Z","steps":["trace[1594147675] 'process raft request' (duration: 151.975134ms)","trace[1594147675] 'compare' (duration: 124.752522ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:05:46.626066Z","caller":"traceutil/trace.go:171","msg":"trace[834726146] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"431.070956ms","start":"2026-04-08T23:05:46.194969Z","end":"2026-04-08T23:05:46.62604Z","steps":["trace[834726146] 'process raft request' (duration: 430.969413ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:05:46.626083Z","caller":"traceutil/trace.go:171","msg":"trace[720501873] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"431.505626ms","start":"2026-04-08T23:05:46.194543Z","end":"2026-04-08T23:05:46.626048Z","steps":["trace[720501873] 'process raft request' (duration: 405.055651ms)","trace[720501873] 'compare' (duration: 26.210629ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:05:46.626216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:05:46.194963Z","time spent":"431.175908ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:05:46.626323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:05:46.194524Z","time spent":"431.630948ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:05:46.626442Z","caller":"traceutil/trace.go:171","msg":"trace[619883606] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"428.780542ms","start":"2026-04-08T23:05:46.197626Z","end":"2026-04-08T23:05:46.626407Z","steps":["trace[619883606] 'process raft request' (duration: 428.360182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:46.62657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:05:46.197605Z","time spent":"428.903936ms","remote":"127.0.0.1:58156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:05:46.626773Z","caller":"traceutil/trace.go:171","msg":"trace[1354607852] linearizableReadLoop","detail":"{readStateIndex:5116; appliedIndex:5112; }","duration":"399.397219ms","start":"2026-04-08T23:05:46.227349Z","end":"2026-04-08T23:05:46.626746Z","steps":["trace[1354607852] 'read index received' (duration: 117.482473ms)","trace[1354607852] 'applied index is now lower than readState.Index' (duration: 281.913726ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:05:46.626955Z","caller":"traceutil/trace.go:171","msg":"trace[1390362456] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"330.588249ms","start":"2026-04-08T23:05:46.296352Z","end":"2026-04-08T23:05:46.626941Z","steps":["trace[1390362456] 'process raft request' (duration: 330.268291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:46.627145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.785578ms","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-04-08T23:05:46.627203Z","caller":"traceutil/trace.go:171","msg":"trace[127274340] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:4913; }","duration":"399.88012ms","start":"2026-04-08T23:05:46.227308Z","end":"2026-04-08T23:05:46.627189Z","steps":["trace[127274340] 'agreement among raft nodes before linearized reading' (duration: 399.717457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:46.627253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:05:46.227292Z","time spent":"399.950662ms","remote":"127.0.0.1:58124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1805,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"warn","ts":"2026-04-08T23:05:46.627559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:05:46.296332Z","time spent":"330.65659ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:05:46.637284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.914103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-08T23:05:46.637337Z","caller":"traceutil/trace.go:171","msg":"trace[373548451] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4914; }","duration":"284.010635ms","start":"2026-04-08T23:05:46.353316Z","end":"2026-04-08T23:05:46.637326Z","steps":["trace[373548451] 'agreement among raft nodes before linearized reading' (duration: 283.76373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:46.637742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.296758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-08T23:05:46.637888Z","caller":"traceutil/trace.go:171","msg":"trace[589740719] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4914; }","duration":"166.41006ms","start":"2026-04-08T23:05:46.471385Z","end":"2026-04-08T23:05:46.637795Z","steps":["trace[589740719] 'agreement among raft nodes before linearized reading' (duration: 165.669153ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:05:53.403551Z","caller":"traceutil/trace.go:171","msg":"trace[1468195947] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"203.133995ms","start":"2026-04-08T23:05:53.200397Z","end":"2026-04-08T23:05:53.403531Z","steps":["trace[1468195947] 'process raft request' (duration: 200.382891ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:05:53.404457Z","caller":"traceutil/trace.go:171","msg":"trace[1162573887] linearizableReadLoop","detail":"{readStateIndex:5220; appliedIndex:5217; }","duration":"187.699706ms","start":"2026-04-08T23:05:53.21674Z","end":"2026-04-08T23:05:53.40444Z","steps":["trace[1162573887] 'read index received' (duration: 184.052021ms)","trace[1162573887] 'applied index is now lower than readState.Index' (duration: 3.646855ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:05:53.404556Z","caller":"traceutil/trace.go:171","msg":"trace[1554452161] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"203.504932ms","start":"2026-04-08T23:05:53.201013Z","end":"2026-04-08T23:05:53.404518Z","steps":["trace[1554452161] 'process raft request' (duration: 203.40127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:53.404661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.9174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-c47d949f5-zhl86\" ","response":"range_response_count:1 size:8466"} {"level":"info","ts":"2026-04-08T23:05:53.4047Z","caller":"traceutil/trace.go:171","msg":"trace[1547168273] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-c47d949f5-zhl86; range_end:; response_count:1; response_revision:5012; }","duration":"187.988782ms","start":"2026-04-08T23:05:53.2167Z","end":"2026-04-08T23:05:53.404688Z","steps":["trace[1547168273] 'agreement among raft nodes before linearized reading' (duration: 187.862739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:53.404734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.024262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:05:53.404753Z","caller":"traceutil/trace.go:171","msg":"trace[1932223479] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:5012; }","duration":"102.078724ms","start":"2026-04-08T23:05:53.302667Z","end":"2026-04-08T23:05:53.404746Z","steps":["trace[1932223479] 'agreement among raft nodes before linearized reading' (duration: 102.051343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:53.404795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.542164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-04-08T23:05:53.40482Z","caller":"traceutil/trace.go:171","msg":"trace[1621492571] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5012; }","duration":"117.608875ms","start":"2026-04-08T23:05:53.287204Z","end":"2026-04-08T23:05:53.404813Z","steps":["trace[1621492571] 'agreement among raft nodes before linearized reading' (duration: 117.527343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:53.404702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.362962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-08T23:05:53.40489Z","caller":"traceutil/trace.go:171","msg":"trace[1872757213] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5012; }","duration":"164.602138ms","start":"2026-04-08T23:05:53.24028Z","end":"2026-04-08T23:05:53.404882Z","steps":["trace[1872757213] 'agreement among raft nodes before linearized reading' (duration: 164.343582ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:05:53.404485Z","caller":"traceutil/trace.go:171","msg":"trace[567471099] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"204.064666ms","start":"2026-04-08T23:05:53.200406Z","end":"2026-04-08T23:05:53.40447Z","steps":["trace[567471099] 'process raft request' (duration: 203.908973ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:05:57.442094Z","caller":"traceutil/trace.go:171","msg":"trace[1585401305] linearizableReadLoop","detail":"{readStateIndex:5279; appliedIndex:5278; }","duration":"156.899289ms","start":"2026-04-08T23:05:57.285169Z","end":"2026-04-08T23:05:57.442069Z","steps":["trace[1585401305] 'read index received' (duration: 156.659293ms)","trace[1585401305] 'applied index is now lower than readState.Index' (duration: 238.966µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:05:57.442202Z","caller":"traceutil/trace.go:171","msg":"trace[782946386] transaction","detail":"{read_only:false; response_revision:5069; number_of_response:1; }","duration":"169.79926ms","start":"2026-04-08T23:05:57.272383Z","end":"2026-04-08T23:05:57.442183Z","steps":["trace[782946386] 'process raft request' (duration: 169.513683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:57.442377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.187106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-08T23:05:57.442423Z","caller":"traceutil/trace.go:171","msg":"trace[1392809516] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5069; }","duration":"157.283708ms","start":"2026-04-08T23:05:57.285125Z","end":"2026-04-08T23:05:57.442409Z","steps":["trace[1392809516] 'agreement among raft nodes before linearized reading' (duration: 157.053323ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:09:03.845139Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3271} {"level":"info","ts":"2026-04-08T23:09:03.930773Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3271,"took":"84.192214ms","hash":3198933948,"current-db-size-bytes":60780544,"current-db-size":"61 MB","current-db-size-in-use-bytes":33923072,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-08T23:09:03.930841Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3198933948,"revision":3271,"compact-revision":-1} {"level":"info","ts":"2026-04-08T23:11:32.132839Z","caller":"traceutil/trace.go:171","msg":"trace[1223263554] transaction","detail":"{read_only:false; response_revision:8087; number_of_response:1; }","duration":"186.926439ms","start":"2026-04-08T23:11:31.945887Z","end":"2026-04-08T23:11:32.132813Z","steps":["trace[1223263554] 'process raft request' (duration: 186.874978ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:32.132947Z","caller":"traceutil/trace.go:171","msg":"trace[781786427] transaction","detail":"{read_only:false; response_revision:8086; number_of_response:1; }","duration":"187.924822ms","start":"2026-04-08T23:11:31.944906Z","end":"2026-04-08T23:11:32.132831Z","steps":["trace[781786427] 'process raft request' (duration: 107.20588ms)","trace[781786427] 'compare' (duration: 80.524238ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:11:33.826353Z","caller":"traceutil/trace.go:171","msg":"trace[1202326178] linearizableReadLoop","detail":"{readStateIndex:8451; appliedIndex:8450; }","duration":"318.453836ms","start":"2026-04-08T23:11:33.507873Z","end":"2026-04-08T23:11:33.826327Z","steps":["trace[1202326178] 'read index received' (duration: 318.409565ms)","trace[1202326178] 'applied index is now lower than readState.Index' (duration: 43.311µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:11:33.826408Z","caller":"traceutil/trace.go:171","msg":"trace[1983374966] transaction","detail":"{read_only:false; response_revision:8095; number_of_response:1; }","duration":"425.060482ms","start":"2026-04-08T23:11:33.401315Z","end":"2026-04-08T23:11:33.826376Z","steps":["trace[1983374966] 'process raft request' (duration: 424.864087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:11:33.826552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.63587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:11:33.826657Z","caller":"traceutil/trace.go:171","msg":"trace[959377331] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:8095; }","duration":"318.795934ms","start":"2026-04-08T23:11:33.507839Z","end":"2026-04-08T23:11:33.826635Z","steps":["trace[959377331] 'agreement among raft nodes before linearized reading' (duration: 318.592249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:11:33.826697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:11:33.401299Z","time spent":"425.310627ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:11:33.826726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:11:33.507823Z","time spent":"318.886566ms","remote":"127.0.0.1:57632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-04-08T23:11:33.877406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.832976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:11:33.877489Z","caller":"traceutil/trace.go:171","msg":"trace[701282144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8096; }","duration":"210.95981ms","start":"2026-04-08T23:11:33.66651Z","end":"2026-04-08T23:11:33.87747Z","steps":["trace[701282144] 'agreement among raft nodes before linearized reading' (duration: 210.789976ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:33.8776Z","caller":"traceutil/trace.go:171","msg":"trace[776208446] transaction","detail":"{read_only:false; response_revision:8096; number_of_response:1; }","duration":"104.390744ms","start":"2026-04-08T23:11:33.773181Z","end":"2026-04-08T23:11:33.877571Z","steps":["trace[776208446] 'process raft request' (duration: 88.399931ms)","trace[776208446] 'compare' (duration: 15.582093ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:11:44.595227Z","caller":"traceutil/trace.go:171","msg":"trace[440334507] transaction","detail":"{read_only:false; response_revision:8171; number_of_response:1; }","duration":"165.390557ms","start":"2026-04-08T23:11:44.42981Z","end":"2026-04-08T23:11:44.595201Z","steps":["trace[440334507] 'process raft request' (duration: 156.765496ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:46.593025Z","caller":"traceutil/trace.go:171","msg":"trace[1385844742] linearizableReadLoop","detail":"{readStateIndex:8539; appliedIndex:8538; }","duration":"149.576458ms","start":"2026-04-08T23:11:46.44343Z","end":"2026-04-08T23:11:46.593006Z","steps":["trace[1385844742] 'read index received' (duration: 149.395854ms)","trace[1385844742] 'applied index is now lower than readState.Index' (duration: 179.814µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:11:46.593283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.831453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-08T23:11:46.593362Z","caller":"traceutil/trace.go:171","msg":"trace[1269140122] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8178; }","duration":"149.952107ms","start":"2026-04-08T23:11:46.443392Z","end":"2026-04-08T23:11:46.593344Z","steps":["trace[1269140122] 'agreement among raft nodes before linearized reading' (duration: 149.701091ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:46.593717Z","caller":"traceutil/trace.go:171","msg":"trace[150608240] transaction","detail":"{read_only:false; response_revision:8178; number_of_response:1; }","duration":"176.455894ms","start":"2026-04-08T23:11:46.417243Z","end":"2026-04-08T23:11:46.593699Z","steps":["trace[150608240] 'process raft request' (duration: 175.644355ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:46.893276Z","caller":"traceutil/trace.go:171","msg":"trace[1054803655] transaction","detail":"{read_only:false; response_revision:8179; number_of_response:1; }","duration":"379.198802ms","start":"2026-04-08T23:11:46.51406Z","end":"2026-04-08T23:11:46.893259Z","steps":["trace[1054803655] 'process raft request' (duration: 379.08846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:11:46.893379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:11:46.514028Z","time spent":"379.299525ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:11:46.893467Z","caller":"traceutil/trace.go:171","msg":"trace[2004480624] linearizableReadLoop","detail":"{readStateIndex:8540; appliedIndex:8540; }","duration":"300.372805ms","start":"2026-04-08T23:11:46.593072Z","end":"2026-04-08T23:11:46.893445Z","steps":["trace[2004480624] 'read index received' (duration: 300.365134ms)","trace[2004480624] 'applied index is now lower than readState.Index' (duration: 6.171µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:11:46.893569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.58287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-08T23:11:46.893597Z","caller":"traceutil/trace.go:171","msg":"trace[147186846] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8179; }","duration":"448.621501ms","start":"2026-04-08T23:11:46.444969Z","end":"2026-04-08T23:11:46.89359Z","steps":["trace[147186846] 'agreement among raft nodes before linearized reading' (duration: 448.541119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:11:46.893611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:11:46.444963Z","time spent":"448.645251ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T23:11:46.893669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.910302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:11:46.893684Z","caller":"traceutil/trace.go:171","msg":"trace[1532944691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8179; }","duration":"223.952772ms","start":"2026-04-08T23:11:46.669728Z","end":"2026-04-08T23:11:46.89368Z","steps":["trace[1532944691] 'agreement among raft nodes before linearized reading' (duration: 223.929402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:11:46.893688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.332013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"warn","ts":"2026-04-08T23:11:46.893706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.616522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-08T23:11:46.893762Z","caller":"traceutil/trace.go:171","msg":"trace[290164958] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8179; }","duration":"147.705395ms","start":"2026-04-08T23:11:46.746043Z","end":"2026-04-08T23:11:46.893748Z","steps":["trace[290164958] 'agreement among raft nodes before linearized reading' (duration: 147.574132ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:46.893723Z","caller":"traceutil/trace.go:171","msg":"trace[1152700745] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8179; }","duration":"297.406735ms","start":"2026-04-08T23:11:46.596306Z","end":"2026-04-08T23:11:46.893713Z","steps":["trace[1152700745] 'agreement among raft nodes before linearized reading' (duration: 297.283032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:11:46.893987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.828629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-08T23:11:46.894019Z","caller":"traceutil/trace.go:171","msg":"trace[1472023422] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8179; }","duration":"449.890991ms","start":"2026-04-08T23:11:46.444121Z","end":"2026-04-08T23:11:46.894012Z","steps":["trace[1472023422] 'agreement among raft nodes before linearized reading' (duration: 449.4408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:11:46.894041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:11:46.444103Z","time spent":"449.932451ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":550,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-08T23:11:48.338832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.449319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T23:11:48.338918Z","caller":"traceutil/trace.go:171","msg":"trace[566871977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8190; }","duration":"123.550681ms","start":"2026-04-08T23:11:48.215347Z","end":"2026-04-08T23:11:48.338898Z","steps":["trace[566871977] 'range keys from in-memory index tree' (duration: 123.273154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:11:48.339015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.281954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T23:11:48.339098Z","caller":"traceutil/trace.go:171","msg":"trace[277420497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8190; }","duration":"117.410127ms","start":"2026-04-08T23:11:48.221669Z","end":"2026-04-08T23:11:48.339079Z","steps":["trace[277420497] 'range keys from in-memory index tree' (duration: 117.111411ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:48.524635Z","caller":"traceutil/trace.go:171","msg":"trace[1657938216] transaction","detail":"{read_only:false; response_revision:8192; number_of_response:1; }","duration":"179.261669ms","start":"2026-04-08T23:11:48.345353Z","end":"2026-04-08T23:11:48.524614Z","steps":["trace[1657938216] 'process raft request' (duration: 179.125676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:11:48.701729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.574209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T23:11:48.701812Z","caller":"traceutil/trace.go:171","msg":"trace[1704410627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8192; }","duration":"104.701052ms","start":"2026-04-08T23:11:48.597089Z","end":"2026-04-08T23:11:48.70179Z","steps":["trace[1704410627] 'range keys from in-memory index tree' (duration: 104.377315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:11:49.029772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.836477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T23:11:49.029863Z","caller":"traceutil/trace.go:171","msg":"trace[591568332] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:8193; }","duration":"192.98549ms","start":"2026-04-08T23:11:48.836858Z","end":"2026-04-08T23:11:49.029843Z","steps":["trace[591568332] 'count revisions from in-memory index tree' (duration: 192.752066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:11:49.029893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.595849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-08T23:11:49.029986Z","caller":"traceutil/trace.go:171","msg":"trace[651500344] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8193; }","duration":"116.736021ms","start":"2026-04-08T23:11:48.913224Z","end":"2026-04-08T23:11:49.02996Z","steps":["trace[651500344] 'range keys from in-memory index tree' (duration: 116.392324ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:57.356757Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000021b5.wal"} {"level":"info","ts":"2026-04-08T23:12:13.273251Z","caller":"traceutil/trace.go:171","msg":"trace[1592400951] linearizableReadLoop","detail":"{readStateIndex:8785; appliedIndex:8784; }","duration":"128.443286ms","start":"2026-04-08T23:12:13.144786Z","end":"2026-04-08T23:12:13.273229Z","steps":["trace[1592400951] 'read index received' (duration: 128.303332ms)","trace[1592400951] 'applied index is now lower than readState.Index' (duration: 139.124µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:12:13.27338Z","caller":"traceutil/trace.go:171","msg":"trace[843197819] transaction","detail":"{read_only:false; response_revision:8411; number_of_response:1; }","duration":"130.228877ms","start":"2026-04-08T23:12:13.143106Z","end":"2026-04-08T23:12:13.273335Z","steps":["trace[843197819] 'process raft request' (duration: 129.990582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:13.273467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.665451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-08T23:12:13.27351Z","caller":"traceutil/trace.go:171","msg":"trace[623595361] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8411; }","duration":"128.762193ms","start":"2026-04-08T23:12:13.144737Z","end":"2026-04-08T23:12:13.273499Z","steps":["trace[623595361] 'agreement among raft nodes before linearized reading' (duration: 128.6248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:13.273528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.524274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2ng24\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T23:12:13.27356Z","caller":"traceutil/trace.go:171","msg":"trace[2102331810] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2ng24; range_end:; response_count:1; response_revision:8411; }","duration":"106.586135ms","start":"2026-04-08T23:12:13.166965Z","end":"2026-04-08T23:12:13.273551Z","steps":["trace[2102331810] 'agreement among raft nodes before linearized reading' (duration: 106.503733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:15.066315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4223705126987389113,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T23:12:15.567453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4223705126987389113,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T23:12:15.733385Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.222895087s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T23:12:15.734014Z","caller":"traceutil/trace.go:171","msg":"trace[1337020203] transaction","detail":"{read_only:false; response_revision:8419; number_of_response:1; }","duration":"1.223644685s","start":"2026-04-08T23:12:14.510308Z","end":"2026-04-08T23:12:15.733953Z","steps":["trace[1337020203] 'process raft request' (duration: 1.223334107s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:15.73418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:14.510275Z","time spent":"1.223818948s","remote":"127.0.0.1:58142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18153,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:12:15.735352Z","caller":"traceutil/trace.go:171","msg":"trace[1682784916] linearizableReadLoop","detail":"{readStateIndex:8794; appliedIndex:8792; }","duration":"1.169925352s","start":"2026-04-08T23:12:14.565395Z","end":"2026-04-08T23:12:15.73532Z","steps":["trace[1682784916] 'read index received' (duration: 1.168380465s)","trace[1682784916] 'applied index is now lower than readState.Index' (duration: 1.543297ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:12:15.735466Z","caller":"traceutil/trace.go:171","msg":"trace[1177627963] transaction","detail":"{read_only:false; response_revision:8420; number_of_response:1; }","duration":"276.492218ms","start":"2026-04-08T23:12:15.458928Z","end":"2026-04-08T23:12:15.73542Z","steps":["trace[1177627963] 'process raft request' (duration: 276.213591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:15.73566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.360646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2ng24\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T23:12:15.735706Z","caller":"traceutil/trace.go:171","msg":"trace[2068068434] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2ng24; range_end:; response_count:1; response_revision:8420; }","duration":"451.475508ms","start":"2026-04-08T23:12:15.284219Z","end":"2026-04-08T23:12:15.735695Z","steps":["trace[2068068434] 'agreement among raft nodes before linearized reading' (duration: 451.273094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:15.735741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:15.284193Z","time spent":"451.540149ms","remote":"127.0.0.1:58156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6555,"request content":"key:\"/registry/pods/openstack/keepalived-2ng24\" "} {"level":"warn","ts":"2026-04-08T23:12:15.735745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.50263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-04-08T23:12:15.73575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.6967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-04-08T23:12:15.735807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.132274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-04-08T23:12:15.735775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089375023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T23:12:15.735826Z","caller":"traceutil/trace.go:171","msg":"trace[249021765] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8420; }","duration":"417.771802ms","start":"2026-04-08T23:12:15.318036Z","end":"2026-04-08T23:12:15.735808Z","steps":["trace[249021765] 'agreement among raft nodes before linearized reading' (duration: 417.562047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:15.735833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.400186ms","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-04-08T23:12:15.735864Z","caller":"traceutil/trace.go:171","msg":"trace[477805945] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8420; }","duration":"404.225867ms","start":"2026-04-08T23:12:15.33161Z","end":"2026-04-08T23:12:15.735836Z","steps":["trace[477805945] 'agreement among raft nodes before linearized reading' (duration: 404.048042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:15.73587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:15.318004Z","time spent":"417.857334ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-04-08T23:12:15.735815Z","caller":"traceutil/trace.go:171","msg":"trace[1171597676] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8420; }","duration":"406.633163ms","start":"2026-04-08T23:12:15.329163Z","end":"2026-04-08T23:12:15.735796Z","steps":["trace[1171597676] 'agreement among raft nodes before linearized reading' (duration: 406.409117ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:15.735895Z","caller":"traceutil/trace.go:171","msg":"trace[176779323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8420; }","duration":"1.089541107s","start":"2026-04-08T23:12:14.646314Z","end":"2026-04-08T23:12:15.735855Z","steps":["trace[176779323] 'agreement among raft nodes before linearized reading' (duration: 1.089169178s)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:15.73593Z","caller":"traceutil/trace.go:171","msg":"trace[996840573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8420; }","duration":"457.56759ms","start":"2026-04-08T23:12:15.278342Z","end":"2026-04-08T23:12:15.73591Z","steps":["trace[996840573] 'agreement among raft nodes before linearized reading' (duration: 457.278024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:15.735953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:15.331599Z","time spent":"404.328539ms","remote":"127.0.0.1:58064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T23:12:15.73596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:15.329132Z","time spent":"406.818477ms","remote":"127.0.0.1:58064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T23:12:15.735977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:14.646295Z","time spent":"1.089672301s","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T23:12:15.735991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:15.278323Z","time spent":"457.660742ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T23:12:15.735917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070107804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T23:12:15.736181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.453129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-08T23:12:15.736197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170806682s","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-04-08T23:12:15.736204Z","caller":"traceutil/trace.go:171","msg":"trace[1872170302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8420; }","duration":"1.070417111s","start":"2026-04-08T23:12:14.665749Z","end":"2026-04-08T23:12:15.736166Z","steps":["trace[1872170302] 'agreement among raft nodes before linearized reading' (duration: 1.06993661s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:15.736388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:14.66573Z","time spent":"1.070645196s","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T23:12:15.736218Z","caller":"traceutil/trace.go:171","msg":"trace[395585176] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8420; }","duration":"852.54394ms","start":"2026-04-08T23:12:14.883665Z","end":"2026-04-08T23:12:15.736209Z","steps":["trace[395585176] 'agreement among raft nodes before linearized reading' (duration: 851.864874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:15.736561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:14.883649Z","time spent":"852.900019ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T23:12:15.736289Z","caller":"traceutil/trace.go:171","msg":"trace[572306229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8420; }","duration":"1.170879144s","start":"2026-04-08T23:12:14.565341Z","end":"2026-04-08T23:12:15.73622Z","steps":["trace[572306229] 'agreement among raft nodes before linearized reading' (duration: 1.170080855s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:15.736655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:14.565324Z","time spent":"1.171326555s","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T23:12:27.242159Z","caller":"traceutil/trace.go:171","msg":"trace[710386935] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8539; }","duration":"172.953883ms","start":"2026-04-08T23:12:27.069167Z","end":"2026-04-08T23:12:27.242121Z","steps":["trace[710386935] 'process raft request' (duration: 73.239008ms)","trace[710386935] 'compare' (duration: 99.529001ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:12:27.281571Z","caller":"traceutil/trace.go:171","msg":"trace[501216953] transaction","detail":"{read_only:false; response_revision:8540; number_of_response:1; }","duration":"210.791325ms","start":"2026-04-08T23:12:27.070748Z","end":"2026-04-08T23:12:27.28154Z","steps":["trace[501216953] 'process raft request' (duration: 210.535759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:27.58239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.671055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T23:12:27.582555Z","caller":"traceutil/trace.go:171","msg":"trace[1809370302] transaction","detail":"{read_only:false; response_revision:8542; number_of_response:1; }","duration":"284.30481ms","start":"2026-04-08T23:12:27.298207Z","end":"2026-04-08T23:12:27.582512Z","steps":["trace[1809370302] 'process raft request' (duration: 51.074771ms)","trace[1809370302] 'compare' (duration: 232.501612ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:12:27.582716Z","caller":"traceutil/trace.go:171","msg":"trace[764140082] linearizableReadLoop","detail":"{readStateIndex:8923; appliedIndex:8922; }","duration":"277.643494ms","start":"2026-04-08T23:12:27.305052Z","end":"2026-04-08T23:12:27.582696Z","steps":["trace[764140082] 'read index received' (duration: 44.203ms)","trace[764140082] 'applied index is now lower than readState.Index' (duration: 233.438194ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:12:27.582962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.361855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T23:12:27.583002Z","caller":"traceutil/trace.go:171","msg":"trace[341112230] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:8542; }","duration":"285.433446ms","start":"2026-04-08T23:12:27.297559Z","end":"2026-04-08T23:12:27.582992Z","steps":["trace[341112230] 'agreement among raft nodes before linearized reading' (duration: 285.352284ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:27.583841Z","caller":"traceutil/trace.go:171","msg":"trace[836551678] transaction","detail":"{read_only:false; response_revision:8543; number_of_response:1; }","duration":"277.952371ms","start":"2026-04-08T23:12:27.305867Z","end":"2026-04-08T23:12:27.58382Z","steps":["trace[836551678] 'process raft request' (duration: 277.845529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:27.584053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.949615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:12:27.584107Z","caller":"traceutil/trace.go:171","msg":"trace[1728865601] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8543; }","duration":"243.17983ms","start":"2026-04-08T23:12:27.340912Z","end":"2026-04-08T23:12:27.584092Z","steps":["trace[1728865601] 'agreement among raft nodes before linearized reading' (duration: 242.879663ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:27.929798Z","caller":"traceutil/trace.go:171","msg":"trace[370467836] transaction","detail":"{read_only:false; response_revision:8544; number_of_response:1; }","duration":"125.70595ms","start":"2026-04-08T23:12:27.804065Z","end":"2026-04-08T23:12:27.929771Z","steps":["trace[370467836] 'process raft request' (duration: 125.556727ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:28.09702Z","caller":"traceutil/trace.go:171","msg":"trace[2061265927] linearizableReadLoop","detail":"{readStateIndex:8926; appliedIndex:8924; }","duration":"291.382674ms","start":"2026-04-08T23:12:27.805616Z","end":"2026-04-08T23:12:28.096999Z","steps":["trace[2061265927] 'read index received' (duration: 124.077542ms)","trace[2061265927] 'applied index is now lower than readState.Index' (duration: 167.303482ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:12:28.097047Z","caller":"traceutil/trace.go:171","msg":"trace[2007744010] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"290.973375ms","start":"2026-04-08T23:12:27.806057Z","end":"2026-04-08T23:12:28.09703Z","steps":["trace[2007744010] 'process raft request' (duration: 290.75855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:28.097232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.596749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-08T23:12:28.097308Z","caller":"traceutil/trace.go:171","msg":"trace[184604037] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8546; }","duration":"291.726962ms","start":"2026-04-08T23:12:27.805562Z","end":"2026-04-08T23:12:28.097289Z","steps":["trace[184604037] 'agreement among raft nodes before linearized reading' (duration: 291.516447ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:28.097348Z","caller":"traceutil/trace.go:171","msg":"trace[1801505313] transaction","detail":"{read_only:false; response_revision:8545; number_of_response:1; }","duration":"291.335243ms","start":"2026-04-08T23:12:27.805537Z","end":"2026-04-08T23:12:28.096872Z","steps":["trace[1801505313] 'process raft request' (duration: 277.525151ms)","trace[1801505313] 'compare' (duration: 13.588277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:12:28.105887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.514677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-08T23:12:28.105941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.020277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-08T23:12:28.10597Z","caller":"traceutil/trace.go:171","msg":"trace[991667521] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8547; }","duration":"294.6364ms","start":"2026-04-08T23:12:27.811315Z","end":"2026-04-08T23:12:28.105952Z","steps":["trace[991667521] 'agreement among raft nodes before linearized reading' (duration: 294.395464ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:28.105994Z","caller":"traceutil/trace.go:171","msg":"trace[916586456] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8547; }","duration":"252.094748ms","start":"2026-04-08T23:12:27.853884Z","end":"2026-04-08T23:12:28.105979Z","steps":["trace[916586456] 'agreement among raft nodes before linearized reading' (duration: 251.949815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:28.106219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.178386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2ng24\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T23:12:28.106336Z","caller":"traceutil/trace.go:171","msg":"trace[449898530] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2ng24; range_end:; response_count:1; response_revision:8547; }","duration":"293.338209ms","start":"2026-04-08T23:12:27.812973Z","end":"2026-04-08T23:12:28.106311Z","steps":["trace[449898530] 'agreement among raft nodes before linearized reading' (duration: 292.932859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:28.106816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.163567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-08T23:12:28.106908Z","caller":"traceutil/trace.go:171","msg":"trace[1726211692] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8547; }","duration":"248.29886ms","start":"2026-04-08T23:12:27.858586Z","end":"2026-04-08T23:12:28.106885Z","steps":["trace[1726211692] 'agreement among raft nodes before linearized reading' (duration: 248.067855ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:28.299124Z","caller":"traceutil/trace.go:171","msg":"trace[980992583] transaction","detail":"{read_only:false; response_revision:8553; number_of_response:1; }","duration":"140.684131ms","start":"2026-04-08T23:12:28.158414Z","end":"2026-04-08T23:12:28.299099Z","steps":["trace[980992583] 'process raft request' (duration: 140.574078ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:28.299443Z","caller":"traceutil/trace.go:171","msg":"trace[2119242279] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"141.08928ms","start":"2026-04-08T23:12:28.158116Z","end":"2026-04-08T23:12:28.299205Z","steps":["trace[2119242279] 'process raft request' (duration: 140.716462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:28.80379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4223705126987389500,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T23:12:29.260936Z","caller":"traceutil/trace.go:171","msg":"trace[2064520851] linearizableReadLoop","detail":"{readStateIndex:8935; appliedIndex:8934; }","duration":"957.777254ms","start":"2026-04-08T23:12:28.303133Z","end":"2026-04-08T23:12:29.26091Z","steps":["trace[2064520851] 'read index received' (duration: 957.523958ms)","trace[2064520851] 'applied index is now lower than readState.Index' (duration: 252.386µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:12:29.261016Z","caller":"traceutil/trace.go:171","msg":"trace[2118230874] transaction","detail":"{read_only:false; response_revision:8554; number_of_response:1; }","duration":"1.061527334s","start":"2026-04-08T23:12:28.199444Z","end":"2026-04-08T23:12:29.260971Z","steps":["trace[2118230874] 'process raft request' (duration: 1.061315439s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:29.261162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.00696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"warn","ts":"2026-04-08T23:12:29.261199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:28.199403Z","time spent":"1.061699248s","remote":"127.0.0.1:58454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:12:29.261209Z","caller":"traceutil/trace.go:171","msg":"trace[875164600] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8554; }","duration":"958.067622ms","start":"2026-04-08T23:12:28.303126Z","end":"2026-04-08T23:12:29.261193Z","steps":["trace[875164600] 'agreement among raft nodes before linearized reading' (duration: 957.910708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:29.26125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:28.303102Z","time spent":"958.139073ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":592,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T23:12:29.261252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.918742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:12:29.261346Z","caller":"traceutil/trace.go:171","msg":"trace[1171903217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8554; }","duration":"595.021025ms","start":"2026-04-08T23:12:28.666302Z","end":"2026-04-08T23:12:29.261323Z","steps":["trace[1171903217] 'agreement among raft nodes before linearized reading' (duration: 594.856341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:29.261394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:28.666232Z","time spent":"595.154468ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T23:12:29.261621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.42493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-08T23:12:29.262295Z","caller":"traceutil/trace.go:171","msg":"trace[42582943] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8554; }","duration":"959.149897ms","start":"2026-04-08T23:12:28.303126Z","end":"2026-04-08T23:12:29.262276Z","steps":["trace[42582943] 'agreement among raft nodes before linearized reading' (duration: 958.00182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:29.262387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:28.30311Z","time spent":"959.260669ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T23:12:30.274713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.480609ms","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-04-08T23:12:30.274789Z","caller":"traceutil/trace.go:171","msg":"trace[421865421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8563; }","duration":"102.618443ms","start":"2026-04-08T23:12:30.172156Z","end":"2026-04-08T23:12:30.274774Z","steps":["trace[421865421] 'agreement among raft nodes before linearized reading' (duration: 102.405638ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:30.75814Z","caller":"traceutil/trace.go:171","msg":"trace[1623095256] linearizableReadLoop","detail":"{readStateIndex:8946; appliedIndex:8945; }","duration":"127.680618ms","start":"2026-04-08T23:12:30.630439Z","end":"2026-04-08T23:12:30.75812Z","steps":["trace[1623095256] 'read index received' (duration: 118.417811ms)","trace[1623095256] 'applied index is now lower than readState.Index' (duration: 9.262137ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:12:30.758315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.167415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T23:12:30.75834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.878202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:2953"} {"level":"info","ts":"2026-04-08T23:12:30.758414Z","caller":"traceutil/trace.go:171","msg":"trace[1676489380] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8564; }","duration":"127.999545ms","start":"2026-04-08T23:12:30.630398Z","end":"2026-04-08T23:12:30.758397Z","steps":["trace[1676489380] 'agreement among raft nodes before linearized reading' (duration: 127.7824ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:30.758417Z","caller":"traceutil/trace.go:171","msg":"trace[1864957104] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:8564; }","duration":"127.296699ms","start":"2026-04-08T23:12:30.631089Z","end":"2026-04-08T23:12:30.758386Z","steps":["trace[1864957104] 'agreement among raft nodes before linearized reading' (duration: 127.140275ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:30.758808Z","caller":"traceutil/trace.go:171","msg":"trace[1624800256] transaction","detail":"{read_only:false; response_revision:8564; number_of_response:1; }","duration":"479.259546ms","start":"2026-04-08T23:12:30.278886Z","end":"2026-04-08T23:12:30.758145Z","steps":["trace[1624800256] 'process raft request' (duration: 470.027631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:30.758993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:30.278861Z","time spent":"480.048714ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:12:32.129969Z","caller":"traceutil/trace.go:171","msg":"trace[100408283] linearizableReadLoop","detail":"{readStateIndex:8957; appliedIndex:8955; }","duration":"177.629352ms","start":"2026-04-08T23:12:31.9523Z","end":"2026-04-08T23:12:32.12993Z","steps":["trace[100408283] 'read index received' (duration: 9.719217ms)","trace[100408283] 'applied index is now lower than readState.Index' (duration: 167.908505ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:12:32.130124Z","caller":"traceutil/trace.go:171","msg":"trace[485113838] transaction","detail":"{read_only:false; response_revision:8574; number_of_response:1; }","duration":"185.67283ms","start":"2026-04-08T23:12:31.944432Z","end":"2026-04-08T23:12:32.130105Z","steps":["trace[485113838] 'process raft request' (duration: 147.458769ms)","trace[485113838] 'compare' (duration: 37.897154ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:12:32.130397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.082772ms","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-04-08T23:12:32.130469Z","caller":"traceutil/trace.go:171","msg":"trace[735837295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8574; }","duration":"178.195575ms","start":"2026-04-08T23:12:31.952257Z","end":"2026-04-08T23:12:32.130453Z","steps":["trace[735837295] 'agreement among raft nodes before linearized reading' (duration: 177.98994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:13:33.035409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.434227ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-08T23:13:33.036272Z","caller":"traceutil/trace.go:171","msg":"trace[1897049578] linearizableReadLoop","detail":"{readStateIndex:9667; appliedIndex:9665; }","duration":"537.207907ms","start":"2026-04-08T23:13:32.499037Z","end":"2026-04-08T23:13:33.036245Z","steps":["trace[1897049578] 'read index received' (duration: 69.947091ms)","trace[1897049578] 'applied index is now lower than readState.Index' (duration: 467.259396ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:13:33.036287Z","caller":"traceutil/trace.go:171","msg":"trace[768676845] transaction","detail":"{read_only:false; response_revision:9253; number_of_response:1; }","duration":"550.309983ms","start":"2026-04-08T23:13:32.485944Z","end":"2026-04-08T23:13:33.036254Z","steps":["trace[768676845] 'process raft request' (duration: 549.638747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:13:33.036461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:13:32.485921Z","time spent":"550.444306ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:13:33.090271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.555992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T23:13:33.09034Z","caller":"traceutil/trace.go:171","msg":"trace[1749231917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9253; }","duration":"548.669304ms","start":"2026-04-08T23:13:32.54165Z","end":"2026-04-08T23:13:33.090319Z","steps":["trace[1749231917] 'agreement among raft nodes before linearized reading' (duration: 494.745047ms)","trace[1749231917] 'range keys from in-memory index tree' (duration: 53.62571ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:13:33.090385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:13:32.541628Z","time spent":"548.744386ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T23:13:33.090476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.976846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:13:33.090584Z","caller":"traceutil/trace.go:171","msg":"trace[1858906599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9253; }","duration":"424.12341ms","start":"2026-04-08T23:13:32.666434Z","end":"2026-04-08T23:13:33.090557Z","steps":["trace[1858906599] 'agreement among raft nodes before linearized reading' (duration: 370.014948ms)","trace[1858906599] 'range keys from in-memory index tree' (duration: 53.980009ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:13:33.090604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.152489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-08T23:13:33.090476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.637441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-04-08T23:13:33.090654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:13:32.666395Z","time spent":"424.235772ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T23:13:33.090685Z","caller":"traceutil/trace.go:171","msg":"trace[505507695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9253; }","duration":"538.249441ms","start":"2026-04-08T23:13:32.552415Z","end":"2026-04-08T23:13:33.090664Z","steps":["trace[505507695] 'agreement among raft nodes before linearized reading' (duration: 484.114929ms)","trace[505507695] 'range keys from in-memory index tree' (duration: 53.963698ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:13:33.090715Z","caller":"traceutil/trace.go:171","msg":"trace[1885369194] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:9253; }","duration":"532.892777ms","start":"2026-04-08T23:13:32.557789Z","end":"2026-04-08T23:13:33.090681Z","steps":["trace[1885369194] 'agreement among raft nodes before linearized reading' (duration: 478.732204ms)","trace[1885369194] 'range keys from in-memory index tree' (duration: 53.821055ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:13:33.090468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.398771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-08T23:13:33.090778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:13:32.557783Z","time spent":"532.979399ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":550,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-08T23:13:33.090468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.707683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2ng24\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T23:13:33.090836Z","caller":"traceutil/trace.go:171","msg":"trace[1045868149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9253; }","duration":"591.78747ms","start":"2026-04-08T23:13:32.499022Z","end":"2026-04-08T23:13:33.090809Z","steps":["trace[1045868149] 'agreement among raft nodes before linearized reading' (duration: 537.35119ms)","trace[1045868149] 'range keys from in-memory index tree' (duration: 53.918007ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:13:33.090865Z","caller":"traceutil/trace.go:171","msg":"trace[1112430216] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2ng24; range_end:; response_count:1; response_revision:9253; }","duration":"476.171944ms","start":"2026-04-08T23:13:32.614677Z","end":"2026-04-08T23:13:33.090849Z","steps":["trace[1112430216] 'agreement among raft nodes before linearized reading' (duration: 421.721744ms)","trace[1112430216] 'range keys from in-memory index tree' (duration: 53.835755ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:13:33.090914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:13:32.498983Z","time spent":"591.911803ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T23:13:33.090767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:13:32.552405Z","time spent":"538.347103ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T23:13:33.090948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:13:32.614657Z","time spent":"476.277266ms","remote":"127.0.0.1:58156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6555,"request content":"key:\"/registry/pods/openstack/keepalived-2ng24\" "} {"level":"warn","ts":"2026-04-08T23:13:33.09116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.726053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-08T23:13:33.091255Z","caller":"traceutil/trace.go:171","msg":"trace[1436633966] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9253; }","duration":"526.855425ms","start":"2026-04-08T23:13:32.564377Z","end":"2026-04-08T23:13:33.091233Z","steps":["trace[1436633966] 'agreement among raft nodes before linearized reading' (duration: 472.16373ms)","trace[1436633966] 'range keys from in-memory index tree' (duration: 54.464ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:13:33.091308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:13:32.564369Z","time spent":"526.930007ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-08T23:13:33.221176Z","caller":"traceutil/trace.go:171","msg":"trace[1020507166] transaction","detail":"{read_only:false; response_revision:9254; number_of_response:1; }","duration":"126.304855ms","start":"2026-04-08T23:13:33.094844Z","end":"2026-04-08T23:13:33.221149Z","steps":["trace[1020507166] 'process raft request' (duration: 126.130371ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:13:33.249046Z","caller":"traceutil/trace.go:171","msg":"trace[38818344] transaction","detail":"{read_only:false; response_revision:9255; number_of_response:1; }","duration":"153.642073ms","start":"2026-04-08T23:13:33.095372Z","end":"2026-04-08T23:13:33.249014Z","steps":["trace[38818344] 'process raft request' (duration: 153.404897ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:13:33.249068Z","caller":"traceutil/trace.go:171","msg":"trace[960464979] linearizableReadLoop","detail":"{readStateIndex:9669; appliedIndex:9667; }","duration":"153.13351ms","start":"2026-04-08T23:13:33.09591Z","end":"2026-04-08T23:13:33.249043Z","steps":["trace[960464979] 'read index received' (duration: 124.993094ms)","trace[960464979] 'applied index is now lower than readState.Index' (duration: 28.139456ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:13:33.249152Z","caller":"traceutil/trace.go:171","msg":"trace[121021273] transaction","detail":"{read_only:false; response_revision:9256; number_of_response:1; }","duration":"153.13921ms","start":"2026-04-08T23:13:33.095995Z","end":"2026-04-08T23:13:33.249134Z","steps":["trace[121021273] 'process raft request' (duration: 152.905415ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:13:33.249198Z","caller":"traceutil/trace.go:171","msg":"trace[833513576] transaction","detail":"{read_only:false; response_revision:9258; number_of_response:1; }","duration":"153.072789ms","start":"2026-04-08T23:13:33.096093Z","end":"2026-04-08T23:13:33.249166Z","steps":["trace[833513576] 'process raft request' (duration: 152.883615ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:13:33.249239Z","caller":"traceutil/trace.go:171","msg":"trace[1099784979] transaction","detail":"{read_only:false; response_revision:9259; number_of_response:1; }","duration":"152.948166ms","start":"2026-04-08T23:13:33.096257Z","end":"2026-04-08T23:13:33.249205Z","steps":["trace[1099784979] 'process raft request' (duration: 152.753072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:13:33.249502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.574131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:28 size:241226"} {"level":"info","ts":"2026-04-08T23:13:33.249551Z","caller":"traceutil/trace.go:171","msg":"trace[1990686575] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:28; response_revision:9259; }","duration":"153.666123ms","start":"2026-04-08T23:13:33.095874Z","end":"2026-04-08T23:13:33.24954Z","steps":["trace[1990686575] 'agreement among raft nodes before linearized reading' (duration: 153.261354ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:13:33.249633Z","caller":"traceutil/trace.go:171","msg":"trace[357055503] transaction","detail":"{read_only:false; response_revision:9257; number_of_response:1; }","duration":"153.312974ms","start":"2026-04-08T23:13:33.095776Z","end":"2026-04-08T23:13:33.249089Z","steps":["trace[357055503] 'process raft request' (duration: 153.169001ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:14:03.851529Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6631} {"level":"info","ts":"2026-04-08T23:14:03.952949Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6631,"took":"99.901478ms","hash":1597686112,"current-db-size-bytes":60780544,"current-db-size":"61 MB","current-db-size-in-use-bytes":26939392,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-08T23:14:03.953051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1597686112,"revision":6631,"compact-revision":3271} {"level":"info","ts":"2026-04-08T23:14:08.287687Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e1d03a09d7253a9d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-08T23:14:08.293805Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-08T23:14:08.293953Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-08T23:19:03.857906Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9517} {"level":"info","ts":"2026-04-08T23:19:03.943091Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9517,"took":"83.008363ms","hash":306283668,"current-db-size-bytes":60780544,"current-db-size":"61 MB","current-db-size-in-use-bytes":25210880,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-08T23:19:03.943167Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":306283668,"revision":9517,"compact-revision":6631}