{"level":"warn","ts":"2026-01-29T15:43:42.88627Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T15:43:42.886333Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.204.45.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-01-29T15:43:42.886382Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T15:43:42.88639Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-01-29T15:43:42.886403Z","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-01-29T15:43:42.886875Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-01-29T15:43:42.886931Z","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":2,"max-cpu-available":2,"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.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-01-29T15:43:42.892898Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.640234ms"} {"level":"info","ts":"2026-01-29T15:43:42.902703Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-01-29T15:43:42.902778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-01-29T15:43:42.902824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-01-29T15:43:42.902842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-29T15:43:42.902857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-01-29T15:43:42.902912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-01-29T15:43:42.912801Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-29T15:43:42.921802Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-29T15:43:42.92609Z","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-01-29T15:43:42.929645Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-29T15:43:42.931555Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-29T15:43:42.933523Z","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-01-29T15:43:42.933546Z","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-01-29T15:43:42.933563Z","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-01-29T15:43:42.940221Z","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-01-29T15:43:42.940356Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-29T15:43:42.940373Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-29T15:43:42.940437Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-01-29T15:43:42.940442Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-01-29T15:43:42.942398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-01-29T15:43:42.942615Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-01-29T15:43:43.904126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-01-29T15:43:43.904669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-01-29T15:43:43.904693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-01-29T15:43:43.904704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-01-29T15:43:43.904714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-01-29T15:43:43.904722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-01-29T15:43:43.904729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-01-29T15:43:43.909111Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-01-29T15:43:43.909255Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T15:43:43.909552Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T15:43:43.909642Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T15:43:43.910814Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-29T15:43:43.910868Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-29T15:43:43.912227Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-29T15:43:43.913942Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-01-29T15:43:43.914016Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T15:43:43.914049Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T15:43:43.914275Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-01-29T15:43:59.182366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.392205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4729"} {"level":"info","ts":"2026-01-29T15:43:59.182427Z","caller":"traceutil/trace.go:171","msg":"trace[182867010] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:418; }","duration":"171.492747ms","start":"2026-01-29T15:43:59.010921Z","end":"2026-01-29T15:43:59.182413Z","steps":["trace[182867010] 'range keys from in-memory index tree' (duration: 171.258662ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:43:59.18275Z","caller":"traceutil/trace.go:171","msg":"trace[1045004747] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"163.329948ms","start":"2026-01-29T15:43:59.019412Z","end":"2026-01-29T15:43:59.182742Z","steps":["trace[1045004747] 'process raft request' (duration: 161.459026ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:44:01.962025Z","caller":"traceutil/trace.go:171","msg":"trace[710931417] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"260.346969ms","start":"2026-01-29T15:44:01.701662Z","end":"2026-01-29T15:44:01.962009Z","steps":["trace[710931417] 'process raft request' (duration: 260.260737ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:44:01.96562Z","caller":"traceutil/trace.go:171","msg":"trace[211794189] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"262.103468ms","start":"2026-01-29T15:44:01.70351Z","end":"2026-01-29T15:44:01.965613Z","steps":["trace[211794189] 'process raft request' (duration: 261.935574ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:44:05.214672Z","caller":"traceutil/trace.go:171","msg":"trace[258320397] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:476; }","duration":"331.938012ms","start":"2026-01-29T15:44:04.882718Z","end":"2026-01-29T15:44:05.214656Z","steps":["trace[258320397] 'read index received' (duration: 331.807399ms)","trace[258320397] 'applied index is now lower than readState.Index' (duration: 130.023µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:44:05.214753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.018773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:44:05.214769Z","caller":"traceutil/trace.go:171","msg":"trace[1247948899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:464; }","duration":"332.075534ms","start":"2026-01-29T15:44:04.882688Z","end":"2026-01-29T15:44:05.214763Z","steps":["trace[1247948899] 'agreement among raft nodes before linearized reading' (duration: 332.031113ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:05.214787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:04.882676Z","time spent":"332.108155ms","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T15:44:05.214849Z","caller":"traceutil/trace.go:171","msg":"trace[943709411] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"413.592195ms","start":"2026-01-29T15:44:04.80122Z","end":"2026-01-29T15:44:05.214813Z","steps":["trace[943709411] 'process raft request' (duration: 413.34438ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:05.215251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:04.801212Z","time spent":"413.685107ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:44:05.616564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.178723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-29T15:44:05.616623Z","caller":"traceutil/trace.go:171","msg":"trace[1129787507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:464; }","duration":"370.274525ms","start":"2026-01-29T15:44:05.246336Z","end":"2026-01-29T15:44:05.61661Z","steps":["trace[1129787507] 'range keys from in-memory index tree' (duration: 370.10285ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:05.616678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:05.24632Z","time spent":"370.350207ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T15:44:14.364876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.308177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T15:44:14.364922Z","caller":"traceutil/trace.go:171","msg":"trace[188998647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"343.387439ms","start":"2026-01-29T15:44:14.021526Z","end":"2026-01-29T15:44:14.364913Z","steps":["trace[188998647] 'range keys from in-memory index tree' (duration: 343.213364ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:14.364943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:14.02151Z","time spent":"343.42692ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-29T15:44:14.365024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.343321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T15:44:14.365036Z","caller":"traceutil/trace.go:171","msg":"trace[1400693736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"340.365442ms","start":"2026-01-29T15:44:14.024667Z","end":"2026-01-29T15:44:14.365032Z","steps":["trace[1400693736] 'range keys from in-memory index tree' (duration: 340.31716ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:14.365048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:14.024661Z","time spent":"340.383522ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-29T15:44:16.173754Z","caller":"traceutil/trace.go:171","msg":"trace[905761008] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"293.718378ms","start":"2026-01-29T15:44:15.88002Z","end":"2026-01-29T15:44:16.173739Z","steps":["trace[905761008] 'read index received' (duration: 293.587595ms)","trace[905761008] 'applied index is now lower than readState.Index' (duration: 130.283µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:44:16.173857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.81307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:44:16.17388Z","caller":"traceutil/trace.go:171","msg":"trace[820543621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"293.881392ms","start":"2026-01-29T15:44:15.879992Z","end":"2026-01-29T15:44:16.173873Z","steps":["trace[820543621] 'agreement among raft nodes before linearized reading' (duration: 293.809651ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:44:16.174131Z","caller":"traceutil/trace.go:171","msg":"trace[1114418568] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"420.680592ms","start":"2026-01-29T15:44:15.753445Z","end":"2026-01-29T15:44:16.174125Z","steps":["trace[1114418568] 'process raft request' (duration: 420.206011ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:16.174182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:15.75343Z","time spent":"420.718092ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:44:17.023437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.537131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:44:17.0235Z","caller":"traceutil/trace.go:171","msg":"trace[1747140161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"138.638652ms","start":"2026-01-29T15:44:16.884848Z","end":"2026-01-29T15:44:17.023487Z","steps":["trace[1747140161] 'range keys from in-memory index tree' (duration: 138.485709ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:44:17.733611Z","caller":"traceutil/trace.go:171","msg":"trace[232085939] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"404.498941ms","start":"2026-01-29T15:44:17.329086Z","end":"2026-01-29T15:44:17.733585Z","steps":["trace[232085939] 'process raft request' (duration: 404.355148ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:17.734118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:17.329071Z","time spent":"404.968522ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:44:19.96811Z","caller":"traceutil/trace.go:171","msg":"trace[1929176405] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"229.325881ms","start":"2026-01-29T15:44:19.738767Z","end":"2026-01-29T15:44:19.968093Z","steps":["trace[1929176405] 'read index received' (duration: 229.206269ms)","trace[1929176405] 'applied index is now lower than readState.Index' (duration: 119.112µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:44:19.968277Z","caller":"traceutil/trace.go:171","msg":"trace[1162369797] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"487.85899ms","start":"2026-01-29T15:44:19.480411Z","end":"2026-01-29T15:44:19.96827Z","steps":["trace[1162369797] 'process raft request' (duration: 487.600385ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:19.968333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:19.480395Z","time spent":"487.895621ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:44:19.968466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.703879ms","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-01-29T15:44:19.968482Z","caller":"traceutil/trace.go:171","msg":"trace[579507422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"229.746471ms","start":"2026-01-29T15:44:19.73873Z","end":"2026-01-29T15:44:19.968477Z","steps":["trace[579507422] 'agreement among raft nodes before linearized reading' (duration: 229.687559ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:22.231514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.773538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:44:22.231585Z","caller":"traceutil/trace.go:171","msg":"trace[1074860985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"348.880621ms","start":"2026-01-29T15:44:21.882688Z","end":"2026-01-29T15:44:22.231568Z","steps":["trace[1074860985] 'range keys from in-memory index tree' (duration: 348.703577ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:22.23162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:21.882644Z","time spent":"348.968243ms","remote":"127.0.0.1:44006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T15:44:22.231895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.326396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-29T15:44:22.231924Z","caller":"traceutil/trace.go:171","msg":"trace[1607359602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:540; }","duration":"254.388176ms","start":"2026-01-29T15:44:21.977527Z","end":"2026-01-29T15:44:22.231916Z","steps":["trace[1607359602] 'range keys from in-memory index tree' (duration: 254.202922ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:22.232115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.934178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2w44m\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-01-29T15:44:22.232142Z","caller":"traceutil/trace.go:171","msg":"trace[1076870063] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2w44m; range_end:; response_count:1; response_revision:540; }","duration":"110.987729ms","start":"2026-01-29T15:44:22.121146Z","end":"2026-01-29T15:44:22.232134Z","steps":["trace[1076870063] 'range keys from in-memory index tree' (duration: 110.865236ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:44:22.625603Z","caller":"traceutil/trace.go:171","msg":"trace[1743236109] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"380.334117ms","start":"2026-01-29T15:44:22.245255Z","end":"2026-01-29T15:44:22.625589Z","steps":["trace[1743236109] 'process raft request' (duration: 380.253295ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:22.625719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:22.245219Z","time spent":"380.428869ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:44:22.719248Z","caller":"traceutil/trace.go:171","msg":"trace[1171437216] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"471.494437ms","start":"2026-01-29T15:44:22.247696Z","end":"2026-01-29T15:44:22.71919Z","steps":["trace[1171437216] 'process raft request' (duration: 470.978266ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:22.719465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:22.247674Z","time spent":"471.671212ms","remote":"127.0.0.1:44204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:44:22.720286Z","caller":"traceutil/trace.go:171","msg":"trace[565919341] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"472.462029ms","start":"2026-01-29T15:44:22.24778Z","end":"2026-01-29T15:44:22.720242Z","steps":["trace[565919341] 'process raft request' (duration: 471.11472ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:22.720532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:22.247775Z","time spent":"472.630713ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:44:22.756485Z","caller":"traceutil/trace.go:171","msg":"trace[1323713988] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:557; }","duration":"256.928663ms","start":"2026-01-29T15:44:22.499537Z","end":"2026-01-29T15:44:22.756466Z","steps":["trace[1323713988] 'read index received' (duration: 126.37148ms)","trace[1323713988] 'applied index is now lower than readState.Index' (duration: 130.555863ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:44:22.756989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.435704ms","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-01-29T15:44:22.757045Z","caller":"traceutil/trace.go:171","msg":"trace[1387484812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:543; }","duration":"257.535656ms","start":"2026-01-29T15:44:22.499498Z","end":"2026-01-29T15:44:22.757033Z","steps":["trace[1387484812] 'agreement among raft nodes before linearized reading' (duration: 257.361392ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:22.757998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.400756ms","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-01-29T15:44:22.758036Z","caller":"traceutil/trace.go:171","msg":"trace[1968332105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:543; }","duration":"258.456727ms","start":"2026-01-29T15:44:22.499569Z","end":"2026-01-29T15:44:22.758026Z","steps":["trace[1968332105] 'agreement among raft nodes before linearized reading' (duration: 258.354395ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:44:25.706078Z","caller":"traceutil/trace.go:171","msg":"trace[352782631] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"528.097342ms","start":"2026-01-29T15:44:25.177956Z","end":"2026-01-29T15:44:25.706053Z","steps":["trace[352782631] 'process raft request' (duration: 525.902063ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:25.706185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:25.177943Z","time spent":"528.193934ms","remote":"127.0.0.1:44084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":743,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T15:44:25.709508Z","caller":"traceutil/trace.go:171","msg":"trace[62385808] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"527.402416ms","start":"2026-01-29T15:44:25.182087Z","end":"2026-01-29T15:44:25.709489Z","steps":["trace[62385808] 'process raft request' (duration: 527.11777ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:25.709602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:25.182077Z","time spent":"527.472817ms","remote":"127.0.0.1:44204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17400,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:44:26.166193Z","caller":"traceutil/trace.go:171","msg":"trace[1447548346] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"112.816289ms","start":"2026-01-29T15:44:26.053361Z","end":"2026-01-29T15:44:26.166177Z","steps":["trace[1447548346] 'process raft request' (duration: 110.189711ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:37.816503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.336383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T15:44:37.816734Z","caller":"traceutil/trace.go:171","msg":"trace[948379238] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:722; }","duration":"1.140132123s","start":"2026-01-29T15:44:36.676592Z","end":"2026-01-29T15:44:37.816724Z","steps":["trace[948379238] 'read index received' (duration: 159.373532ms)","trace[948379238] 'applied index is now lower than readState.Index' (duration: 980.757881ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:44:37.816786Z","caller":"traceutil/trace.go:171","msg":"trace[2105527428] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"1.282875245s","start":"2026-01-29T15:44:36.533904Z","end":"2026-01-29T15:44:37.81678Z","steps":["trace[2105527428] 'process raft request' (duration: 302.102324ms)","trace[2105527428] 'compare' (duration: 980.232249ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:44:37.816816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:36.533895Z","time spent":"1.282902906s","remote":"127.0.0.1:44494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:44:37.816926Z","caller":"traceutil/trace.go:171","msg":"trace[531140368] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"1.178781099s","start":"2026-01-29T15:44:36.638139Z","end":"2026-01-29T15:44:37.81692Z","steps":["trace[531140368] 'process raft request' (duration: 1.178558534s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:37.816952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:36.638122Z","time spent":"1.1788143s","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:44:37.816933Z","caller":"traceutil/trace.go:171","msg":"trace[171809048] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"1.282909216s","start":"2026-01-29T15:44:36.534Z","end":"2026-01-29T15:44:37.81691Z","steps":["trace[171809048] 'process raft request' (duration: 1.282564738s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:37.817051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:36.533997Z","time spent":"1.282998238s","remote":"127.0.0.1:44084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-29T15:44:37.817115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140526171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-29T15:44:37.81713Z","caller":"traceutil/trace.go:171","msg":"trace[1814768390] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:706; }","duration":"1.140573392s","start":"2026-01-29T15:44:36.676552Z","end":"2026-01-29T15:44:37.817125Z","steps":["trace[1814768390] 'agreement among raft nodes before linearized reading' (duration: 1.140459769s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:37.817144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:36.67654Z","time spent":"1.140601293s","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T15:44:37.817246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049997435s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-29T15:44:37.81726Z","caller":"traceutil/trace.go:171","msg":"trace[412226620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:706; }","duration":"1.050032847s","start":"2026-01-29T15:44:36.767222Z","end":"2026-01-29T15:44:37.817255Z","steps":["trace[412226620] 'agreement among raft nodes before linearized reading' (duration: 1.049994786s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:37.817271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:36.767208Z","time spent":"1.050060217s","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-29T15:44:37.817319Z","caller":"traceutil/trace.go:171","msg":"trace[1460998440] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"1.27180739s","start":"2026-01-29T15:44:36.545497Z","end":"2026-01-29T15:44:37.817304Z","steps":["trace[1460998440] 'process raft request' (duration: 1.271120905s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:37.817384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:36.545485Z","time spent":"1.271860952s","remote":"127.0.0.1:44462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4164,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:44:37.817415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.114789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-nprbq\" ","response":"range_response_count:1 size:5026"} {"level":"info","ts":"2026-01-29T15:44:37.817433Z","caller":"traceutil/trace.go:171","msg":"trace[1245898749] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-nprbq; range_end:; response_count:1; response_revision:706; }","duration":"550.15587ms","start":"2026-01-29T15:44:37.267271Z","end":"2026-01-29T15:44:37.817427Z","steps":["trace[1245898749] 'agreement among raft nodes before linearized reading' (duration: 550.090579ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:37.817445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:37.267258Z","time spent":"550.184141ms","remote":"127.0.0.1:44204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5048,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-nprbq\" "} {"level":"warn","ts":"2026-01-29T15:44:37.817544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.299946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-29T15:44:37.817556Z","caller":"traceutil/trace.go:171","msg":"trace[733612680] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:706; }","duration":"760.330897ms","start":"2026-01-29T15:44:37.057221Z","end":"2026-01-29T15:44:37.817552Z","steps":["trace[733612680] 'agreement among raft nodes before linearized reading' (duration: 760.293396ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:37.817567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:37.057206Z","time spent":"760.359087ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"warn","ts":"2026-01-29T15:44:37.817648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.599718ms","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-01-29T15:44:37.8177Z","caller":"traceutil/trace.go:171","msg":"trace[1373408075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:706; }","duration":"962.639129ms","start":"2026-01-29T15:44:36.855019Z","end":"2026-01-29T15:44:37.817658Z","steps":["trace[1373408075] 'agreement among raft nodes before linearized reading' (duration: 962.601128ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:37.817713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:36.855004Z","time spent":"962.70518ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T15:44:37.817845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.486137ms","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-01-29T15:44:37.817869Z","caller":"traceutil/trace.go:171","msg":"trace[204405419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:706; }","duration":"963.526479ms","start":"2026-01-29T15:44:36.854336Z","end":"2026-01-29T15:44:37.817862Z","steps":["trace[204405419] 'agreement among raft nodes before linearized reading' (duration: 963.428717ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:37.817892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:36.854322Z","time spent":"963.565079ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-29T15:44:37.988988Z","caller":"traceutil/trace.go:171","msg":"trace[1357540217] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"160.015187ms","start":"2026-01-29T15:44:37.828958Z","end":"2026-01-29T15:44:37.988973Z","steps":["trace[1357540217] 'process raft request' (duration: 159.968086ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:44:37.989167Z","caller":"traceutil/trace.go:171","msg":"trace[1222569134] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"160.248092ms","start":"2026-01-29T15:44:37.828913Z","end":"2026-01-29T15:44:37.989161Z","steps":["trace[1222569134] 'process raft request' (duration: 95.293052ms)","trace[1222569134] 'compare' (duration: 64.632863ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:44:37.98937Z","caller":"traceutil/trace.go:171","msg":"trace[1961488460] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"157.651905ms","start":"2026-01-29T15:44:37.831713Z","end":"2026-01-29T15:44:37.989364Z","steps":["trace[1961488460] 'process raft request' (duration: 157.238726ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:44:37.989689Z","caller":"traceutil/trace.go:171","msg":"trace[613695199] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"118.864254ms","start":"2026-01-29T15:44:37.870809Z","end":"2026-01-29T15:44:37.989673Z","steps":["trace[613695199] 'process raft request' (duration: 118.822813ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:44:37.989722Z","caller":"traceutil/trace.go:171","msg":"trace[1198359443] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"157.966061ms","start":"2026-01-29T15:44:37.831749Z","end":"2026-01-29T15:44:37.989715Z","steps":["trace[1198359443] 'process raft request' (duration: 157.569293ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:44:37.989838Z","caller":"traceutil/trace.go:171","msg":"trace[266228034] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:727; }","duration":"160.410285ms","start":"2026-01-29T15:44:37.82942Z","end":"2026-01-29T15:44:37.98983Z","steps":["trace[266228034] 'read index received' (duration: 40.776643ms)","trace[266228034] 'applied index is now lower than readState.Index' (duration: 119.632802ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:44:37.989894Z","caller":"traceutil/trace.go:171","msg":"trace[226625877] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"158.113464ms","start":"2026-01-29T15:44:37.831775Z","end":"2026-01-29T15:44:37.989888Z","steps":["trace[226625877] 'process raft request' (duration: 157.738726ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:37.990046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.432371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4179"} {"level":"info","ts":"2026-01-29T15:44:37.990077Z","caller":"traceutil/trace.go:171","msg":"trace[1934480750] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:712; }","duration":"158.480302ms","start":"2026-01-29T15:44:37.831585Z","end":"2026-01-29T15:44:37.990065Z","steps":["trace[1934480750] 'agreement among raft nodes before linearized reading' (duration: 158.37541ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:37.990179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.744042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3845"} {"level":"warn","ts":"2026-01-29T15:44:37.990191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.517561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:44:37.990203Z","caller":"traceutil/trace.go:171","msg":"trace[443190796] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:712; }","duration":"160.794783ms","start":"2026-01-29T15:44:37.829401Z","end":"2026-01-29T15:44:37.990196Z","steps":["trace[443190796] 'agreement among raft nodes before linearized reading' (duration: 160.726751ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:44:37.990215Z","caller":"traceutil/trace.go:171","msg":"trace[1160395139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:712; }","duration":"107.564953ms","start":"2026-01-29T15:44:37.882643Z","end":"2026-01-29T15:44:37.990208Z","steps":["trace[1160395139] 'agreement among raft nodes before linearized reading' (duration: 107.529382ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:44:38.455918Z","caller":"traceutil/trace.go:171","msg":"trace[650508818] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"444.614831ms","start":"2026-01-29T15:44:38.011286Z","end":"2026-01-29T15:44:38.455901Z","steps":["trace[650508818] 'process raft request' (duration: 444.57989ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:38.456024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:38.01128Z","time spent":"444.685722ms","remote":"127.0.0.1:44494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:44:38.456259Z","caller":"traceutil/trace.go:171","msg":"trace[1446702956] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"445.562403ms","start":"2026-01-29T15:44:38.01069Z","end":"2026-01-29T15:44:38.456252Z","steps":["trace[1446702956] 'process raft request' (duration: 445.050601ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:38.456302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:38.010682Z","time spent":"445.597213ms","remote":"127.0.0.1:44186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":901,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:44:38.456513Z","caller":"traceutil/trace.go:171","msg":"trace[1421304591] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"445.767227ms","start":"2026-01-29T15:44:38.010738Z","end":"2026-01-29T15:44:38.456505Z","steps":["trace[1421304591] 'process raft request' (duration: 445.099293ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:38.45655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:38.010734Z","time spent":"445.795668ms","remote":"127.0.0.1:44286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1613,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:44:38.456944Z","caller":"traceutil/trace.go:171","msg":"trace[1475258822] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:737; }","duration":"408.475571ms","start":"2026-01-29T15:44:38.048462Z","end":"2026-01-29T15:44:38.456938Z","steps":["trace[1475258822] 'read index received' (duration: 408.47296ms)","trace[1475258822] 'applied index is now lower than readState.Index' (duration: 2.141µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:44:38.457033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.391771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-29T15:44:38.457051Z","caller":"traceutil/trace.go:171","msg":"trace[1387296746] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:716; }","duration":"446.424002ms","start":"2026-01-29T15:44:38.010622Z","end":"2026-01-29T15:44:38.457046Z","steps":["trace[1387296746] 'agreement among raft nodes before linearized reading' (duration: 446.35189ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:38.457069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:38.010616Z","time spent":"446.448902ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-01-29T15:44:38.457178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.822222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3845"} {"level":"info","ts":"2026-01-29T15:44:38.457194Z","caller":"traceutil/trace.go:171","msg":"trace[950362967] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:716; }","duration":"456.846552ms","start":"2026-01-29T15:44:38.000342Z","end":"2026-01-29T15:44:38.457189Z","steps":["trace[950362967] 'agreement among raft nodes before linearized reading' (duration: 456.798561ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:38.457218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:38.000338Z","time spent":"456.875933ms","remote":"127.0.0.1:44494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3867,"request content":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" "} {"level":"info","ts":"2026-01-29T15:44:38.900954Z","caller":"traceutil/trace.go:171","msg":"trace[1182963425] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"431.45392ms","start":"2026-01-29T15:44:38.469479Z","end":"2026-01-29T15:44:38.900933Z","steps":["trace[1182963425] 'process raft request' (duration: 431.399219ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:38.901079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:38.469472Z","time spent":"431.541912ms","remote":"127.0.0.1:44462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4164,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:44:38.901304Z","caller":"traceutil/trace.go:171","msg":"trace[1488482968] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"628.863244ms","start":"2026-01-29T15:44:38.27243Z","end":"2026-01-29T15:44:38.901293Z","steps":["trace[1488482968] 'process raft request' (duration: 572.466034ms)","trace[1488482968] 'compare' (duration: 55.815647ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:44:38.901374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:38.272415Z","time spent":"628.935665ms","remote":"127.0.0.1:44084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T15:44:38.901503Z","caller":"traceutil/trace.go:171","msg":"trace[983845418] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:737; }","duration":"444.533069ms","start":"2026-01-29T15:44:38.456961Z","end":"2026-01-29T15:44:38.901494Z","steps":["trace[983845418] 'read index received' (duration: 388.004527ms)","trace[983845418] 'applied index is now lower than readState.Index' (duration: 56.527652ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:44:38.901667Z","caller":"traceutil/trace.go:171","msg":"trace[1599995306] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"438.040776ms","start":"2026-01-29T15:44:38.463618Z","end":"2026-01-29T15:44:38.901659Z","steps":["trace[1599995306] 'process raft request' (duration: 437.202248ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:38.901715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:38.463607Z","time spent":"438.080536ms","remote":"127.0.0.1:44494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3754,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:44:38.902061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.807434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-2pzw7\" ","response":"range_response_count:1 size:4821"} {"level":"info","ts":"2026-01-29T15:44:38.902094Z","caller":"traceutil/trace.go:171","msg":"trace[247145234] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-2pzw7; range_end:; response_count:1; response_revision:719; }","duration":"629.875536ms","start":"2026-01-29T15:44:38.27221Z","end":"2026-01-29T15:44:38.902085Z","steps":["trace[247145234] 'agreement among raft nodes before linearized reading' (duration: 629.740992ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:38.902134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:38.272193Z","time spent":"629.932618ms","remote":"127.0.0.1:44204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4843,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-2pzw7\" "} {"level":"warn","ts":"2026-01-29T15:44:38.902302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.934563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-01-29T15:44:38.902329Z","caller":"traceutil/trace.go:171","msg":"trace[1225757985] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:719; }","duration":"441.974793ms","start":"2026-01-29T15:44:38.460346Z","end":"2026-01-29T15:44:38.902321Z","steps":["trace[1225757985] 'agreement among raft nodes before linearized reading' (duration: 441.925872ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:38.902353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:38.460336Z","time spent":"442.011294ms","remote":"127.0.0.1:44286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-01-29T15:44:44.256785Z","caller":"traceutil/trace.go:171","msg":"trace[193039964] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"227.140052ms","start":"2026-01-29T15:44:44.02962Z","end":"2026-01-29T15:44:44.25676Z","steps":["trace[193039964] 'read index received' (duration: 226.911917ms)","trace[193039964] 'applied index is now lower than readState.Index' (duration: 227.265µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:44:44.257107Z","caller":"traceutil/trace.go:171","msg":"trace[1313227923] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"238.784801ms","start":"2026-01-29T15:44:44.018311Z","end":"2026-01-29T15:44:44.257096Z","steps":["trace[1313227923] 'process raft request' (duration: 238.28274ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:44.257355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.714075ms","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-01-29T15:44:44.257387Z","caller":"traceutil/trace.go:171","msg":"trace[731569222] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:733; }","duration":"227.777227ms","start":"2026-01-29T15:44:44.029601Z","end":"2026-01-29T15:44:44.257378Z","steps":["trace[731569222] 'agreement among raft nodes before linearized reading' (duration: 227.655654ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:44.25755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.02619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T15:44:44.257575Z","caller":"traceutil/trace.go:171","msg":"trace[768105182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"227.064391ms","start":"2026-01-29T15:44:44.030504Z","end":"2026-01-29T15:44:44.257568Z","steps":["trace[768105182] 'agreement among raft nodes before linearized reading' (duration: 226.993789ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:44.257779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.399967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-29T15:44:44.257805Z","caller":"traceutil/trace.go:171","msg":"trace[1117480260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:733; }","duration":"194.476099ms","start":"2026-01-29T15:44:44.063321Z","end":"2026-01-29T15:44:44.257797Z","steps":["trace[1117480260] 'agreement among raft nodes before linearized reading' (duration: 194.401057ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:44.257944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.775155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T15:44:44.257968Z","caller":"traceutil/trace.go:171","msg":"trace[400827156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"226.798675ms","start":"2026-01-29T15:44:44.031162Z","end":"2026-01-29T15:44:44.257961Z","steps":["trace[400827156] 'agreement among raft nodes before linearized reading' (duration: 226.733794ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:44.967165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.048155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T15:44:44.967457Z","caller":"traceutil/trace.go:171","msg":"trace[1413233788] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"700.063471ms","start":"2026-01-29T15:44:44.267376Z","end":"2026-01-29T15:44:44.967439Z","steps":["trace[1413233788] 'process raft request' (duration: 699.858177ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:44.967507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:44.267335Z","time spent":"700.147633ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:44:44.967625Z","caller":"traceutil/trace.go:171","msg":"trace[1283418924] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"700.544312ms","start":"2026-01-29T15:44:44.267072Z","end":"2026-01-29T15:44:44.967616Z","steps":["trace[1283418924] 'process raft request' (duration: 451.731019ms)","trace[1283418924] 'compare' (duration: 247.8044ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:44:44.967662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:44.267053Z","time spent":"700.589692ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:44:44.968056Z","caller":"traceutil/trace.go:171","msg":"trace[1244356353] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"700.585252ms","start":"2026-01-29T15:44:44.267463Z","end":"2026-01-29T15:44:44.968048Z","steps":["trace[1244356353] 'process raft request' (duration: 699.804725ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:44.968104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:44.267457Z","time spent":"700.626753ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:44:44.968233Z","caller":"traceutil/trace.go:171","msg":"trace[2037088767] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"700.721265ms","start":"2026-01-29T15:44:44.267504Z","end":"2026-01-29T15:44:44.968225Z","steps":["trace[2037088767] 'process raft request' (duration: 699.796225ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:44:44.96827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:44:44.267497Z","time spent":"700.752627ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:45:01.262997Z","caller":"traceutil/trace.go:171","msg":"trace[1686933282] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"206.681939ms","start":"2026-01-29T15:45:01.056298Z","end":"2026-01-29T15:45:01.26298Z","steps":["trace[1686933282] 'read index received' (duration: 206.505395ms)","trace[1686933282] 'applied index is now lower than readState.Index' (duration: 175.484µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:45:01.263084Z","caller":"traceutil/trace.go:171","msg":"trace[879113954] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"215.696019ms","start":"2026-01-29T15:45:01.047381Z","end":"2026-01-29T15:45:01.263077Z","steps":["trace[879113954] 'process raft request' (duration: 215.488655ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:01.263244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.929755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T15:45:01.263265Z","caller":"traceutil/trace.go:171","msg":"trace[1343895868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:786; }","duration":"206.974695ms","start":"2026-01-29T15:45:01.056283Z","end":"2026-01-29T15:45:01.263258Z","steps":["trace[1343895868] 'agreement among raft nodes before linearized reading' (duration: 206.883403ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:01.263364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.103133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T15:45:01.26338Z","caller":"traceutil/trace.go:171","msg":"trace[1284083973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:786; }","duration":"204.130393ms","start":"2026-01-29T15:45:01.059245Z","end":"2026-01-29T15:45:01.263375Z","steps":["trace[1284083973] 'agreement among raft nodes before linearized reading' (duration: 204.084882ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:01.263512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.211006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-29T15:45:01.263563Z","caller":"traceutil/trace.go:171","msg":"trace[205056706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:786; }","duration":"200.281999ms","start":"2026-01-29T15:45:01.063267Z","end":"2026-01-29T15:45:01.263549Z","steps":["trace[205056706] 'agreement among raft nodes before linearized reading' (duration: 200.149256ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:02.382694Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646841076380024217,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T15:45:02.883618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646841076380024217,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T15:45:02.921188Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.652856243s","expected-duration":"1s"} {"level":"info","ts":"2026-01-29T15:45:02.921497Z","caller":"traceutil/trace.go:171","msg":"trace[1404665715] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"1.65320458s","start":"2026-01-29T15:45:01.268275Z","end":"2026-01-29T15:45:02.921479Z","steps":["trace[1404665715] 'process raft request' (duration: 1.653099398s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:02.921595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:01.26826Z","time spent":"1.653269562s","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:45:03.203613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.975737ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:45:03.203703Z","caller":"traceutil/trace.go:171","msg":"trace[354020509] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:787; }","duration":"221.076629ms","start":"2026-01-29T15:45:02.982614Z","end":"2026-01-29T15:45:03.203691Z","steps":["trace[354020509] 'range keys from in-memory index tree' (duration: 220.965776ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:45:03.205452Z","caller":"traceutil/trace.go:171","msg":"trace[838412726] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:812; }","duration":"1.32371825s","start":"2026-01-29T15:45:01.881724Z","end":"2026-01-29T15:45:03.205442Z","steps":["trace[838412726] 'read index received' (duration: 1.039754548s)","trace[838412726] 'applied index is now lower than readState.Index' (duration: 283.961832ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:45:03.205594Z","caller":"traceutil/trace.go:171","msg":"trace[974781653] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"1.936188881s","start":"2026-01-29T15:45:01.269397Z","end":"2026-01-29T15:45:03.205586Z","steps":["trace[974781653] 'process raft request' (duration: 1.933487081s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:03.205656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:01.26938Z","time spent":"1.936230682s","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:45:03.205769Z","caller":"traceutil/trace.go:171","msg":"trace[378033947] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"1.935651249s","start":"2026-01-29T15:45:01.270112Z","end":"2026-01-29T15:45:03.205763Z","steps":["trace[378033947] 'process raft request' (duration: 1.93385651s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:03.205809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:01.270104Z","time spent":"1.935687919s","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:45:03.205899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.32418032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:45:03.205916Z","caller":"traceutil/trace.go:171","msg":"trace[1346138658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"1.324245022s","start":"2026-01-29T15:45:01.881666Z","end":"2026-01-29T15:45:03.205911Z","steps":["trace[1346138658] 'agreement among raft nodes before linearized reading' (duration: 1.324217411s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:03.205933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:01.881651Z","time spent":"1.324278642s","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T15:45:03.206067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.302169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-29T15:45:03.206084Z","caller":"traceutil/trace.go:171","msg":"trace[1698360874] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:789; }","duration":"836.353501ms","start":"2026-01-29T15:45:02.369725Z","end":"2026-01-29T15:45:03.206079Z","steps":["trace[1698360874] 'agreement among raft nodes before linearized reading' (duration: 836.287849ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:03.206099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:02.369706Z","time spent":"836.389082ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T15:45:03.206265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.243612ms","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-01-29T15:45:03.206284Z","caller":"traceutil/trace.go:171","msg":"trace[465021360] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:789; }","duration":"179.283013ms","start":"2026-01-29T15:45:03.026995Z","end":"2026-01-29T15:45:03.206278Z","steps":["trace[465021360] 'agreement among raft nodes before linearized reading' (duration: 179.253082ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:45:03.416325Z","caller":"traceutil/trace.go:171","msg":"trace[209763619] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"145.502364ms","start":"2026-01-29T15:45:03.270803Z","end":"2026-01-29T15:45:03.416305Z","steps":["trace[209763619] 'process raft request' (duration: 53.646349ms)","trace[209763619] 'compare' (duration: 91.784323ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:45:07.361027Z","caller":"traceutil/trace.go:171","msg":"trace[1718494715] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"301.49924ms","start":"2026-01-29T15:45:07.059502Z","end":"2026-01-29T15:45:07.361001Z","steps":["trace[1718494715] 'read index received' (duration: 301.349097ms)","trace[1718494715] 'applied index is now lower than readState.Index' (duration: 149.053µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:45:07.361167Z","caller":"traceutil/trace.go:171","msg":"trace[492681638] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"425.592559ms","start":"2026-01-29T15:45:06.935562Z","end":"2026-01-29T15:45:07.361154Z","steps":["trace[492681638] 'process raft request' (duration: 425.239971ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:07.361273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:06.935536Z","time spent":"425.659321ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:45:07.361538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.056053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-29T15:45:07.361568Z","caller":"traceutil/trace.go:171","msg":"trace[949284860] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:797; }","duration":"302.112924ms","start":"2026-01-29T15:45:07.059446Z","end":"2026-01-29T15:45:07.361559Z","steps":["trace[949284860] 'agreement among raft nodes before linearized reading' (duration: 301.983932ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:07.361596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:07.059433Z","time spent":"302.157495ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"warn","ts":"2026-01-29T15:45:07.362222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.337779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T15:45:07.362272Z","caller":"traceutil/trace.go:171","msg":"trace[556959853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:797; }","duration":"130.401199ms","start":"2026-01-29T15:45:07.231848Z","end":"2026-01-29T15:45:07.36225Z","steps":["trace[556959853] 'agreement among raft nodes before linearized reading' (duration: 130.295847ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:07.362451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.635442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-29T15:45:07.362485Z","caller":"traceutil/trace.go:171","msg":"trace[1129439394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:797; }","duration":"129.684723ms","start":"2026-01-29T15:45:07.23279Z","end":"2026-01-29T15:45:07.362475Z","steps":["trace[1129439394] 'agreement among raft nodes before linearized reading' (duration: 129.590901ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:07.362503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.590095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-29T15:45:07.362601Z","caller":"traceutil/trace.go:171","msg":"trace[1106934409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:797; }","duration":"112.706458ms","start":"2026-01-29T15:45:07.249876Z","end":"2026-01-29T15:45:07.362582Z","steps":["trace[1106934409] 'agreement among raft nodes before linearized reading' (duration: 112.502483ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:07.883007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.624082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T15:45:07.883103Z","caller":"traceutil/trace.go:171","msg":"trace[899391001] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"514.711614ms","start":"2026-01-29T15:45:07.368372Z","end":"2026-01-29T15:45:07.883084Z","steps":["trace[899391001] 'process raft request' (duration: 143.944289ms)","trace[899391001] 'compare' (duration: 370.53888ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:45:07.883156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:07.368356Z","time spent":"514.776145ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:45:07.97932Z","caller":"traceutil/trace.go:171","msg":"trace[308211730] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:823; }","duration":"554.927295ms","start":"2026-01-29T15:45:07.424376Z","end":"2026-01-29T15:45:07.979304Z","steps":["trace[308211730] 'read index received' (duration: 87.937958ms)","trace[308211730] 'applied index is now lower than readState.Index' (duration: 466.988707ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:45:07.979414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:07.368445Z","time spent":"610.967216ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-29T15:45:07.979756Z","caller":"traceutil/trace.go:171","msg":"trace[870370394] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"608.964523ms","start":"2026-01-29T15:45:07.370784Z","end":"2026-01-29T15:45:07.979748Z","steps":["trace[870370394] 'process raft request' (duration: 608.416441ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:07.979831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:07.370772Z","time spent":"609.005473ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:45:07.979961Z","caller":"traceutil/trace.go:171","msg":"trace[1255435555] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"609.116925ms","start":"2026-01-29T15:45:07.370837Z","end":"2026-01-29T15:45:07.979954Z","steps":["trace[1255435555] 'process raft request' (duration: 608.44069ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:07.979994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:07.370832Z","time spent":"609.144347ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:45:07.980261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.744013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-29T15:45:07.980288Z","caller":"traceutil/trace.go:171","msg":"trace[588504403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:800; }","duration":"555.936887ms","start":"2026-01-29T15:45:07.424342Z","end":"2026-01-29T15:45:07.980279Z","steps":["trace[588504403] 'agreement among raft nodes before linearized reading' (duration: 555.717403ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:07.980309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:07.424325Z","time spent":"555.977599ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T15:45:19.35735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.796898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:45:19.357413Z","caller":"traceutil/trace.go:171","msg":"trace[420526996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"403.91425ms","start":"2026-01-29T15:45:18.953487Z","end":"2026-01-29T15:45:19.357401Z","steps":["trace[420526996] 'range keys from in-memory index tree' (duration: 403.744026ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:19.357471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:18.953474Z","time spent":"403.991922ms","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T15:45:20.530808Z","caller":"traceutil/trace.go:171","msg":"trace[1463017449] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"402.418757ms","start":"2026-01-29T15:45:20.128375Z","end":"2026-01-29T15:45:20.530793Z","steps":["trace[1463017449] 'read index received' (duration: 402.259944ms)","trace[1463017449] 'applied index is now lower than readState.Index' (duration: 158.303µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:45:20.53106Z","caller":"traceutil/trace.go:171","msg":"trace[2056039855] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"567.430607ms","start":"2026-01-29T15:45:19.963622Z","end":"2026-01-29T15:45:20.531052Z","steps":["trace[2056039855] 'process raft request' (duration: 567.050299ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:20.531121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:19.963612Z","time spent":"567.463088ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:45:20.531304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.925099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-29T15:45:20.531356Z","caller":"traceutil/trace.go:171","msg":"trace[592733329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:885; }","duration":"402.99901ms","start":"2026-01-29T15:45:20.128347Z","end":"2026-01-29T15:45:20.531346Z","steps":["trace[592733329] 'agreement among raft nodes before linearized reading' (duration: 402.860427ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:20.531379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:20.128335Z","time spent":"403.0396ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-29T15:45:20.531501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.384553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T15:45:20.531518Z","caller":"traceutil/trace.go:171","msg":"trace[513911381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:885; }","duration":"397.417863ms","start":"2026-01-29T15:45:20.134094Z","end":"2026-01-29T15:45:20.531512Z","steps":["trace[513911381] 'agreement among raft nodes before linearized reading' (duration: 397.369522ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:20.531533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:20.134085Z","time spent":"397.444874ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-29T15:45:20.531684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.100552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T15:45:20.531719Z","caller":"traceutil/trace.go:171","msg":"trace[1254432220] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:885; }","duration":"384.148204ms","start":"2026-01-29T15:45:20.147565Z","end":"2026-01-29T15:45:20.531714Z","steps":["trace[1254432220] 'agreement among raft nodes before linearized reading' (duration: 384.086833ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:20.531737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:20.147559Z","time spent":"384.172914ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-01-29T15:45:20.531892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.01996ms","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-01-29T15:45:20.531909Z","caller":"traceutil/trace.go:171","msg":"trace[88871299] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:885; }","duration":"395.04727ms","start":"2026-01-29T15:45:20.136857Z","end":"2026-01-29T15:45:20.531904Z","steps":["trace[88871299] 'agreement among raft nodes before linearized reading' (duration: 394.995019ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:20.531924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:20.136852Z","time spent":"395.06897ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T15:45:21.693202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.664463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T15:45:21.693263Z","caller":"traceutil/trace.go:171","msg":"trace[672265618] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"1.156884062s","start":"2026-01-29T15:45:20.536368Z","end":"2026-01-29T15:45:21.693253Z","steps":["trace[672265618] 'process raft request' (duration: 842.111726ms)","trace[672265618] 'compare' (duration: 314.525079ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:45:21.693294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:20.536356Z","time spent":"1.156923874s","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:45:22.266101Z","caller":"traceutil/trace.go:171","msg":"trace[1472432960] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:916; }","duration":"1.386702258s","start":"2026-01-29T15:45:20.879385Z","end":"2026-01-29T15:45:22.266087Z","steps":["trace[1472432960] 'read index received' (duration: 499.131703ms)","trace[1472432960] 'applied index is now lower than readState.Index' (duration: 887.570045ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:45:22.266251Z","caller":"traceutil/trace.go:171","msg":"trace[889292229] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"1.726754074s","start":"2026-01-29T15:45:20.539491Z","end":"2026-01-29T15:45:22.266245Z","steps":["trace[889292229] 'process raft request' (duration: 1.726473408s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:22.26632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:20.539484Z","time spent":"1.726782095s","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:45:22.266417Z","caller":"traceutil/trace.go:171","msg":"trace[1192913834] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"1.725586228s","start":"2026-01-29T15:45:20.540827Z","end":"2026-01-29T15:45:22.266413Z","steps":["trace[1192913834] 'process raft request' (duration: 1.72521662s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:22.266452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:20.540821Z","time spent":"1.725606338s","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:45:22.266517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.387138017s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:45:22.266532Z","caller":"traceutil/trace.go:171","msg":"trace[1011662177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"1.387175798s","start":"2026-01-29T15:45:20.879351Z","end":"2026-01-29T15:45:22.266527Z","steps":["trace[1011662177] 'agreement among raft nodes before linearized reading' (duration: 1.387151658s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:22.266545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:20.879336Z","time spent":"1.387206479s","remote":"127.0.0.1:44006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T15:45:22.306362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.818422ms","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-01-29T15:45:22.306405Z","caller":"traceutil/trace.go:171","msg":"trace[273555004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:889; }","duration":"881.903095ms","start":"2026-01-29T15:45:21.424492Z","end":"2026-01-29T15:45:22.306395Z","steps":["trace[273555004] 'agreement among raft nodes before linearized reading' (duration: 881.781722ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:22.306422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:21.424475Z","time spent":"881.943676ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-29T15:45:22.306496Z","caller":"traceutil/trace.go:171","msg":"trace[1273443480] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"849.417341ms","start":"2026-01-29T15:45:21.457074Z","end":"2026-01-29T15:45:22.306491Z","steps":["trace[1273443480] 'process raft request' (duration: 849.086373ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:22.306538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:21.457062Z","time spent":"849.445033ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:45:22.779506Z","caller":"traceutil/trace.go:171","msg":"trace[1155561415] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"241.752755ms","start":"2026-01-29T15:45:22.537736Z","end":"2026-01-29T15:45:22.779489Z","steps":["trace[1155561415] 'process raft request' (duration: 241.661823ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:30.826616Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646841076380024518,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T15:45:30.907515Z","caller":"traceutil/trace.go:171","msg":"trace[282789516] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"607.918762ms","start":"2026-01-29T15:45:30.29958Z","end":"2026-01-29T15:45:30.907499Z","steps":["trace[282789516] 'process raft request' (duration: 607.82139ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:30.907627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:30.299572Z","time spent":"607.998884ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:45:31.294952Z","caller":"traceutil/trace.go:171","msg":"trace[58090018] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"968.347781ms","start":"2026-01-29T15:45:30.32658Z","end":"2026-01-29T15:45:31.294928Z","steps":["trace[58090018] 'read index received' (duration: 580.972364ms)","trace[58090018] 'applied index is now lower than readState.Index' (duration: 387.374717ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:45:31.295022Z","caller":"traceutil/trace.go:171","msg":"trace[738422525] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"985.506239ms","start":"2026-01-29T15:45:30.309511Z","end":"2026-01-29T15:45:31.295017Z","steps":["trace[738422525] 'process raft request' (duration: 982.449359ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:31.295082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:30.309502Z","time spent":"985.533309ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:45:31.295146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.446955ms","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-01-29T15:45:31.295196Z","caller":"traceutil/trace.go:171","msg":"trace[8097215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:911; }","duration":"950.514576ms","start":"2026-01-29T15:45:30.34467Z","end":"2026-01-29T15:45:31.295185Z","steps":["trace[8097215] 'agreement among raft nodes before linearized reading' (duration: 950.400444ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:31.295217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:30.344664Z","time spent":"950.548987ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T15:45:31.29528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.049214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:45:31.2953Z","caller":"traceutil/trace.go:171","msg":"trace[1161606083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"416.091726ms","start":"2026-01-29T15:45:30.879202Z","end":"2026-01-29T15:45:31.295294Z","steps":["trace[1161606083] 'agreement among raft nodes before linearized reading' (duration: 416.059515ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:31.295313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:30.87919Z","time spent":"416.120276ms","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T15:45:31.295338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.665471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-29T15:45:31.295354Z","caller":"traceutil/trace.go:171","msg":"trace[360606141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:911; }","duration":"482.718762ms","start":"2026-01-29T15:45:30.81263Z","end":"2026-01-29T15:45:31.295348Z","steps":["trace[360606141] 'agreement among raft nodes before linearized reading' (duration: 482.678461ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:31.295369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:30.812617Z","time spent":"482.748962ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T15:45:31.295396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.812551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:45:31.295416Z","caller":"traceutil/trace.go:171","msg":"trace[21071762] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:911; }","duration":"968.842401ms","start":"2026-01-29T15:45:30.326564Z","end":"2026-01-29T15:45:31.295406Z","steps":["trace[21071762] 'agreement among raft nodes before linearized reading' (duration: 968.816871ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:31.29543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:30.326551Z","time spent":"968.866922ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-01-29T15:45:32.195265Z","caller":"traceutil/trace.go:171","msg":"trace[1757073595] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"468.866978ms","start":"2026-01-29T15:45:31.726384Z","end":"2026-01-29T15:45:32.195251Z","steps":["trace[1757073595] 'process raft request' (duration: 468.785557ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:32.195357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:31.726375Z","time spent":"468.93475ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:45:35.543298Z","caller":"traceutil/trace.go:171","msg":"trace[2039866278] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"213.509447ms","start":"2026-01-29T15:45:35.329774Z","end":"2026-01-29T15:45:35.543283Z","steps":["trace[2039866278] 'read index received' (duration: 213.402465ms)","trace[2039866278] 'applied index is now lower than readState.Index' (duration: 106.472µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:45:35.543424Z","caller":"traceutil/trace.go:171","msg":"trace[574540078] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"524.322533ms","start":"2026-01-29T15:45:35.019097Z","end":"2026-01-29T15:45:35.543419Z","steps":["trace[574540078] 'process raft request' (duration: 524.120599ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:35.543491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:35.019086Z","time spent":"524.351934ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:45:35.543624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.848765ms","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-01-29T15:45:35.543644Z","caller":"traceutil/trace.go:171","msg":"trace[1037438325] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:921; }","duration":"213.887175ms","start":"2026-01-29T15:45:35.329751Z","end":"2026-01-29T15:45:35.543639Z","steps":["trace[1037438325] 'agreement among raft nodes before linearized reading' (duration: 213.822714ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:35.543725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.887632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-29T15:45:35.543737Z","caller":"traceutil/trace.go:171","msg":"trace[1162131513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:921; }","duration":"158.915722ms","start":"2026-01-29T15:45:35.384818Z","end":"2026-01-29T15:45:35.543733Z","steps":["trace[1162131513] 'agreement among raft nodes before linearized reading' (duration: 158.881582ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:35.543831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.32374ms","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-01-29T15:45:35.543843Z","caller":"traceutil/trace.go:171","msg":"trace[1422389893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"158.34449ms","start":"2026-01-29T15:45:35.385495Z","end":"2026-01-29T15:45:35.543839Z","steps":["trace[1422389893] 'agreement among raft nodes before linearized reading' (duration: 158.311589ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:45:35.676841Z","caller":"traceutil/trace.go:171","msg":"trace[1779513009] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"129.425705ms","start":"2026-01-29T15:45:35.547391Z","end":"2026-01-29T15:45:35.676817Z","steps":["trace[1779513009] 'process raft request' (duration: 129.283792ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:45:35.679427Z","caller":"traceutil/trace.go:171","msg":"trace[2062969062] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"131.881041ms","start":"2026-01-29T15:45:35.547533Z","end":"2026-01-29T15:45:35.679414Z","steps":["trace[2062969062] 'process raft request' (duration: 131.782509ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:45:35.679517Z","caller":"traceutil/trace.go:171","msg":"trace[320136023] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"130.639792ms","start":"2026-01-29T15:45:35.548867Z","end":"2026-01-29T15:45:35.679507Z","steps":["trace[320136023] 'process raft request' (duration: 130.52049ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:36.455631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.159566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:45:36.455726Z","caller":"traceutil/trace.go:171","msg":"trace[500615428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"573.259168ms","start":"2026-01-29T15:45:35.882426Z","end":"2026-01-29T15:45:36.455685Z","steps":["trace[500615428] 'range keys from in-memory index tree' (duration: 573.110675ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:36.455767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:35.882416Z","time spent":"573.34237ms","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T15:45:36.45616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.028494ms","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-01-29T15:45:36.456271Z","caller":"traceutil/trace.go:171","msg":"trace[423277026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:924; }","duration":"251.139817ms","start":"2026-01-29T15:45:36.20507Z","end":"2026-01-29T15:45:36.45621Z","steps":["trace[423277026] 'range keys from in-memory index tree' (duration: 250.881271ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:45:36.590055Z","caller":"traceutil/trace.go:171","msg":"trace[2049285707] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"127.710586ms","start":"2026-01-29T15:45:36.462327Z","end":"2026-01-29T15:45:36.590037Z","steps":["trace[2049285707] 'process raft request' (duration: 127.598274ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:36.922345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.236463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:45:36.922424Z","caller":"traceutil/trace.go:171","msg":"trace[560603947] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:925; }","duration":"191.358525ms","start":"2026-01-29T15:45:36.731048Z","end":"2026-01-29T15:45:36.922406Z","steps":["trace[560603947] 'count revisions from in-memory index tree' (duration: 191.16338ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:37.413011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.016745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T15:45:37.413095Z","caller":"traceutil/trace.go:171","msg":"trace[534352905] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"201.045355ms","start":"2026-01-29T15:45:37.212032Z","end":"2026-01-29T15:45:37.413077Z","steps":["trace[534352905] 'process raft request' (duration: 18.894667ms)","trace[534352905] 'compare' (duration: 181.79577ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:45:37.6544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.832797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T15:45:37.654466Z","caller":"traceutil/trace.go:171","msg":"trace[1482082117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:926; }","duration":"103.9408ms","start":"2026-01-29T15:45:37.55051Z","end":"2026-01-29T15:45:37.654451Z","steps":["trace[1482082117] 'range keys from in-memory index tree' (duration: 103.692174ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:45:37.91725Z","caller":"traceutil/trace.go:171","msg":"trace[14480886] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"237.185402ms","start":"2026-01-29T15:45:37.68004Z","end":"2026-01-29T15:45:37.917225Z","steps":["trace[14480886] 'read index received' (duration: 236.977587ms)","trace[14480886] 'applied index is now lower than readState.Index' (duration: 207.125µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:45:37.917464Z","caller":"traceutil/trace.go:171","msg":"trace[428539467] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"259.525787ms","start":"2026-01-29T15:45:37.657927Z","end":"2026-01-29T15:45:37.917452Z","steps":["trace[428539467] 'process raft request' (duration: 259.115578ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:37.917702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.09403ms","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-01-29T15:45:37.917724Z","caller":"traceutil/trace.go:171","msg":"trace[1604943380] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"234.115942ms","start":"2026-01-29T15:45:37.683601Z","end":"2026-01-29T15:45:37.917717Z","steps":["trace[1604943380] 'agreement among raft nodes before linearized reading' (duration: 234.072761ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:37.917531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.272904ms","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-01-29T15:45:37.917875Z","caller":"traceutil/trace.go:171","msg":"trace[1030060352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"234.646804ms","start":"2026-01-29T15:45:37.683221Z","end":"2026-01-29T15:45:37.917867Z","steps":["trace[1030060352] 'agreement among raft nodes before linearized reading' (duration: 234.242195ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:37.917571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.53265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-29T15:45:37.91793Z","caller":"traceutil/trace.go:171","msg":"trace[628132885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:927; }","duration":"237.926568ms","start":"2026-01-29T15:45:37.679999Z","end":"2026-01-29T15:45:37.917926Z","steps":["trace[628132885] 'agreement among raft nodes before linearized reading' (duration: 237.547789ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:38.537866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.310003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T15:45:38.537934Z","caller":"traceutil/trace.go:171","msg":"trace[1550140206] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"614.061692ms","start":"2026-01-29T15:45:37.923861Z","end":"2026-01-29T15:45:38.537923Z","steps":["trace[1550140206] 'read index received' (duration: 212.630516ms)","trace[1550140206] 'applied index is now lower than readState.Index' (duration: 401.430396ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:45:38.537993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.130073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:45:38.538008Z","caller":"traceutil/trace.go:171","msg":"trace[1866767515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"614.165224ms","start":"2026-01-29T15:45:37.923837Z","end":"2026-01-29T15:45:38.538002Z","steps":["trace[1866767515] 'agreement among raft nodes before linearized reading' (duration: 614.115283ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:38.538028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:37.923826Z","time spent":"614.196455ms","remote":"127.0.0.1:44006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T15:45:38.538316Z","caller":"traceutil/trace.go:171","msg":"trace[1130915426] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"616.336293ms","start":"2026-01-29T15:45:37.921957Z","end":"2026-01-29T15:45:38.538294Z","steps":["trace[1130915426] 'process raft request' (duration: 214.525049ms)","trace[1130915426] 'compare' (duration: 401.230501ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:45:38.538438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:37.921942Z","time spent":"616.435605ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:45:38.543615Z","caller":"traceutil/trace.go:171","msg":"trace[1217190582] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"617.319315ms","start":"2026-01-29T15:45:37.926286Z","end":"2026-01-29T15:45:38.543605Z","steps":["trace[1217190582] 'process raft request' (duration: 617.292404ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:38.54367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:37.926278Z","time spent":"617.365106ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:45:38.543851Z","caller":"traceutil/trace.go:171","msg":"trace[347703116] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"619.030133ms","start":"2026-01-29T15:45:37.924814Z","end":"2026-01-29T15:45:38.543845Z","steps":["trace[347703116] 'process raft request' (duration: 618.687426ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:38.543885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:37.924806Z","time spent":"619.060404ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:45:39.126782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.65364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T15:45:39.126848Z","caller":"traceutil/trace.go:171","msg":"trace[606905667] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"528.510467ms","start":"2026-01-29T15:45:38.598326Z","end":"2026-01-29T15:45:39.126837Z","steps":["trace[606905667] 'process raft request' (duration: 165.748275ms)","trace[606905667] 'compare' (duration: 362.585258ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:45:39.126877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:38.598315Z","time spent":"528.549269ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:45:40.072566Z","caller":"traceutil/trace.go:171","msg":"trace[1523874343] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"144.016755ms","start":"2026-01-29T15:45:39.928523Z","end":"2026-01-29T15:45:40.07254Z","steps":["trace[1523874343] 'process raft request' (duration: 143.841161ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:45:55.449577Z","caller":"traceutil/trace.go:171","msg":"trace[1504376104] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"150.195124ms","start":"2026-01-29T15:45:55.299356Z","end":"2026-01-29T15:45:55.449551Z","steps":["trace[1504376104] 'process raft request' (duration: 150.05185ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:55.803292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.271027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-kjjwk\" ","response":"range_response_count:1 size:12337"} {"level":"info","ts":"2026-01-29T15:45:55.803361Z","caller":"traceutil/trace.go:171","msg":"trace[644961138] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-kjjwk; range_end:; response_count:1; response_revision:1034; }","duration":"204.371989ms","start":"2026-01-29T15:45:55.598969Z","end":"2026-01-29T15:45:55.803341Z","steps":["trace[644961138] 'range keys from in-memory index tree' (duration: 204.117233ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:55.803698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.500007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:45:55.80373Z","caller":"traceutil/trace.go:171","msg":"trace[216544546] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1034; }","duration":"114.562769ms","start":"2026-01-29T15:45:55.689158Z","end":"2026-01-29T15:45:55.80372Z","steps":["trace[216544546] 'count revisions from in-memory index tree' (duration: 114.442896ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:56.719084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646841076380024786,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T15:45:56.785028Z","caller":"traceutil/trace.go:171","msg":"trace[1210449832] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"566.059006ms","start":"2026-01-29T15:45:56.218947Z","end":"2026-01-29T15:45:56.785006Z","steps":["trace[1210449832] 'read index received' (duration: 565.772679ms)","trace[1210449832] 'applied index is now lower than readState.Index' (duration: 285.527µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:45:56.785236Z","caller":"traceutil/trace.go:171","msg":"trace[551778341] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"969.644707ms","start":"2026-01-29T15:45:55.815568Z","end":"2026-01-29T15:45:56.785213Z","steps":["trace[551778341] 'process raft request' (duration: 969.213858ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:56.785352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:55.815549Z","time spent":"969.718529ms","remote":"127.0.0.1:44204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13254,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:45:56.78546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.06975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-29T15:45:56.785524Z","caller":"traceutil/trace.go:171","msg":"trace[2013922858] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1035; }","duration":"180.173383ms","start":"2026-01-29T15:45:56.605333Z","end":"2026-01-29T15:45:56.785506Z","steps":["trace[2013922858] 'agreement among raft nodes before linearized reading' (duration: 180.020669ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:56.785741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.250309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-01-29T15:45:56.785757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.110107ms","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-01-29T15:45:56.785769Z","caller":"traceutil/trace.go:171","msg":"trace[805470468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1035; }","duration":"159.30063ms","start":"2026-01-29T15:45:56.62646Z","end":"2026-01-29T15:45:56.785761Z","steps":["trace[805470468] 'agreement among raft nodes before linearized reading' (duration: 159.223919ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:45:56.785793Z","caller":"traceutil/trace.go:171","msg":"trace[782792450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1035; }","duration":"144.181349ms","start":"2026-01-29T15:45:56.641602Z","end":"2026-01-29T15:45:56.785783Z","steps":["trace[782792450] 'agreement among raft nodes before linearized reading' (duration: 144.059346ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:56.785921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.973197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T15:45:56.78595Z","caller":"traceutil/trace.go:171","msg":"trace[1510904459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1035; }","duration":"567.034168ms","start":"2026-01-29T15:45:56.218908Z","end":"2026-01-29T15:45:56.785942Z","steps":["trace[1510904459] 'agreement among raft nodes before linearized reading' (duration: 566.957817ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:56.785973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:56.21889Z","time spent":"567.077829ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-29T15:45:56.94184Z","caller":"traceutil/trace.go:171","msg":"trace[1219159740] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"143.32139ms","start":"2026-01-29T15:45:56.798499Z","end":"2026-01-29T15:45:56.941821Z","steps":["trace[1219159740] 'process raft request' (duration: 133.243492ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:45:57.126621Z","caller":"traceutil/trace.go:171","msg":"trace[581511647] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"325.884257ms","start":"2026-01-29T15:45:56.80072Z","end":"2026-01-29T15:45:57.126604Z","steps":["trace[581511647] 'process raft request' (duration: 291.593622ms)","trace[581511647] 'compare' (duration: 34.170072ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:45:57.126729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:56.800701Z","time spent":"325.967728ms","remote":"127.0.0.1:44286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1524,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:45:57.126944Z","caller":"traceutil/trace.go:171","msg":"trace[1815206396] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1073; }","duration":"244.936866ms","start":"2026-01-29T15:45:56.882Z","end":"2026-01-29T15:45:57.126937Z","steps":["trace[1815206396] 'read index received' (duration: 49.774385ms)","trace[1815206396] 'applied index is now lower than readState.Index' (duration: 195.161871ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:45:57.127113Z","caller":"traceutil/trace.go:171","msg":"trace[1738480024] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"326.318716ms","start":"2026-01-29T15:45:56.800787Z","end":"2026-01-29T15:45:57.127106Z","steps":["trace[1738480024] 'process raft request' (duration: 325.791444ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:57.127172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:56.800783Z","time spent":"326.349657ms","remote":"127.0.0.1:44494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10772,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:45:57.127334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.323755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-29T15:45:57.12741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.820943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-01-29T15:45:57.127436Z","caller":"traceutil/trace.go:171","msg":"trace[517342164] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1042; }","duration":"140.874014ms","start":"2026-01-29T15:45:56.986555Z","end":"2026-01-29T15:45:57.127429Z","steps":["trace[517342164] 'agreement among raft nodes before linearized reading' (duration: 140.798903ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:45:57.12742Z","caller":"traceutil/trace.go:171","msg":"trace[456461155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"245.409907ms","start":"2026-01-29T15:45:56.881996Z","end":"2026-01-29T15:45:57.127406Z","steps":["trace[456461155] 'agreement among raft nodes before linearized reading' (duration: 245.300984ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:45:57.127711Z","caller":"traceutil/trace.go:171","msg":"trace[1989858690] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"326.203744ms","start":"2026-01-29T15:45:56.801494Z","end":"2026-01-29T15:45:57.127698Z","steps":["trace[1989858690] 'process raft request' (duration: 325.333064ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:57.127823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:56.801491Z","time spent":"326.291686ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:45:57.128134Z","caller":"traceutil/trace.go:171","msg":"trace[1053201838] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"326.669735ms","start":"2026-01-29T15:45:56.801457Z","end":"2026-01-29T15:45:57.128127Z","steps":["trace[1053201838] 'process raft request' (duration: 325.458447ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:57.128192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:56.801448Z","time spent":"326.711365ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:45:57.128244Z","caller":"traceutil/trace.go:171","msg":"trace[904495288] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"326.696335ms","start":"2026-01-29T15:45:56.801542Z","end":"2026-01-29T15:45:57.128238Z","steps":["trace[904495288] 'process raft request' (duration: 325.319614ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:57.128279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:56.801538Z","time spent":"326.722486ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:45:57.12832Z","caller":"traceutil/trace.go:171","msg":"trace[1189130170] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"326.744897ms","start":"2026-01-29T15:45:56.801571Z","end":"2026-01-29T15:45:57.128316Z","steps":["trace[1189130170] 'process raft request' (duration: 325.322994ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:45:57.128354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:45:56.801568Z","time spent":"326.769047ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:47:15.853771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.03914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T15:47:15.859384Z","caller":"traceutil/trace.go:171","msg":"trace[1320030324] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1417; }","duration":"169.904411ms","start":"2026-01-29T15:47:15.683986Z","end":"2026-01-29T15:47:15.853891Z","steps":["trace[1320030324] 'read index received' (duration: 47.553204ms)","trace[1320030324] 'applied index is now lower than readState.Index' (duration: 122.349637ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:47:15.859653Z","caller":"traceutil/trace.go:171","msg":"trace[1078195588] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"286.942219ms","start":"2026-01-29T15:47:15.572687Z","end":"2026-01-29T15:47:15.859629Z","steps":["trace[1078195588] 'process raft request' (duration: 158.924296ms)","trace[1078195588] 'compare' (duration: 121.795374ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:47:15.860597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.121884ms","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-01-29T15:47:15.860758Z","caller":"traceutil/trace.go:171","msg":"trace[1381082986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1364; }","duration":"173.317718ms","start":"2026-01-29T15:47:15.687418Z","end":"2026-01-29T15:47:15.860736Z","steps":["trace[1381082986] 'agreement among raft nodes before linearized reading' (duration: 172.652702ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:47:15.860672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.679343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-29T15:47:15.861089Z","caller":"traceutil/trace.go:171","msg":"trace[413820364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1364; }","duration":"177.158963ms","start":"2026-01-29T15:47:15.683923Z","end":"2026-01-29T15:47:15.861082Z","steps":["trace[413820364] 'agreement among raft nodes before linearized reading' (duration: 176.614711ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:47:15.862284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.552034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-01-29T15:47:15.862384Z","caller":"traceutil/trace.go:171","msg":"trace[1928877337] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1364; }","duration":"165.693768ms","start":"2026-01-29T15:47:15.696631Z","end":"2026-01-29T15:47:15.862324Z","steps":["trace[1928877337] 'agreement among raft nodes before linearized reading' (duration: 165.594185ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:47:16.126754Z","caller":"traceutil/trace.go:171","msg":"trace[1882837882] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"172.97805ms","start":"2026-01-29T15:47:15.953747Z","end":"2026-01-29T15:47:16.126725Z","steps":["trace[1882837882] 'process raft request' (duration: 80.4772ms)","trace[1882837882] 'compare' (duration: 92.391497ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:47:17.255609Z","caller":"traceutil/trace.go:171","msg":"trace[358297987] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"133.463316ms","start":"2026-01-29T15:47:17.122102Z","end":"2026-01-29T15:47:17.255566Z","steps":["trace[358297987] 'process raft request' (duration: 78.990377ms)","trace[358297987] 'compare' (duration: 54.297436ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:47:17.457442Z","caller":"traceutil/trace.go:171","msg":"trace[2093262962] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"132.656859ms","start":"2026-01-29T15:47:17.32475Z","end":"2026-01-29T15:47:17.457406Z","steps":["trace[2093262962] 'process raft request' (duration: 86.706931ms)","trace[2093262962] 'compare' (duration: 45.658632ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:47:18.069649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.426823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-29T15:47:18.069771Z","caller":"traceutil/trace.go:171","msg":"trace[1790741936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1373; }","duration":"115.611616ms","start":"2026-01-29T15:47:17.954134Z","end":"2026-01-29T15:47:18.069745Z","steps":["trace[1790741936] 'range keys from in-memory index tree' (duration: 115.007023ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:47:18.070298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.878944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-01-29T15:47:18.070361Z","caller":"traceutil/trace.go:171","msg":"trace[1040223318] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1373; }","duration":"133.968986ms","start":"2026-01-29T15:47:17.936373Z","end":"2026-01-29T15:47:18.070342Z","steps":["trace[1040223318] 'range keys from in-memory index tree' (duration: 133.752231ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:47:18.070385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.058639ms","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-01-29T15:47:18.070427Z","caller":"traceutil/trace.go:171","msg":"trace[1930735197] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1373; }","duration":"134.148882ms","start":"2026-01-29T15:47:17.936268Z","end":"2026-01-29T15:47:18.070417Z","steps":["trace[1930735197] 'range keys from in-memory index tree' (duration: 133.886105ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:47:18.186142Z","caller":"traceutil/trace.go:171","msg":"trace[1568725600] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"112.17616ms","start":"2026-01-29T15:47:18.073933Z","end":"2026-01-29T15:47:18.186109Z","steps":["trace[1568725600] 'process raft request' (duration: 111.980896ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:47:18.350286Z","caller":"traceutil/trace.go:171","msg":"trace[1910075994] linearizableReadLoop","detail":"{readStateIndex:1431; appliedIndex:1428; }","duration":"219.262776ms","start":"2026-01-29T15:47:18.130989Z","end":"2026-01-29T15:47:18.350252Z","steps":["trace[1910075994] 'read index received' (duration: 54.877268ms)","trace[1910075994] 'applied index is now lower than readState.Index' (duration: 164.384058ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:47:18.35064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.622915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-29T15:47:18.350681Z","caller":"traceutil/trace.go:171","msg":"trace[1651784017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1376; }","duration":"219.718816ms","start":"2026-01-29T15:47:18.13095Z","end":"2026-01-29T15:47:18.350669Z","steps":["trace[1651784017] 'agreement among raft nodes before linearized reading' (duration: 219.425719ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:47:18.351033Z","caller":"traceutil/trace.go:171","msg":"trace[84726746] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"272.825395ms","start":"2026-01-29T15:47:18.078193Z","end":"2026-01-29T15:47:18.351018Z","steps":["trace[84726746] 'process raft request' (duration: 271.920574ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:47:18.351098Z","caller":"traceutil/trace.go:171","msg":"trace[1730435073] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"275.675749ms","start":"2026-01-29T15:47:18.075385Z","end":"2026-01-29T15:47:18.351061Z","steps":["trace[1730435073] 'process raft request' (duration: 219.481362ms)","trace[1730435073] 'compare' (duration: 55.03533ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:47:18.698989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.743158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T15:47:18.699291Z","caller":"traceutil/trace.go:171","msg":"trace[181671877] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"344.094979ms","start":"2026-01-29T15:47:18.355175Z","end":"2026-01-29T15:47:18.69927Z","steps":["trace[181671877] 'process raft request' (duration: 148.987333ms)","trace[181671877] 'compare' (duration: 194.659526ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:47:18.699578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:47:18.355154Z","time spent":"344.390415ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:47:20.215385Z","caller":"traceutil/trace.go:171","msg":"trace[1991021260] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"114.240907ms","start":"2026-01-29T15:47:20.101126Z","end":"2026-01-29T15:47:20.215367Z","steps":["trace[1991021260] 'process raft request' (duration: 113.93181ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:47:27.721567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.837912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-01-29T15:47:27.721647Z","caller":"traceutil/trace.go:171","msg":"trace[1424910846] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1411; }","duration":"102.972135ms","start":"2026-01-29T15:47:27.618657Z","end":"2026-01-29T15:47:27.72163Z","steps":["trace[1424910846] 'range keys from in-memory index tree' (duration: 102.632788ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:47:30.435237Z","caller":"traceutil/trace.go:171","msg":"trace[1105619374] linearizableReadLoop","detail":"{readStateIndex:1479; appliedIndex:1478; }","duration":"131.505251ms","start":"2026-01-29T15:47:30.30371Z","end":"2026-01-29T15:47:30.435215Z","steps":["trace[1105619374] 'read index received' (duration: 131.349047ms)","trace[1105619374] 'applied index is now lower than readState.Index' (duration: 155.644µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:47:30.435433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.711126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-29T15:47:30.435459Z","caller":"traceutil/trace.go:171","msg":"trace[1529252631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1422; }","duration":"131.775507ms","start":"2026-01-29T15:47:30.303675Z","end":"2026-01-29T15:47:30.435451Z","steps":["trace[1529252631] 'agreement among raft nodes before linearized reading' (duration: 131.632894ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:47:30.43572Z","caller":"traceutil/trace.go:171","msg":"trace[1385785945] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"149.140075ms","start":"2026-01-29T15:47:30.28657Z","end":"2026-01-29T15:47:30.43571Z","steps":["trace[1385785945] 'process raft request' (duration: 148.54121ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:48:27.454128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.256058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363638"} {"level":"info","ts":"2026-01-29T15:48:27.454218Z","caller":"traceutil/trace.go:171","msg":"trace[1495883291] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1656; }","duration":"102.397242ms","start":"2026-01-29T15:48:27.351805Z","end":"2026-01-29T15:48:27.454202Z","steps":["trace[1495883291] 'range keys from in-memory index tree' (duration: 101.705706ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:49:35.3736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.707962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T15:49:35.374263Z","caller":"traceutil/trace.go:171","msg":"trace[723192163] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"146.680764ms","start":"2026-01-29T15:49:35.227Z","end":"2026-01-29T15:49:35.373681Z","steps":["trace[723192163] 'process raft request' (duration: 45.804059ms)","trace[723192163] 'compare' (duration: 100.61811ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:49:35.596354Z","caller":"traceutil/trace.go:171","msg":"trace[647168690] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"367.143893ms","start":"2026-01-29T15:49:35.229186Z","end":"2026-01-29T15:49:35.59633Z","steps":["trace[647168690] 'process raft request' (duration: 367.095122ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:49:35.596524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:49:35.229182Z","time spent":"367.248615ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:49:35.597262Z","caller":"traceutil/trace.go:171","msg":"trace[680745548] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"368.104644ms","start":"2026-01-29T15:49:35.229142Z","end":"2026-01-29T15:49:35.597247Z","steps":["trace[680745548] 'process raft request' (duration: 335.003747ms)","trace[680745548] 'compare' (duration: 31.711537ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:49:35.597335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:49:35.229134Z","time spent":"368.165855ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}