{"level":"warn","ts":"2026-05-07T19:15:50.837241Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T19:15:50.837315Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-05-07T19:15:50.837372Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T19:15:50.837381Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-05-07T19:15:50.837399Z","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-05-07T19:15:50.837988Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-05-07T19:15:50.838054Z","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":8,"max-cpu-available":8,"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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-05-07T19:15:50.842697Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.472362ms"} {"level":"info","ts":"2026-05-07T19:15:50.849639Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-05-07T19:15:50.849726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T19:15:50.849751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-05-07T19:15:50.849769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T19:15:50.849792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-05-07T19:15:50.849842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-05-07T19:15:50.859301Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T19:15:50.860724Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T19:15:50.861727Z","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-05-07T19:15:50.86388Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T19:15:50.864037Z","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-05-07T19:15:50.864451Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T19:15:50.864408Z","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-05-07T19:15:50.864747Z","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-05-07T19:15:50.865917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-05-07T19:15:50.865996Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-05-07T19:15:50.867887Z","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-05-07T19:15:50.868072Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T19:15:50.868096Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T19:15:50.868178Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-05-07T19:15:50.868189Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-05-07T19:15:51.150556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T19:15:51.150629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T19:15:51.150664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-05-07T19:15:51.150685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-05-07T19:15:51.150697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-05-07T19:15:51.150714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-05-07T19:15:51.150742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-05-07T19:15:51.151828Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T19:15:51.15204Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T19:15:51.152156Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T19:15:51.152298Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T19:15:51.153289Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T19:15:51.156227Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T19:15:51.157536Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T19:15:51.165926Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T19:15:51.166101Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T19:15:51.166248Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T19:15:51.168536Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-05-07T19:16:20.330426Z","caller":"traceutil/trace.go:171","msg":"trace[793674697] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"101.449956ms","start":"2026-05-07T19:16:20.228954Z","end":"2026-05-07T19:16:20.330404Z","steps":["trace[793674697] 'read index received' (duration: 97.267791ms)","trace[793674697] 'applied index is now lower than readState.Index' (duration: 4.181335ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T19:16:20.33065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.675181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10078"} {"level":"info","ts":"2026-05-07T19:16:20.330688Z","caller":"traceutil/trace.go:171","msg":"trace[876323026] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:527; }","duration":"101.752693ms","start":"2026-05-07T19:16:20.228925Z","end":"2026-05-07T19:16:20.330678Z","steps":["trace[876323026] 'agreement among raft nodes before linearized reading' (duration: 101.565729ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:16:20.330803Z","caller":"traceutil/trace.go:171","msg":"trace[1206928312] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"101.847935ms","start":"2026-05-07T19:16:20.228919Z","end":"2026-05-07T19:16:20.330767Z","steps":["trace[1206928312] 'process raft request' (duration: 97.355313ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:16:21.110736Z","caller":"traceutil/trace.go:171","msg":"trace[1219391848] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"544.841134ms","start":"2026-05-07T19:16:20.565869Z","end":"2026-05-07T19:16:21.11071Z","steps":["trace[1219391848] 'process raft request' (duration: 544.6679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:21.111481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:20.565852Z","time spent":"544.963827ms","remote":"127.0.0.1:52234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T19:16:21.128862Z","caller":"traceutil/trace.go:171","msg":"trace[757289775] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:571; }","duration":"426.996671ms","start":"2026-05-07T19:16:20.701846Z","end":"2026-05-07T19:16:21.128843Z","steps":["trace[757289775] 'read index received' (duration: 409.414453ms)","trace[757289775] 'applied index is now lower than readState.Index' (duration: 17.581498ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T19:16:21.129261Z","caller":"traceutil/trace.go:171","msg":"trace[916888866] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"550.704961ms","start":"2026-05-07T19:16:20.578548Z","end":"2026-05-07T19:16:21.129253Z","steps":["trace[916888866] 'process raft request' (duration: 548.446865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:21.129513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.18539ms","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-05-07T19:16:21.129553Z","caller":"traceutil/trace.go:171","msg":"trace[1133529577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:552; }","duration":"228.255282ms","start":"2026-05-07T19:16:20.901287Z","end":"2026-05-07T19:16:21.129543Z","steps":["trace[1133529577] 'agreement among raft nodes before linearized reading' (duration: 228.18114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:21.129613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:20.578525Z","time spent":"550.766892ms","remote":"127.0.0.1:52234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T19:16:21.12969Z","caller":"traceutil/trace.go:171","msg":"trace[816587688] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"545.275994ms","start":"2026-05-07T19:16:20.584405Z","end":"2026-05-07T19:16:21.129681Z","steps":["trace[816587688] 'process raft request' (duration: 544.318461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:21.129749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:20.584377Z","time spent":"545.333876ms","remote":"127.0.0.1:52234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T19:16:21.129928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.086187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T19:16:21.129958Z","caller":"traceutil/trace.go:171","msg":"trace[1739467072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:552; }","duration":"428.154239ms","start":"2026-05-07T19:16:20.701795Z","end":"2026-05-07T19:16:21.129949Z","steps":["trace[1739467072] 'agreement among raft nodes before linearized reading' (duration: 428.101928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:21.129978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:20.701774Z","time spent":"428.199971ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T19:16:24.098569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.830934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T19:16:24.098664Z","caller":"traceutil/trace.go:171","msg":"trace[992986806] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"469.54187ms","start":"2026-05-07T19:16:23.629108Z","end":"2026-05-07T19:16:24.098649Z","steps":["trace[992986806] 'read index received' (duration: 124.519371ms)","trace[992986806] 'applied index is now lower than readState.Index' (duration: 345.021199ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T19:16:24.098809Z","caller":"traceutil/trace.go:171","msg":"trace[769880803] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"729.741156ms","start":"2026-05-07T19:16:23.369039Z","end":"2026-05-07T19:16:24.09878Z","steps":["trace[769880803] 'process raft request' (duration: 384.508482ms)","trace[769880803] 'compare' (duration: 344.593398ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T19:16:24.098988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.873698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T19:16:24.099071Z","caller":"traceutil/trace.go:171","msg":"trace[1699930622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"469.997221ms","start":"2026-05-07T19:16:23.629057Z","end":"2026-05-07T19:16:24.099055Z","steps":["trace[1699930622] 'agreement among raft nodes before linearized reading' (duration: 469.760695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:24.098934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:23.368952Z","time spent":"729.921841ms","remote":"127.0.0.1:52278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T19:16:24.09912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:23.629038Z","time spent":"470.073982ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T19:16:24.110945Z","caller":"traceutil/trace.go:171","msg":"trace[1323541969] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"357.994042ms","start":"2026-05-07T19:16:23.752914Z","end":"2026-05-07T19:16:24.110908Z","steps":["trace[1323541969] 'process raft request' (duration: 357.218193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:24.111076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.713232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T19:16:24.111106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:23.752889Z","time spent":"358.152686ms","remote":"127.0.0.1:52266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T19:16:24.111158Z","caller":"traceutil/trace.go:171","msg":"trace[1503131213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"138.832215ms","start":"2026-05-07T19:16:23.972304Z","end":"2026-05-07T19:16:24.111136Z","steps":["trace[1503131213] 'agreement among raft nodes before linearized reading' (duration: 138.62731ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:16:25.304195Z","caller":"traceutil/trace.go:171","msg":"trace[538557133] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:619; }","duration":"136.484677ms","start":"2026-05-07T19:16:25.167637Z","end":"2026-05-07T19:16:25.304122Z","steps":["trace[538557133] 'read index received' (duration: 129.402581ms)","trace[538557133] 'applied index is now lower than readState.Index' (duration: 7.080906ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T19:16:25.304329Z","caller":"traceutil/trace.go:171","msg":"trace[1172509445] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"147.303687ms","start":"2026-05-07T19:16:25.157004Z","end":"2026-05-07T19:16:25.304308Z","steps":["trace[1172509445] 'process raft request' (duration: 147.054891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:25.304514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.852396ms","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-05-07T19:16:25.3046Z","caller":"traceutil/trace.go:171","msg":"trace[151217526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"136.937989ms","start":"2026-05-07T19:16:25.167617Z","end":"2026-05-07T19:16:25.304555Z","steps":["trace[151217526] 'agreement among raft nodes before linearized reading' (duration: 136.701523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:25.304596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.059663ms","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-05-07T19:16:25.304619Z","caller":"traceutil/trace.go:171","msg":"trace[1449463335] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"149.013909ms","start":"2026-05-07T19:16:25.155594Z","end":"2026-05-07T19:16:25.304608Z","steps":["trace[1449463335] 'process raft request' (duration: 141.433591ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:16:25.304658Z","caller":"traceutil/trace.go:171","msg":"trace[700961231] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"123.174255ms","start":"2026-05-07T19:16:25.181465Z","end":"2026-05-07T19:16:25.304639Z","steps":["trace[700961231] 'agreement among raft nodes before linearized reading' (duration: 122.98574ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:16:27.987735Z","caller":"traceutil/trace.go:171","msg":"trace[1773650194] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"319.3444ms","start":"2026-05-07T19:16:27.66836Z","end":"2026-05-07T19:16:27.987705Z","steps":["trace[1773650194] 'process raft request' (duration: 318.787166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:27.987918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:27.668329Z","time spent":"319.502204ms","remote":"127.0.0.1:52266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T19:16:28.27208Z","caller":"traceutil/trace.go:171","msg":"trace[1925048033] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"145.438941ms","start":"2026-05-07T19:16:28.126589Z","end":"2026-05-07T19:16:28.272028Z","steps":["trace[1925048033] 'read index received' (duration: 90.620565ms)","trace[1925048033] 'applied index is now lower than readState.Index' (duration: 54.816266ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T19:16:28.272289Z","caller":"traceutil/trace.go:171","msg":"trace[2091973943] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"231.154605ms","start":"2026-05-07T19:16:28.041098Z","end":"2026-05-07T19:16:28.272252Z","steps":["trace[2091973943] 'process raft request' (duration: 176.169966ms)","trace[2091973943] 'compare' (duration: 54.539818ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T19:16:28.272529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.896773ms","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-05-07T19:16:28.272656Z","caller":"traceutil/trace.go:171","msg":"trace[1174154207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"146.089258ms","start":"2026-05-07T19:16:28.126547Z","end":"2026-05-07T19:16:28.272636Z","steps":["trace[1174154207] 'agreement among raft nodes before linearized reading' (duration: 145.723379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:29.121112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.918323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T19:16:29.121523Z","caller":"traceutil/trace.go:171","msg":"trace[1227227003] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"835.382347ms","start":"2026-05-07T19:16:28.28611Z","end":"2026-05-07T19:16:29.121492Z","steps":["trace[1227227003] 'process raft request' (duration: 118.183082ms)","trace[1227227003] 'compare' (duration: 715.761089ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T19:16:29.121639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:28.286082Z","time spent":"835.51002ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T19:16:29.125472Z","caller":"traceutil/trace.go:171","msg":"trace[399136199] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"837.857549ms","start":"2026-05-07T19:16:28.287493Z","end":"2026-05-07T19:16:29.125351Z","steps":["trace[399136199] 'process raft request' (duration: 833.731676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:29.125562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.602798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T19:16:29.12569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:28.287477Z","time spent":"838.080874ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T19:16:29.125655Z","caller":"traceutil/trace.go:171","msg":"trace[1239905722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"152.722031ms","start":"2026-05-07T19:16:28.972902Z","end":"2026-05-07T19:16:29.125624Z","steps":["trace[1239905722] 'agreement among raft nodes before linearized reading' (duration: 152.524506ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:16:29.129056Z","caller":"traceutil/trace.go:171","msg":"trace[260631628] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"152.401844ms","start":"2026-05-07T19:16:28.972955Z","end":"2026-05-07T19:16:29.125356Z","steps":["trace[260631628] 'read index received' (duration: 54.401µs)","trace[260631628] 'applied index is now lower than readState.Index' (duration: 152.345393ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T19:16:29.660641Z","caller":"traceutil/trace.go:171","msg":"trace[1478626579] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"530.012355ms","start":"2026-05-07T19:16:29.130589Z","end":"2026-05-07T19:16:29.660601Z","steps":["trace[1478626579] 'process raft request' (duration: 529.773559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:29.660975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:29.130564Z","time spent":"530.183019ms","remote":"127.0.0.1:52248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T19:16:29.665269Z","caller":"traceutil/trace.go:171","msg":"trace[1305377904] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"532.474037ms","start":"2026-05-07T19:16:29.132755Z","end":"2026-05-07T19:16:29.665229Z","steps":["trace[1305377904] 'process raft request' (duration: 532.233491ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:16:29.665323Z","caller":"traceutil/trace.go:171","msg":"trace[216439385] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"532.478286ms","start":"2026-05-07T19:16:29.132802Z","end":"2026-05-07T19:16:29.66528Z","steps":["trace[216439385] 'process raft request' (duration: 532.310922ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:16:29.665403Z","caller":"traceutil/trace.go:171","msg":"trace[1760496637] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"531.072921ms","start":"2026-05-07T19:16:29.134286Z","end":"2026-05-07T19:16:29.665359Z","steps":["trace[1760496637] 'process raft request' (duration: 530.892946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:29.665455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:29.132748Z","time spent":"532.61373ms","remote":"127.0.0.1:52248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T19:16:29.665546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:29.132786Z","time spent":"532.63492ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T19:16:29.665636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:29.134252Z","time spent":"531.256596ms","remote":"127.0.0.1:52458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T19:16:29.681621Z","caller":"traceutil/trace.go:171","msg":"trace[836807295] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:644; }","duration":"357.190753ms","start":"2026-05-07T19:16:29.324398Z","end":"2026-05-07T19:16:29.681589Z","steps":["trace[836807295] 'read index received' (duration: 335.911253ms)","trace[836807295] 'applied index is now lower than readState.Index' (duration: 21.27748ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T19:16:29.681833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.425969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T19:16:29.6819Z","caller":"traceutil/trace.go:171","msg":"trace[1862975626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"357.562432ms","start":"2026-05-07T19:16:29.324324Z","end":"2026-05-07T19:16:29.681886Z","steps":["trace[1862975626] 'agreement among raft nodes before linearized reading' (duration: 357.366578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:29.681911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.461849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-07T19:16:29.681944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:29.324294Z","time spent":"357.641545ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T19:16:29.681953Z","caller":"traceutil/trace.go:171","msg":"trace[41539241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"351.562443ms","start":"2026-05-07T19:16:29.330376Z","end":"2026-05-07T19:16:29.681938Z","steps":["trace[41539241] 'agreement among raft nodes before linearized reading' (duration: 351.381658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:29.681995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:29.330355Z","time spent":"351.631434ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T19:16:29.681987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.164492ms","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-05-07T19:16:29.682058Z","caller":"traceutil/trace.go:171","msg":"trace[428498079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"351.263245ms","start":"2026-05-07T19:16:29.330778Z","end":"2026-05-07T19:16:29.682041Z","steps":["trace[428498079] 'agreement among raft nodes before linearized reading' (duration: 351.021779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:29.682119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:29.33077Z","time spent":"351.339807ms","remote":"127.0.0.1:52490","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-05-07T19:16:41.883062Z","caller":"traceutil/trace.go:171","msg":"trace[1183721474] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"133.475322ms","start":"2026-05-07T19:16:41.749539Z","end":"2026-05-07T19:16:41.883014Z","steps":["trace[1183721474] 'process raft request' (duration: 133.147624ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:16:41.898956Z","caller":"traceutil/trace.go:171","msg":"trace[1546480984] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"148.290981ms","start":"2026-05-07T19:16:41.750619Z","end":"2026-05-07T19:16:41.89891Z","steps":["trace[1546480984] 'process raft request' (duration: 148.22493ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:16:41.899056Z","caller":"traceutil/trace.go:171","msg":"trace[1180414916] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"149.169454ms","start":"2026-05-07T19:16:41.749841Z","end":"2026-05-07T19:16:41.89901Z","steps":["trace[1180414916] 'process raft request' (duration: 148.777024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:44.854238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831657266530177,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T19:16:44.946877Z","caller":"traceutil/trace.go:171","msg":"trace[110288862] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:805; }","duration":"593.327941ms","start":"2026-05-07T19:16:44.353523Z","end":"2026-05-07T19:16:44.946851Z","steps":["trace[110288862] 'read index received' (duration: 593.29457ms)","trace[110288862] 'applied index is now lower than readState.Index' (duration: 31.281µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T19:16:44.94689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:44.295356Z","time spent":"651.526889ms","remote":"127.0.0.1:52228","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-07T19:16:44.947173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.634559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-r42wg\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-07T19:16:44.947218Z","caller":"traceutil/trace.go:171","msg":"trace[1133962709] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-r42wg; range_end:; response_count:1; response_revision:779; }","duration":"593.727041ms","start":"2026-05-07T19:16:44.353477Z","end":"2026-05-07T19:16:44.947204Z","steps":["trace[1133962709] 'agreement among raft nodes before linearized reading' (duration: 593.543797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:44.947265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:44.35346Z","time spent":"593.793772ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-r42wg\" "} {"level":"warn","ts":"2026-05-07T19:16:44.947317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.189429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T19:16:44.947448Z","caller":"traceutil/trace.go:171","msg":"trace[1774564032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:779; }","duration":"581.385583ms","start":"2026-05-07T19:16:44.366029Z","end":"2026-05-07T19:16:44.947415Z","steps":["trace[1774564032] 'agreement among raft nodes before linearized reading' (duration: 581.010354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:16:44.947508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:16:44.366003Z","time spent":"581.490165ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T19:16:45.102404Z","caller":"traceutil/trace.go:171","msg":"trace[952203937] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"152.913237ms","start":"2026-05-07T19:16:44.949465Z","end":"2026-05-07T19:16:45.102378Z","steps":["trace[952203937] 'process raft request' (duration: 151.365488ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:16:45.111959Z","caller":"traceutil/trace.go:171","msg":"trace[809402013] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"159.094891ms","start":"2026-05-07T19:16:44.952846Z","end":"2026-05-07T19:16:45.111941Z","steps":["trace[809402013] 'process raft request' (duration: 158.903726ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:16:45.112056Z","caller":"traceutil/trace.go:171","msg":"trace[1619482284] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"157.358847ms","start":"2026-05-07T19:16:44.954691Z","end":"2026-05-07T19:16:45.11205Z","steps":["trace[1619482284] 'process raft request' (duration: 157.179853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:19.763042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.223719ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-07T19:17:24.36689Z","caller":"traceutil/trace.go:171","msg":"trace[1357261464] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"394.705305ms","start":"2026-05-07T19:17:23.972164Z","end":"2026-05-07T19:17:24.36687Z","steps":["trace[1357261464] 'read index received' (duration: 394.535581ms)","trace[1357261464] 'applied index is now lower than readState.Index' (duration: 168.774µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T19:17:24.367022Z","caller":"traceutil/trace.go:171","msg":"trace[71380496] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"420.831979ms","start":"2026-05-07T19:17:23.94618Z","end":"2026-05-07T19:17:24.367012Z","steps":["trace[71380496] 'process raft request' (duration: 420.546292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:24.367114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:17:23.946154Z","time spent":"420.891481ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T19:17:24.367293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.742176ms","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-05-07T19:17:24.367359Z","caller":"traceutil/trace.go:171","msg":"trace[27218159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1011; }","duration":"298.811917ms","start":"2026-05-07T19:17:24.068529Z","end":"2026-05-07T19:17:24.367341Z","steps":["trace[27218159] 'agreement among raft nodes before linearized reading' (duration: 298.658153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:24.367485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.669482ms","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-05-07T19:17:24.367511Z","caller":"traceutil/trace.go:171","msg":"trace[555177855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"294.738333ms","start":"2026-05-07T19:17:24.072766Z","end":"2026-05-07T19:17:24.367504Z","steps":["trace[555177855] 'agreement among raft nodes before linearized reading' (duration: 294.641051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:24.367524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.404411ms","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-05-07T19:17:24.367546Z","caller":"traceutil/trace.go:171","msg":"trace[1414833157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"295.465822ms","start":"2026-05-07T19:17:24.072072Z","end":"2026-05-07T19:17:24.367538Z","steps":["trace[1414833157] 'agreement among raft nodes before linearized reading' (duration: 295.410711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:24.367611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.458174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T19:17:24.367626Z","caller":"traceutil/trace.go:171","msg":"trace[1369958126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"395.514136ms","start":"2026-05-07T19:17:23.972107Z","end":"2026-05-07T19:17:24.367621Z","steps":["trace[1369958126] 'agreement among raft nodes before linearized reading' (duration: 395.487976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:24.367644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:17:23.972083Z","time spent":"395.555697ms","remote":"127.0.0.1:52208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T19:17:43.078609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.058806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T19:17:43.078704Z","caller":"traceutil/trace.go:171","msg":"trace[344122877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1074; }","duration":"106.21115ms","start":"2026-05-07T19:17:42.972471Z","end":"2026-05-07T19:17:43.078682Z","steps":["trace[344122877] 'range keys from in-memory index tree' (duration: 105.867242ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:17:43.886796Z","caller":"traceutil/trace.go:171","msg":"trace[2023489997] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"436.929248ms","start":"2026-05-07T19:17:43.449842Z","end":"2026-05-07T19:17:43.886771Z","steps":["trace[2023489997] 'process raft request' (duration: 436.757884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:43.887006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:17:43.449814Z","time spent":"437.106353ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T19:17:43.918718Z","caller":"traceutil/trace.go:171","msg":"trace[1095638940] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"468.671835ms","start":"2026-05-07T19:17:43.450021Z","end":"2026-05-07T19:17:43.918693Z","steps":["trace[1095638940] 'process raft request' (duration: 468.503801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:43.918879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:17:43.450011Z","time spent":"468.781218ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T19:17:44.603809Z","caller":"traceutil/trace.go:171","msg":"trace[1603536202] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"118.680288ms","start":"2026-05-07T19:17:44.485109Z","end":"2026-05-07T19:17:44.603789Z","steps":["trace[1603536202] 'process raft request' (duration: 118.495304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:44.698415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.075069ms","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-05-07T19:17:44.698511Z","caller":"traceutil/trace.go:171","msg":"trace[407500909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1078; }","duration":"121.217222ms","start":"2026-05-07T19:17:44.577272Z","end":"2026-05-07T19:17:44.698489Z","steps":["trace[407500909] 'agreement among raft nodes before linearized reading' (duration: 77.479621ms)","trace[407500909] 'range keys from in-memory index tree' (duration: 43.187528ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T19:17:44.698561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.651706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T19:17:44.698636Z","caller":"traceutil/trace.go:171","msg":"trace[19126161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1078; }","duration":"175.716258ms","start":"2026-05-07T19:17:44.522873Z","end":"2026-05-07T19:17:44.698589Z","steps":["trace[19126161] 'agreement among raft nodes before linearized reading' (duration: 131.76779ms)","trace[19126161] 'range keys from in-memory index tree' (duration: 43.332812ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T19:17:45.317268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.426981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T19:17:45.317393Z","caller":"traceutil/trace.go:171","msg":"trace[585140953] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"614.263786ms","start":"2026-05-07T19:17:44.703105Z","end":"2026-05-07T19:17:45.317369Z","steps":["trace[585140953] 'process raft request' (duration: 458.63501ms)","trace[585140953] 'compare' (duration: 155.071742ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T19:17:45.317456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:17:44.70309Z","time spent":"614.337068ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T19:17:46.079807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.188167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T19:17:46.079904Z","caller":"traceutil/trace.go:171","msg":"trace[1466675948] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"188.206126ms","start":"2026-05-07T19:17:45.891683Z","end":"2026-05-07T19:17:46.079889Z","steps":["trace[1466675948] 'read index received' (duration: 58.611µs)","trace[1466675948] 'applied index is now lower than readState.Index' (duration: 188.146465ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T19:17:46.080208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.400342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-07T19:17:46.080207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.523064ms","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-05-07T19:17:46.080246Z","caller":"traceutil/trace.go:171","msg":"trace[2101853055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1082; }","duration":"157.461803ms","start":"2026-05-07T19:17:45.922775Z","end":"2026-05-07T19:17:46.080236Z","steps":["trace[2101853055] 'agreement among raft nodes before linearized reading' (duration: 157.34286ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:17:46.080277Z","caller":"traceutil/trace.go:171","msg":"trace[1570084145] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1082; }","duration":"188.640287ms","start":"2026-05-07T19:17:45.891617Z","end":"2026-05-07T19:17:46.080257Z","steps":["trace[1570084145] 'agreement among raft nodes before linearized reading' (duration: 188.417551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:46.080282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.382445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T19:17:46.080307Z","caller":"traceutil/trace.go:171","msg":"trace[1975445538] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"1.048154539s","start":"2026-05-07T19:17:45.032133Z","end":"2026-05-07T19:17:46.080287Z","steps":["trace[1975445538] 'process raft request' (duration: 492.384017ms)","trace[1975445538] 'compare' (duration: 555.056333ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T19:17:46.080333Z","caller":"traceutil/trace.go:171","msg":"trace[891144414] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1082; }","duration":"104.457356ms","start":"2026-05-07T19:17:45.975861Z","end":"2026-05-07T19:17:46.080318Z","steps":["trace[891144414] 'agreement among raft nodes before linearized reading' (duration: 104.374124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:46.080334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.469889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T19:17:46.080421Z","caller":"traceutil/trace.go:171","msg":"trace[1115925420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"108.630213ms","start":"2026-05-07T19:17:45.971774Z","end":"2026-05-07T19:17:46.080405Z","steps":["trace[1115925420] 'agreement among raft nodes before linearized reading' (duration: 108.483929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:46.08043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:17:45.032111Z","time spent":"1.048247101s","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T19:17:46.93439Z","caller":"traceutil/trace.go:171","msg":"trace[274749377] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1125; }","duration":"231.463545ms","start":"2026-05-07T19:17:46.702905Z","end":"2026-05-07T19:17:46.934369Z","steps":["trace[274749377] 'read index received' (duration: 231.25219ms)","trace[274749377] 'applied index is now lower than readState.Index' (duration: 210.045µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T19:17:46.9345Z","caller":"traceutil/trace.go:171","msg":"trace[1121233727] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"326.305266ms","start":"2026-05-07T19:17:46.608185Z","end":"2026-05-07T19:17:46.93449Z","steps":["trace[1121233727] 'process raft request' (duration: 326.016669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:46.934599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:17:46.608164Z","time spent":"326.362238ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T19:17:46.934781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.912166ms","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-05-07T19:17:46.934815Z","caller":"traceutil/trace.go:171","msg":"trace[1190360769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1085; }","duration":"231.978068ms","start":"2026-05-07T19:17:46.702829Z","end":"2026-05-07T19:17:46.934807Z","steps":["trace[1190360769] 'agreement among raft nodes before linearized reading' (duration: 231.871715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:46.935095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.029945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T19:17:46.935163Z","caller":"traceutil/trace.go:171","msg":"trace[115109695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1085; }","duration":"220.125407ms","start":"2026-05-07T19:17:46.715021Z","end":"2026-05-07T19:17:46.935146Z","steps":["trace[115109695] 'agreement among raft nodes before linearized reading' (duration: 219.87654ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:17:47.264149Z","caller":"traceutil/trace.go:171","msg":"trace[1979635368] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"324.554052ms","start":"2026-05-07T19:17:46.939562Z","end":"2026-05-07T19:17:47.264116Z","steps":["trace[1979635368] 'process raft request' (duration: 312.280041ms)","trace[1979635368] 'compare' (duration: 12.003854ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T19:17:47.264317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:17:46.93954Z","time spent":"324.690575ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T19:17:47.279228Z","caller":"traceutil/trace.go:171","msg":"trace[1302450801] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1126; }","duration":"306.500544ms","start":"2026-05-07T19:17:46.972704Z","end":"2026-05-07T19:17:47.279205Z","steps":["trace[1302450801] 'read index received' (duration: 279.047286ms)","trace[1302450801] 'applied index is now lower than readState.Index' (duration: 27.452098ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T19:17:47.279339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.629398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T19:17:47.279393Z","caller":"traceutil/trace.go:171","msg":"trace[1233803911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1087; }","duration":"306.719989ms","start":"2026-05-07T19:17:46.972658Z","end":"2026-05-07T19:17:47.279378Z","steps":["trace[1233803911] 'agreement among raft nodes before linearized reading' (duration: 306.632897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:47.279471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:17:46.972646Z","time spent":"306.784061ms","remote":"127.0.0.1:52208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T19:17:47.279536Z","caller":"traceutil/trace.go:171","msg":"trace[1312118274] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"338.923467ms","start":"2026-05-07T19:17:46.94054Z","end":"2026-05-07T19:17:47.279463Z","steps":["trace[1312118274] 'process raft request' (duration: 338.558088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:47.279739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:17:46.94053Z","time spent":"339.098412ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T19:17:47.443237Z","caller":"traceutil/trace.go:171","msg":"trace[857184327] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"116.170563ms","start":"2026-05-07T19:17:47.327041Z","end":"2026-05-07T19:17:47.443211Z","steps":["trace[857184327] 'process raft request' (duration: 95.855227ms)","trace[857184327] 'compare' (duration: 20.029399ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T19:17:48.321714Z","caller":"traceutil/trace.go:171","msg":"trace[955864659] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"163.655502ms","start":"2026-05-07T19:17:48.158031Z","end":"2026-05-07T19:17:48.321686Z","steps":["trace[955864659] 'read index received' (duration: 163.135459ms)","trace[955864659] 'applied index is now lower than readState.Index' (duration: 519.073µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T19:17:48.321859Z","caller":"traceutil/trace.go:171","msg":"trace[1618048345] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"185.947117ms","start":"2026-05-07T19:17:48.135864Z","end":"2026-05-07T19:17:48.321811Z","steps":["trace[1618048345] 'process raft request' (duration: 185.353172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:17:48.321923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.871987ms","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-05-07T19:17:48.322084Z","caller":"traceutil/trace.go:171","msg":"trace[742655669] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1089; }","duration":"164.073762ms","start":"2026-05-07T19:17:48.157998Z","end":"2026-05-07T19:17:48.322071Z","steps":["trace[742655669] 'agreement among raft nodes before linearized reading' (duration: 163.780235ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:17:48.438545Z","caller":"traceutil/trace.go:171","msg":"trace[1980762429] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"111.191248ms","start":"2026-05-07T19:17:48.327282Z","end":"2026-05-07T19:17:48.438473Z","steps":["trace[1980762429] 'process raft request' (duration: 63.765572ms)","trace[1980762429] 'compare' (duration: 47.20323ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T19:17:49.071032Z","caller":"traceutil/trace.go:171","msg":"trace[1661040556] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"130.535859ms","start":"2026-05-07T19:17:48.940462Z","end":"2026-05-07T19:17:49.070998Z","steps":["trace[1661040556] 'process raft request' (duration: 130.355854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:18:00.949492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.86992ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-07T19:18:00.949656Z","caller":"traceutil/trace.go:171","msg":"trace[2081659976] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"669.172944ms","start":"2026-05-07T19:18:00.280456Z","end":"2026-05-07T19:18:00.949629Z","steps":["trace[2081659976] 'read index received' (duration: 197.072558ms)","trace[2081659976] 'applied index is now lower than readState.Index' (duration: 472.097886ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T19:18:00.949877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.389959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T19:18:00.949974Z","caller":"traceutil/trace.go:171","msg":"trace[1431426635] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1179; }","duration":"669.535622ms","start":"2026-05-07T19:18:00.280409Z","end":"2026-05-07T19:18:00.949944Z","steps":["trace[1431426635] 'agreement among raft nodes before linearized reading' (duration: 669.368368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:18:00.950005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.63814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-07T19:18:00.950023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:18:00.280388Z","time spent":"669.623664ms","remote":"127.0.0.1:52326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-05-07T19:18:00.950055Z","caller":"traceutil/trace.go:171","msg":"trace[994023127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1179; }","duration":"580.725722ms","start":"2026-05-07T19:18:00.369309Z","end":"2026-05-07T19:18:00.950034Z","steps":["trace[994023127] 'agreement among raft nodes before linearized reading' (duration: 580.481266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:18:00.950107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:18:00.369293Z","time spent":"580.803484ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T19:18:00.950557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.953243ms","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-05-07T19:18:00.950592Z","caller":"traceutil/trace.go:171","msg":"trace[1863072289] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1179; }","duration":"460.019395ms","start":"2026-05-07T19:18:00.490562Z","end":"2026-05-07T19:18:00.950582Z","steps":["trace[1863072289] 'agreement among raft nodes before linearized reading' (duration: 459.865362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:18:00.950621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:18:00.490543Z","time spent":"460.071727ms","remote":"127.0.0.1:52490","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-05-07T19:18:03.711723Z","caller":"traceutil/trace.go:171","msg":"trace[432706192] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"109.500458ms","start":"2026-05-07T19:18:03.602191Z","end":"2026-05-07T19:18:03.711692Z","steps":["trace[432706192] 'process raft request' (duration: 109.330224ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:18:16.452285Z","caller":"traceutil/trace.go:171","msg":"trace[1239656296] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"129.219121ms","start":"2026-05-07T19:18:16.323036Z","end":"2026-05-07T19:18:16.452255Z","steps":["trace[1239656296] 'process raft request' (duration: 129.088347ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:18:17.406976Z","caller":"traceutil/trace.go:171","msg":"trace[615504536] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"150.4802ms","start":"2026-05-07T19:18:17.256239Z","end":"2026-05-07T19:18:17.40672Z","steps":["trace[615504536] 'process raft request' (duration: 150.237904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:18:17.828984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.593278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T19:18:17.829099Z","caller":"traceutil/trace.go:171","msg":"trace[202772273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1255; }","duration":"309.800333ms","start":"2026-05-07T19:18:17.519275Z","end":"2026-05-07T19:18:17.829075Z","steps":["trace[202772273] 'range keys from in-memory index tree' (duration: 309.390503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:18:17.829149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:18:17.519254Z","time spent":"309.884485ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T19:18:17.829282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.797782ms","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-05-07T19:18:17.829376Z","caller":"traceutil/trace.go:171","msg":"trace[1228034767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1255; }","duration":"254.930286ms","start":"2026-05-07T19:18:17.574418Z","end":"2026-05-07T19:18:17.829349Z","steps":["trace[1228034767] 'range keys from in-memory index tree' (duration: 254.69967ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:18:18.043766Z","caller":"traceutil/trace.go:171","msg":"trace[1268445466] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"211.685659ms","start":"2026-05-07T19:18:17.832054Z","end":"2026-05-07T19:18:18.04374Z","steps":["trace[1268445466] 'process raft request' (duration: 211.531525ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:18:18.092485Z","caller":"traceutil/trace.go:171","msg":"trace[1926879088] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1305; }","duration":"171.840892ms","start":"2026-05-07T19:18:17.920623Z","end":"2026-05-07T19:18:18.092464Z","steps":["trace[1926879088] 'read index received' (duration: 123.166555ms)","trace[1926879088] 'applied index is now lower than readState.Index' (duration: 48.673317ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T19:18:18.092558Z","caller":"traceutil/trace.go:171","msg":"trace[390441778] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"259.458945ms","start":"2026-05-07T19:18:17.83307Z","end":"2026-05-07T19:18:18.092529Z","steps":["trace[390441778] 'process raft request' (duration: 259.202879ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:18:18.092617Z","caller":"traceutil/trace.go:171","msg":"trace[588308806] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"259.507706ms","start":"2026-05-07T19:18:17.833096Z","end":"2026-05-07T19:18:18.092604Z","steps":["trace[588308806] 'process raft request' (duration: 259.329852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:18:18.092795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.15252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T19:18:18.092832Z","caller":"traceutil/trace.go:171","msg":"trace[1445568114] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1258; }","duration":"172.309053ms","start":"2026-05-07T19:18:17.920514Z","end":"2026-05-07T19:18:18.092823Z","steps":["trace[1445568114] 'agreement among raft nodes before linearized reading' (duration: 172.20809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:18:18.109739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.557166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T19:18:18.109852Z","caller":"traceutil/trace.go:171","msg":"trace[1422080031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1259; }","duration":"136.743051ms","start":"2026-05-07T19:18:17.973084Z","end":"2026-05-07T19:18:18.109827Z","steps":["trace[1422080031] 'agreement among raft nodes before linearized reading' (duration: 136.522795ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:18:19.910642Z","caller":"traceutil/trace.go:171","msg":"trace[507010452] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"719.45799ms","start":"2026-05-07T19:18:19.191157Z","end":"2026-05-07T19:18:19.910615Z","steps":["trace[507010452] 'process raft request' (duration: 719.289016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:18:19.910788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:18:19.191137Z","time spent":"719.585802ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T19:18:19.942718Z","caller":"traceutil/trace.go:171","msg":"trace[523276803] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"685.957075ms","start":"2026-05-07T19:18:19.256731Z","end":"2026-05-07T19:18:19.942688Z","steps":["trace[523276803] 'process raft request' (duration: 685.76631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:18:19.94285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:18:19.256705Z","time spent":"686.074889ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T19:18:19.942875Z","caller":"traceutil/trace.go:171","msg":"trace[469558563] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"530.125008ms","start":"2026-05-07T19:18:19.412733Z","end":"2026-05-07T19:18:19.942858Z","steps":["trace[469558563] 'process raft request' (duration: 529.85825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:18:19.942992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:18:19.412705Z","time spent":"530.21517ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T19:18:20.229424Z","caller":"traceutil/trace.go:171","msg":"trace[1559748751] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"117.533759ms","start":"2026-05-07T19:18:20.111863Z","end":"2026-05-07T19:18:20.229396Z","steps":["trace[1559748751] 'process raft request' (duration: 76.408053ms)","trace[1559748751] 'compare' (duration: 41.006033ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T19:18:31.993467Z","caller":"traceutil/trace.go:171","msg":"trace[492015884] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"678.721722ms","start":"2026-05-07T19:18:31.314701Z","end":"2026-05-07T19:18:31.993422Z","steps":["trace[492015884] 'process raft request' (duration: 678.568218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:18:31.993922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:18:31.314661Z","time spent":"678.890986ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T19:18:32.038496Z","caller":"traceutil/trace.go:171","msg":"trace[92173609] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"720.177007ms","start":"2026-05-07T19:18:31.318291Z","end":"2026-05-07T19:18:32.038468Z","steps":["trace[92173609] 'process raft request' (duration: 720.003413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:18:32.038665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:18:31.318268Z","time spent":"720.30342ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T19:18:32.496909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.956365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T19:18:32.497036Z","caller":"traceutil/trace.go:171","msg":"trace[1882553258] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"458.590881ms","start":"2026-05-07T19:18:32.038427Z","end":"2026-05-07T19:18:32.497018Z","steps":["trace[1882553258] 'read index received' (duration: 326.338619ms)","trace[1882553258] 'applied index is now lower than readState.Index' (duration: 132.250722ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T19:18:32.497128Z","caller":"traceutil/trace.go:171","msg":"trace[2140567675] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"488.816655ms","start":"2026-05-07T19:18:32.008296Z","end":"2026-05-07T19:18:32.497113Z","steps":["trace[2140567675] 'process raft request' (duration: 356.551883ms)","trace[2140567675] 'compare' (duration: 131.646878ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T19:18:32.497196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:18:32.008259Z","time spent":"488.901087ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T19:18:32.497524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.857463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-07T19:18:32.497507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.513315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T19:18:32.497575Z","caller":"traceutil/trace.go:171","msg":"trace[1968599857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1349; }","duration":"482.982656ms","start":"2026-05-07T19:18:32.014579Z","end":"2026-05-07T19:18:32.497562Z","steps":["trace[1968599857] 'agreement among raft nodes before linearized reading' (duration: 482.787801ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:18:32.497595Z","caller":"traceutil/trace.go:171","msg":"trace[935109648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1349; }","duration":"457.655548ms","start":"2026-05-07T19:18:32.039918Z","end":"2026-05-07T19:18:32.497574Z","steps":["trace[935109648] 'agreement among raft nodes before linearized reading' (duration: 457.488054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:18:32.497618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:18:32.014535Z","time spent":"483.074428ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T19:18:32.497651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:18:32.039901Z","time spent":"457.74047ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T19:18:32.497674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.97819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-07T19:18:32.49763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.923521ms","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-05-07T19:18:32.497717Z","caller":"traceutil/trace.go:171","msg":"trace[2055731751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1349; }","duration":"216.058382ms","start":"2026-05-07T19:18:32.281649Z","end":"2026-05-07T19:18:32.497707Z","steps":["trace[2055731751] 'agreement among raft nodes before linearized reading' (duration: 215.94257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:18:32.497715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.791068ms","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-05-07T19:18:32.497749Z","caller":"traceutil/trace.go:171","msg":"trace[716831076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1349; }","duration":"294.087075ms","start":"2026-05-07T19:18:32.203638Z","end":"2026-05-07T19:18:32.497725Z","steps":["trace[716831076] 'agreement among raft nodes before linearized reading' (duration: 293.780738ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:18:32.497803Z","caller":"traceutil/trace.go:171","msg":"trace[1105499913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1349; }","duration":"338.929031ms","start":"2026-05-07T19:18:32.158851Z","end":"2026-05-07T19:18:32.49778Z","steps":["trace[1105499913] 'agreement among raft nodes before linearized reading' (duration: 338.680495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T19:18:32.497851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T19:18:32.158834Z","time spent":"339.008293ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T19:21:53.389121Z","caller":"traceutil/trace.go:171","msg":"trace[2000437502] transaction","detail":"{read_only:false; response_revision:2299; number_of_response:1; }","duration":"121.470604ms","start":"2026-05-07T19:21:53.267626Z","end":"2026-05-07T19:21:53.389097Z","steps":["trace[2000437502] 'process raft request' (duration: 121.336681ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T19:21:53.41345Z","caller":"traceutil/trace.go:171","msg":"trace[1697807856] transaction","detail":"{read_only:false; response_revision:2300; number_of_response:1; }","duration":"143.078862ms","start":"2026-05-07T19:21:53.270348Z","end":"2026-05-07T19:21:53.413427Z","steps":["trace[1697807856] 'process raft request' (duration: 142.962819ms)"],"step_count":1}