{"level":"warn","ts":"2026-01-31T16:12:33.395161Z","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-31T16:12:33.395247Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.250:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.250:2380","--initial-cluster=instance=https://162.253.55.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.250: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-31T16:12:33.395466Z","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-31T16:12:33.395482Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.250:2380"]} {"level":"info","ts":"2026-01-31T16:12:33.395558Z","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-31T16:12:33.396299Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.250:2379"]} {"level":"info","ts":"2026-01-31T16:12:33.396777Z","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://162.253.55.250:2380"],"listen-peer-urls":["https://162.253.55.250:2380"],"advertise-client-urls":["https://162.253.55.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.250: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-31T16:12:33.40588Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.83144ms"} {"level":"info","ts":"2026-01-31T16:12:33.427923Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1e4a0bccff66c64d","cluster-id":"8ba64f6e83da771"} {"level":"info","ts":"2026-01-31T16:12:33.428028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d switched to configuration voters=()"} {"level":"info","ts":"2026-01-31T16:12:33.428081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became follower at term 0"} {"level":"info","ts":"2026-01-31T16:12:33.428108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1e4a0bccff66c64d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-31T16:12:33.428128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became follower at term 1"} {"level":"info","ts":"2026-01-31T16:12:33.428199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d switched to configuration voters=(2182569944500586061)"} {"level":"warn","ts":"2026-01-31T16:12:33.440527Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-31T16:12:33.446006Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-31T16:12:33.447935Z","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-31T16:12:33.452478Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1e4a0bccff66c64d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-31T16:12:33.456191Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1e4a0bccff66c64d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-31T16:12:33.456545Z","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-31T16:12:33.45658Z","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-31T16:12:33.456649Z","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-31T16:12:33.458094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d switched to configuration voters=(2182569944500586061)"} {"level":"info","ts":"2026-01-31T16:12:33.461491Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ba64f6e83da771","local-member-id":"1e4a0bccff66c64d","added-peer-id":"1e4a0bccff66c64d","added-peer-peer-urls":["https://162.253.55.250:2380"]} {"level":"info","ts":"2026-01-31T16:12:33.463071Z","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-31T16:12:33.463525Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1e4a0bccff66c64d","initial-advertise-peer-urls":["https://162.253.55.250:2380"],"listen-peer-urls":["https://162.253.55.250:2380"],"advertise-client-urls":["https://162.253.55.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-31T16:12:33.463552Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-31T16:12:33.464051Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.250:2380"} {"level":"info","ts":"2026-01-31T16:12:33.466791Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.250:2380"} {"level":"info","ts":"2026-01-31T16:12:34.229875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d is starting a new election at term 1"} {"level":"info","ts":"2026-01-31T16:12:34.229994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became pre-candidate at term 1"} {"level":"info","ts":"2026-01-31T16:12:34.230019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d received MsgPreVoteResp from 1e4a0bccff66c64d at term 1"} {"level":"info","ts":"2026-01-31T16:12:34.23004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became candidate at term 2"} {"level":"info","ts":"2026-01-31T16:12:34.23008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d received MsgVoteResp from 1e4a0bccff66c64d at term 2"} {"level":"info","ts":"2026-01-31T16:12:34.230096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became leader at term 2"} {"level":"info","ts":"2026-01-31T16:12:34.230104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1e4a0bccff66c64d elected leader 1e4a0bccff66c64d at term 2"} {"level":"info","ts":"2026-01-31T16:12:34.232946Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T16:12:34.234499Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1e4a0bccff66c64d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.250:2379]}","request-path":"/0/members/1e4a0bccff66c64d/attributes","cluster-id":"8ba64f6e83da771","publish-timeout":"7s"} {"level":"info","ts":"2026-01-31T16:12:34.235198Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T16:12:34.235518Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T16:12:34.235568Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ba64f6e83da771","local-member-id":"1e4a0bccff66c64d","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T16:12:34.235645Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T16:12:34.235681Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T16:12:34.237026Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.250:2379"} {"level":"info","ts":"2026-01-31T16:12:34.243051Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-31T16:12:34.25014Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-31T16:12:34.25016Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-31T16:13:01.491956Z","caller":"traceutil/trace.go:171","msg":"trace[605215244] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"456.82836ms","start":"2026-01-31T16:13:01.035101Z","end":"2026-01-31T16:13:01.49193Z","steps":["trace[605215244] 'process raft request' (duration: 456.674456ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:01.492612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:01.035085Z","time spent":"456.972123ms","remote":"127.0.0.1:58456","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-31T16:13:03.713843Z","caller":"traceutil/trace.go:171","msg":"trace[735657278] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"215.407076ms","start":"2026-01-31T16:13:03.498416Z","end":"2026-01-31T16:13:03.713823Z","steps":["trace[735657278] 'process raft request' (duration: 215.287974ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:06.229861Z","caller":"traceutil/trace.go:171","msg":"trace[539260509] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"510.660283ms","start":"2026-01-31T16:13:05.719183Z","end":"2026-01-31T16:13:06.229844Z","steps":["trace[539260509] 'process raft request' (duration: 510.53673ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:06.230007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:05.719175Z","time spent":"510.756835ms","remote":"127.0.0.1:58456","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-31T16:13:06.230008Z","caller":"traceutil/trace.go:171","msg":"trace[760156514] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:535; }","duration":"441.705623ms","start":"2026-01-31T16:13:05.788211Z","end":"2026-01-31T16:13:06.229917Z","steps":["trace[760156514] 'read index received' (duration: 441.698963ms)","trace[760156514] 'applied index is now lower than readState.Index' (duration: 5.31µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T16:13:06.230246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.02561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T16:13:06.230355Z","caller":"traceutil/trace.go:171","msg":"trace[1391029032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"442.128493ms","start":"2026-01-31T16:13:05.788174Z","end":"2026-01-31T16:13:06.230302Z","steps":["trace[1391029032] 'agreement among raft nodes before linearized reading' (duration: 441.960089ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:06.230434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:05.78816Z","time spent":"442.265356ms","remote":"127.0.0.1:58172","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-31T16:13:06.251418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.498074ms","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-31T16:13:06.251456Z","caller":"traceutil/trace.go:171","msg":"trace[2000516652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:519; }","duration":"316.569755ms","start":"2026-01-31T16:13:05.934876Z","end":"2026-01-31T16:13:06.251446Z","steps":["trace[2000516652] 'agreement among raft nodes before linearized reading' (duration: 316.434902ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:06.251485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:05.934857Z","time spent":"316.621297ms","remote":"127.0.0.1:58456","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-31T16:13:06.251596Z","caller":"traceutil/trace.go:171","msg":"trace[995554684] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"134.001931ms","start":"2026-01-31T16:13:06.117586Z","end":"2026-01-31T16:13:06.251588Z","steps":["trace[995554684] 'process raft request' (duration: 133.607052ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:06.251642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.307567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-01-31T16:13:06.251681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.687325ms","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-31T16:13:06.2517Z","caller":"traceutil/trace.go:171","msg":"trace[275180034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:519; }","duration":"315.732887ms","start":"2026-01-31T16:13:05.935962Z","end":"2026-01-31T16:13:06.251695Z","steps":["trace[275180034] 'agreement among raft nodes before linearized reading' (duration: 315.687155ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:06.251713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:05.935948Z","time spent":"315.761687ms","remote":"127.0.0.1:58456","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-31T16:13:06.251688Z","caller":"traceutil/trace.go:171","msg":"trace[1400041486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"377.46149ms","start":"2026-01-31T16:13:05.874216Z","end":"2026-01-31T16:13:06.251678Z","steps":["trace[1400041486] 'agreement among raft nodes before linearized reading' (duration: 377.196264ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:06.251773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:05.874204Z","time spent":"377.560863ms","remote":"127.0.0.1:58456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-31T16:13:06.397583Z","caller":"traceutil/trace.go:171","msg":"trace[1081004418] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"137.053611ms","start":"2026-01-31T16:13:06.260506Z","end":"2026-01-31T16:13:06.397559Z","steps":["trace[1081004418] 'process raft request' (duration: 137.01017ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:06.397956Z","caller":"traceutil/trace.go:171","msg":"trace[1670171202] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"137.481061ms","start":"2026-01-31T16:13:06.260462Z","end":"2026-01-31T16:13:06.397943Z","steps":["trace[1670171202] 'process raft request' (duration: 136.921128ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:11.15013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.940683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T16:13:11.150211Z","caller":"traceutil/trace.go:171","msg":"trace[388651987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"365.065776ms","start":"2026-01-31T16:13:10.785127Z","end":"2026-01-31T16:13:11.150193Z","steps":["trace[388651987] 'range keys from in-memory index tree' (duration: 364.886052ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:11.150333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:10.785115Z","time spent":"365.20473ms","remote":"127.0.0.1:58188","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-31T16:13:11.150413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.144794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pxcmg\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-01-31T16:13:11.150486Z","caller":"traceutil/trace.go:171","msg":"trace[948125705] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pxcmg; range_end:; response_count:1; response_revision:535; }","duration":"189.264597ms","start":"2026-01-31T16:13:10.961202Z","end":"2026-01-31T16:13:11.150466Z","steps":["trace[948125705] 'range keys from in-memory index tree' (duration: 188.97522ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:11.332872Z","caller":"traceutil/trace.go:171","msg":"trace[1067578342] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"168.025871ms","start":"2026-01-31T16:13:11.164822Z","end":"2026-01-31T16:13:11.332847Z","steps":["trace[1067578342] 'process raft request' (duration: 167.875756ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:13.526671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.506289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-01-31T16:13:13.526746Z","caller":"traceutil/trace.go:171","msg":"trace[1291620455] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:550; }","duration":"119.624002ms","start":"2026-01-31T16:13:13.407105Z","end":"2026-01-31T16:13:13.526729Z","steps":["trace[1291620455] 'range keys from in-memory index tree' (duration: 119.420157ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:16.319987Z","caller":"traceutil/trace.go:171","msg":"trace[1094959416] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"127.400029ms","start":"2026-01-31T16:13:16.19173Z","end":"2026-01-31T16:13:16.31913Z","steps":["trace[1094959416] 'process raft request' (duration: 122.274572ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:24.575682Z","caller":"traceutil/trace.go:171","msg":"trace[629583764] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"193.429303ms","start":"2026-01-31T16:13:24.382235Z","end":"2026-01-31T16:13:24.575664Z","steps":["trace[629583764] 'read index received' (duration: 193.30341ms)","trace[629583764] 'applied index is now lower than readState.Index' (duration: 125.273µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T16:13:24.57578Z","caller":"traceutil/trace.go:171","msg":"trace[92546177] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"508.472203ms","start":"2026-01-31T16:13:24.0673Z","end":"2026-01-31T16:13:24.575773Z","steps":["trace[92546177] 'process raft request' (duration: 508.255758ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:24.575895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:24.067289Z","time spent":"508.510174ms","remote":"127.0.0.1:58396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5011,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T16:13:24.576048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.813151ms","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-31T16:13:24.576079Z","caller":"traceutil/trace.go:171","msg":"trace[818677243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:685; }","duration":"193.867773ms","start":"2026-01-31T16:13:24.382193Z","end":"2026-01-31T16:13:24.576061Z","steps":["trace[818677243] 'agreement among raft nodes before linearized reading' (duration: 193.783401ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:24.57618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.290434ms","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-31T16:13:24.576236Z","caller":"traceutil/trace.go:171","msg":"trace[25869800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:685; }","duration":"143.365765ms","start":"2026-01-31T16:13:24.432848Z","end":"2026-01-31T16:13:24.576214Z","steps":["trace[25869800] 'agreement among raft nodes before linearized reading' (duration: 143.252012ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:24.768819Z","caller":"traceutil/trace.go:171","msg":"trace[1430148324] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"186.622167ms","start":"2026-01-31T16:13:24.582177Z","end":"2026-01-31T16:13:24.768799Z","steps":["trace[1430148324] 'process raft request' (duration: 186.454723ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:24.768845Z","caller":"traceutil/trace.go:171","msg":"trace[139732002] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"178.830608ms","start":"2026-01-31T16:13:24.589992Z","end":"2026-01-31T16:13:24.768823Z","steps":["trace[139732002] 'process raft request' (duration: 178.808688ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:24.76917Z","caller":"traceutil/trace.go:171","msg":"trace[434983165] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"186.930294ms","start":"2026-01-31T16:13:24.582221Z","end":"2026-01-31T16:13:24.769151Z","steps":["trace[434983165] 'process raft request' (duration: 186.500954ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:24.769237Z","caller":"traceutil/trace.go:171","msg":"trace[802954954] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"180.492977ms","start":"2026-01-31T16:13:24.588737Z","end":"2026-01-31T16:13:24.76923Z","steps":["trace[802954954] 'process raft request' (duration: 180.010956ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:24.769303Z","caller":"traceutil/trace.go:171","msg":"trace[1459929823] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"180.514997ms","start":"2026-01-31T16:13:24.588784Z","end":"2026-01-31T16:13:24.769299Z","steps":["trace[1459929823] 'process raft request' (duration: 179.982245ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:24.774751Z","caller":"traceutil/trace.go:171","msg":"trace[1387078330] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"146.490817ms","start":"2026-01-31T16:13:24.628251Z","end":"2026-01-31T16:13:24.774741Z","steps":["trace[1387078330] 'process raft request' (duration: 146.427155ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:27.192225Z","caller":"traceutil/trace.go:171","msg":"trace[1785737976] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"416.003175ms","start":"2026-01-31T16:13:26.776204Z","end":"2026-01-31T16:13:27.192207Z","steps":["trace[1785737976] 'process raft request' (duration: 415.846421ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:27.192336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:26.776196Z","time spent":"416.080166ms","remote":"127.0.0.1:58456","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-31T16:13:27.211061Z","caller":"traceutil/trace.go:171","msg":"trace[435536587] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"433.958265ms","start":"2026-01-31T16:13:26.777088Z","end":"2026-01-31T16:13:27.211046Z","steps":["trace[435536587] 'process raft request' (duration: 433.77103ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:27.211158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:26.777083Z","time spent":"434.033877ms","remote":"127.0.0.1:58456","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-31T16:13:27.211167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.221309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T16:13:27.211211Z","caller":"traceutil/trace.go:171","msg":"trace[2055781328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"426.29161ms","start":"2026-01-31T16:13:26.784907Z","end":"2026-01-31T16:13:27.211198Z","steps":["trace[2055781328] 'agreement among raft nodes before linearized reading' (duration: 426.188288ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:27.211247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:26.784887Z","time spent":"426.351482ms","remote":"127.0.0.1:58172","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-31T16:13:27.211047Z","caller":"traceutil/trace.go:171","msg":"trace[1219145640] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:741; }","duration":"426.105146ms","start":"2026-01-31T16:13:26.784918Z","end":"2026-01-31T16:13:27.211023Z","steps":["trace[1219145640] 'read index received' (duration: 407.743915ms)","trace[1219145640] 'applied index is now lower than readState.Index' (duration: 18.360211ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T16:13:27.21147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.099511ms","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-31T16:13:27.211494Z","caller":"traceutil/trace.go:171","msg":"trace[1449040618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:720; }","duration":"404.134402ms","start":"2026-01-31T16:13:26.807352Z","end":"2026-01-31T16:13:27.211487Z","steps":["trace[1449040618] 'agreement among raft nodes before linearized reading' (duration: 404.051501ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:27.211492Z","caller":"traceutil/trace.go:171","msg":"trace[1198544724] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"433.616577ms","start":"2026-01-31T16:13:26.777864Z","end":"2026-01-31T16:13:27.211481Z","steps":["trace[1198544724] 'process raft request' (duration: 433.117636ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:27.211513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:26.807346Z","time spent":"404.163323ms","remote":"127.0.0.1:58456","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":"warn","ts":"2026-01-31T16:13:27.211575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:26.777842Z","time spent":"433.676259ms","remote":"127.0.0.1:58456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T16:13:27.211646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.996971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-31T16:13:27.211669Z","caller":"traceutil/trace.go:171","msg":"trace[48519714] range","detail":"{range_begin:/registry/masterleases/162.253.55.250; range_end:; response_count:1; response_revision:720; }","duration":"103.051282ms","start":"2026-01-31T16:13:27.10861Z","end":"2026-01-31T16:13:27.211662Z","steps":["trace[48519714] 'agreement among raft nodes before linearized reading' (duration: 102.997571ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:27.448665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.61846ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-01-31T16:13:27.448812Z","caller":"traceutil/trace.go:171","msg":"trace[1848552308] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"231.014703ms","start":"2026-01-31T16:13:27.217787Z","end":"2026-01-31T16:13:27.448802Z","steps":["trace[1848552308] 'process raft request' (duration: 230.946172ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:27.44893Z","caller":"traceutil/trace.go:171","msg":"trace[2050197642] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"231.30829ms","start":"2026-01-31T16:13:27.217584Z","end":"2026-01-31T16:13:27.448892Z","steps":["trace[2050197642] 'read index received' (duration: 22.385913ms)","trace[2050197642] 'applied index is now lower than readState.Index' (duration: 208.920127ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T16:13:27.449046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.442483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T16:13:27.449067Z","caller":"traceutil/trace.go:171","msg":"trace[1229285438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"231.505605ms","start":"2026-01-31T16:13:27.217555Z","end":"2026-01-31T16:13:27.449061Z","steps":["trace[1229285438] 'agreement among raft nodes before linearized reading' (duration: 231.417773ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:32.179779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.567385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T16:13:32.179896Z","caller":"traceutil/trace.go:171","msg":"trace[1626820517] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:755; }","duration":"720.755617ms","start":"2026-01-31T16:13:31.459128Z","end":"2026-01-31T16:13:32.179884Z","steps":["trace[1626820517] 'read index received' (duration: 451.999258ms)","trace[1626820517] 'applied index is now lower than readState.Index' (duration: 268.755469ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T16:13:32.180054Z","caller":"traceutil/trace.go:171","msg":"trace[156197278] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"948.078046ms","start":"2026-01-31T16:13:31.231969Z","end":"2026-01-31T16:13:32.180047Z","steps":["trace[156197278] 'process raft request' (duration: 679.171714ms)","trace[156197278] 'compare' (duration: 268.217067ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T16:13:32.180094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:31.231958Z","time spent":"948.115618ms","remote":"127.0.0.1:58456","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-31T16:13:32.180198Z","caller":"traceutil/trace.go:171","msg":"trace[586228557] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"947.998575ms","start":"2026-01-31T16:13:31.232193Z","end":"2026-01-31T16:13:32.180192Z","steps":["trace[586228557] 'process raft request' (duration: 947.659117ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:32.180226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:31.232187Z","time spent":"948.023115ms","remote":"127.0.0.1:58456","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-31T16:13:32.180348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.219947ms","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-31T16:13:32.180367Z","caller":"traceutil/trace.go:171","msg":"trace[1693941507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"721.268969ms","start":"2026-01-31T16:13:31.459091Z","end":"2026-01-31T16:13:32.18036Z","steps":["trace[1693941507] 'agreement among raft nodes before linearized reading' (duration: 721.198357ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:32.180384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:31.459072Z","time spent":"721.3078ms","remote":"127.0.0.1:58456","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":"warn","ts":"2026-01-31T16:13:32.180483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.150002ms","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-31T16:13:32.180499Z","caller":"traceutil/trace.go:171","msg":"trace[457816115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:733; }","duration":"497.191894ms","start":"2026-01-31T16:13:31.683302Z","end":"2026-01-31T16:13:32.180494Z","steps":["trace[457816115] 'agreement among raft nodes before linearized reading' (duration: 497.147093ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:32.180514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:31.683287Z","time spent":"497.223174ms","remote":"127.0.0.1:58456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-31T16:13:33.48123Z","caller":"traceutil/trace.go:171","msg":"trace[939757358] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"245.6703ms","start":"2026-01-31T16:13:33.235541Z","end":"2026-01-31T16:13:33.481211Z","steps":["trace[939757358] 'process raft request' (duration: 245.546797ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:50.332978Z","caller":"traceutil/trace.go:171","msg":"trace[508566530] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"161.911673ms","start":"2026-01-31T16:13:50.171049Z","end":"2026-01-31T16:13:50.33296Z","steps":["trace[508566530] 'process raft request' (duration: 161.639087ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:50.498916Z","caller":"traceutil/trace.go:171","msg":"trace[1444480670] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"149.155486ms","start":"2026-01-31T16:13:50.349741Z","end":"2026-01-31T16:13:50.498896Z","steps":["trace[1444480670] 'read index received' (duration: 122.652554ms)","trace[1444480670] 'applied index is now lower than readState.Index' (duration: 26.502072ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T16:13:50.499011Z","caller":"traceutil/trace.go:171","msg":"trace[1711636459] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"162.16065ms","start":"2026-01-31T16:13:50.33684Z","end":"2026-01-31T16:13:50.499Z","steps":["trace[1711636459] 'process raft request' (duration: 135.587807ms)","trace[1711636459] 'compare' (duration: 26.368758ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T16:13:50.499219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.504567ms","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-31T16:13:50.499281Z","caller":"traceutil/trace.go:171","msg":"trace[1129344909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:789; }","duration":"144.585308ms","start":"2026-01-31T16:13:50.354679Z","end":"2026-01-31T16:13:50.499264Z","steps":["trace[1129344909] 'agreement among raft nodes before linearized reading' (duration: 144.421034ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:50.499361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.602996ms","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-31T16:13:50.499399Z","caller":"traceutil/trace.go:171","msg":"trace[152000582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"149.674648ms","start":"2026-01-31T16:13:50.349714Z","end":"2026-01-31T16:13:50.499389Z","steps":["trace[152000582] 'agreement among raft nodes before linearized reading' (duration: 149.550835ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:50.499485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.559813ms","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-31T16:13:50.499515Z","caller":"traceutil/trace.go:171","msg":"trace[614917828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"143.588284ms","start":"2026-01-31T16:13:50.355918Z","end":"2026-01-31T16:13:50.499506Z","steps":["trace[614917828] 'agreement among raft nodes before linearized reading' (duration: 143.508272ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:52.802824Z","caller":"traceutil/trace.go:171","msg":"trace[779760653] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"294.961241ms","start":"2026-01-31T16:13:52.507793Z","end":"2026-01-31T16:13:52.802754Z","steps":["trace[779760653] 'process raft request' (duration: 294.842989ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:53.231166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.5129ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-31T16:13:53.231278Z","caller":"traceutil/trace.go:171","msg":"trace[261420868] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"672.391444ms","start":"2026-01-31T16:13:52.558865Z","end":"2026-01-31T16:13:53.231256Z","steps":["trace[261420868] 'read index received' (duration: 243.926365ms)","trace[261420868] 'applied index is now lower than readState.Index' (duration: 428.463709ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T16:13:53.231492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.62495ms","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-31T16:13:53.231592Z","caller":"traceutil/trace.go:171","msg":"trace[2115481523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:794; }","duration":"672.745112ms","start":"2026-01-31T16:13:52.558829Z","end":"2026-01-31T16:13:53.231574Z","steps":["trace[2115481523] 'agreement among raft nodes before linearized reading' (duration: 672.523477ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:53.231617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.324855ms","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-31T16:13:53.231637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:52.558822Z","time spent":"672.800403ms","remote":"127.0.0.1:58456","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-31T16:13:53.231651Z","caller":"traceutil/trace.go:171","msg":"trace[957124235] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:794; }","duration":"667.383587ms","start":"2026-01-31T16:13:52.564256Z","end":"2026-01-31T16:13:53.23164Z","steps":["trace[957124235] 'agreement among raft nodes before linearized reading' (duration: 667.305125ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:53.231693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:52.56424Z","time spent":"667.444548ms","remote":"127.0.0.1:58456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-31T16:13:53.231529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.312275ms","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-31T16:13:53.231812Z","caller":"traceutil/trace.go:171","msg":"trace[396171268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"667.623073ms","start":"2026-01-31T16:13:52.564181Z","end":"2026-01-31T16:13:53.231804Z","steps":["trace[396171268] 'agreement among raft nodes before linearized reading' (duration: 667.191303ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:53.231839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:52.564175Z","time spent":"667.657933ms","remote":"127.0.0.1:58456","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-31T16:13:53.371362Z","caller":"traceutil/trace.go:171","msg":"trace[785793557] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"130.255762ms","start":"2026-01-31T16:13:53.241083Z","end":"2026-01-31T16:13:53.371339Z","steps":["trace[785793557] 'process raft request' (duration: 130.208761ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:53.372006Z","caller":"traceutil/trace.go:171","msg":"trace[1968806536] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"132.163536ms","start":"2026-01-31T16:13:53.239827Z","end":"2026-01-31T16:13:53.371991Z","steps":["trace[1968806536] 'process raft request' (duration: 112.423084ms)","trace[1968806536] 'compare' (duration: 18.861452ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T16:14:03.72983Z","caller":"traceutil/trace.go:171","msg":"trace[1747952280] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"306.669146ms","start":"2026-01-31T16:14:03.423138Z","end":"2026-01-31T16:14:03.729807Z","steps":["trace[1747952280] 'process raft request' (duration: 306.526792ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:03.729986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:14:03.423122Z","time spent":"306.778108ms","remote":"127.0.0.1:58456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T16:14:03.753857Z","caller":"traceutil/trace.go:171","msg":"trace[544495463] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:909; }","duration":"103.560977ms","start":"2026-01-31T16:14:03.650283Z","end":"2026-01-31T16:14:03.753844Z","steps":["trace[544495463] 'read index received' (duration: 80.16486ms)","trace[544495463] 'applied index is now lower than readState.Index' (duration: 23.395527ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T16:14:03.753991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.69203ms","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-31T16:14:03.754009Z","caller":"traceutil/trace.go:171","msg":"trace[1990164385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:876; }","duration":"103.746581ms","start":"2026-01-31T16:14:03.650257Z","end":"2026-01-31T16:14:03.754003Z","steps":["trace[1990164385] 'agreement among raft nodes before linearized reading' (duration: 103.641739ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:14:03.754196Z","caller":"traceutil/trace.go:171","msg":"trace[1709590480] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"327.591796ms","start":"2026-01-31T16:14:03.426585Z","end":"2026-01-31T16:14:03.754177Z","steps":["trace[1709590480] 'process raft request' (duration: 327.152926ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:03.754252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:14:03.426571Z","time spent":"327.637417ms","remote":"127.0.0.1:58456","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-31T16:14:05.073746Z","caller":"traceutil/trace.go:171","msg":"trace[1007808299] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"160.866559ms","start":"2026-01-31T16:14:04.912864Z","end":"2026-01-31T16:14:05.073731Z","steps":["trace[1007808299] 'process raft request' (duration: 160.786837ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:14:05.76465Z","caller":"traceutil/trace.go:171","msg":"trace[1595979313] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"271.678236ms","start":"2026-01-31T16:14:05.492959Z","end":"2026-01-31T16:14:05.764637Z","steps":["trace[1595979313] 'process raft request' (duration: 271.40593ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:06.268113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.76603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T16:14:06.268228Z","caller":"traceutil/trace.go:171","msg":"trace[1624096686] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"496.175385ms","start":"2026-01-31T16:14:05.772036Z","end":"2026-01-31T16:14:06.268211Z","steps":["trace[1624096686] 'process raft request' (duration: 238.239802ms)","trace[1624096686] 'compare' (duration: 257.594606ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T16:14:06.268287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:14:05.772028Z","time spent":"496.230098ms","remote":"127.0.0.1:58456","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-31T16:14:06.284815Z","caller":"traceutil/trace.go:171","msg":"trace[967867239] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:913; }","duration":"500.897457ms","start":"2026-01-31T16:14:05.783903Z","end":"2026-01-31T16:14:06.284801Z","steps":["trace[967867239] 'read index received' (duration: 226.379595ms)","trace[967867239] 'applied index is now lower than readState.Index' (duration: 274.515392ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T16:14:06.284907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.988779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T16:14:06.28493Z","caller":"traceutil/trace.go:171","msg":"trace[1774094244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"501.03515ms","start":"2026-01-31T16:14:05.783888Z","end":"2026-01-31T16:14:06.284923Z","steps":["trace[1774094244] 'agreement among raft nodes before linearized reading' (duration: 500.965708ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:06.284949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:14:05.783883Z","time spent":"501.06234ms","remote":"127.0.0.1:58172","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-31T16:14:06.285109Z","caller":"traceutil/trace.go:171","msg":"trace[44793832] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"512.99818ms","start":"2026-01-31T16:14:05.772106Z","end":"2026-01-31T16:14:06.285104Z","steps":["trace[44793832] 'process raft request' (duration: 512.624331ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:06.285161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:14:05.772102Z","time spent":"513.02411ms","remote":"127.0.0.1:58456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T16:14:06.28522Z","caller":"traceutil/trace.go:171","msg":"trace[222779304] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"513.081531ms","start":"2026-01-31T16:14:05.772134Z","end":"2026-01-31T16:14:06.285216Z","steps":["trace[222779304] 'process raft request' (duration: 512.645901ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:06.285244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:14:05.772131Z","time spent":"513.098572ms","remote":"127.0.0.1:58456","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-31T16:14:09.333465Z","caller":"traceutil/trace.go:171","msg":"trace[762351779] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"178.943133ms","start":"2026-01-31T16:14:09.154504Z","end":"2026-01-31T16:14:09.333447Z","steps":["trace[762351779] 'process raft request' (duration: 178.835141ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:14:16.209992Z","caller":"traceutil/trace.go:171","msg":"trace[1236913805] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"400.437242ms","start":"2026-01-31T16:14:15.809539Z","end":"2026-01-31T16:14:16.209976Z","steps":["trace[1236913805] 'process raft request' (duration: 400.34658ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:16.210091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:14:15.809531Z","time spent":"400.512944ms","remote":"127.0.0.1:58456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T16:14:16.532031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.295842ms","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-31T16:14:16.532094Z","caller":"traceutil/trace.go:171","msg":"trace[769489064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:907; }","duration":"162.397885ms","start":"2026-01-31T16:14:16.369684Z","end":"2026-01-31T16:14:16.532082Z","steps":["trace[769489064] 'range keys from in-memory index tree' (duration: 162.170289ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:16.532238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.234721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-01-31T16:14:16.53232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.568323ms","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-31T16:14:16.532321Z","caller":"traceutil/trace.go:171","msg":"trace[1723388868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:907; }","duration":"159.338013ms","start":"2026-01-31T16:14:16.372966Z","end":"2026-01-31T16:14:16.532304Z","steps":["trace[1723388868] 'range keys from in-memory index tree' (duration: 159.116078ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:14:16.532339Z","caller":"traceutil/trace.go:171","msg":"trace[1505140698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:907; }","duration":"166.608434ms","start":"2026-01-31T16:14:16.365725Z","end":"2026-01-31T16:14:16.532334Z","steps":["trace[1505140698] 'range keys from in-memory index tree' (duration: 166.507022ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:17.030385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.406046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T16:14:17.030478Z","caller":"traceutil/trace.go:171","msg":"trace[1722765558] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:910; }","duration":"212.540419ms","start":"2026-01-31T16:14:16.817915Z","end":"2026-01-31T16:14:17.030456Z","steps":["trace[1722765558] 'count revisions from in-memory index tree' (duration: 212.323144ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:14:20.375832Z","caller":"traceutil/trace.go:171","msg":"trace[165680658] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"116.762846ms","start":"2026-01-31T16:14:20.259053Z","end":"2026-01-31T16:14:20.375816Z","steps":["trace[165680658] 'process raft request' (duration: 116.678974ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:20.665895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.994481ms","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-31T16:14:20.665942Z","caller":"traceutil/trace.go:171","msg":"trace[2028836987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:919; }","duration":"117.071292ms","start":"2026-01-31T16:14:20.548859Z","end":"2026-01-31T16:14:20.66593Z","steps":["trace[2028836987] 'range keys from in-memory index tree' (duration: 116.895358ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:20.666088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.261667ms","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-31T16:14:20.666106Z","caller":"traceutil/trace.go:171","msg":"trace[1039419179] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:919; }","duration":"114.288887ms","start":"2026-01-31T16:14:20.551812Z","end":"2026-01-31T16:14:20.666101Z","steps":["trace[1039419179] 'range keys from in-memory index tree' (duration: 114.220616ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:20.666751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.629068ms","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-31T16:14:20.666796Z","caller":"traceutil/trace.go:171","msg":"trace[2092520802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:919; }","duration":"106.68088ms","start":"2026-01-31T16:14:20.560107Z","end":"2026-01-31T16:14:20.666788Z","steps":["trace[2092520802] 'range keys from in-memory index tree' (duration: 106.582076ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:14:21.682226Z","caller":"traceutil/trace.go:171","msg":"trace[1207369056] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"183.832508ms","start":"2026-01-31T16:14:21.498379Z","end":"2026-01-31T16:14:21.682211Z","steps":["trace[1207369056] 'read index received' (duration: 183.704325ms)","trace[1207369056] 'applied index is now lower than readState.Index' (duration: 127.643µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T16:14:21.682315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.92297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T16:14:21.682332Z","caller":"traceutil/trace.go:171","msg":"trace[1565929350] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:924; }","duration":"183.981471ms","start":"2026-01-31T16:14:21.498345Z","end":"2026-01-31T16:14:21.682326Z","steps":["trace[1565929350] 'agreement among raft nodes before linearized reading' (duration: 183.921779ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:14:21.682537Z","caller":"traceutil/trace.go:171","msg":"trace[48041680] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"263.164207ms","start":"2026-01-31T16:14:21.419368Z","end":"2026-01-31T16:14:21.682532Z","steps":["trace[48041680] 'process raft request' (duration: 262.755907ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:14:26.486014Z","caller":"traceutil/trace.go:171","msg":"trace[1329360943] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"131.331138ms","start":"2026-01-31T16:14:26.354664Z","end":"2026-01-31T16:14:26.485995Z","steps":["trace[1329360943] 'process raft request' (duration: 130.904648ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:14:38.727496Z","caller":"traceutil/trace.go:171","msg":"trace[706806359] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"109.213339ms","start":"2026-01-31T16:14:38.618254Z","end":"2026-01-31T16:14:38.727468Z","steps":["trace[706806359] 'process raft request' (duration: 108.801568ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:14:58.994652Z","caller":"traceutil/trace.go:171","msg":"trace[1305679731] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"128.603268ms","start":"2026-01-31T16:14:58.866026Z","end":"2026-01-31T16:14:58.994629Z","steps":["trace[1305679731] 'process raft request' (duration: 86.212791ms)","trace[1305679731] 'compare' (duration: 42.256004ms)"],"step_count":2}