{"level":"warn","ts":"2026-04-07T09:41:54.173856Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T09:41:54.173934Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70: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.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T09:41:54.173993Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T09:41:54.174004Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-04-07T09:41:54.174022Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T09:41:54.174757Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-04-07T09:41:54.174853Z","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.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T09:41:54.178631Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.570987ms"} {"level":"info","ts":"2026-04-07T09:41:54.186714Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-04-07T09:41:54.186873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T09:41:54.186899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-04-07T09:41:54.186964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T09:41:54.186978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-04-07T09:41:54.187036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-04-07T09:41:54.191552Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T09:41:54.193142Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T09:41:54.194346Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T09:41:54.196942Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T09:41:54.197524Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T09:41:54.197477Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T09:41:54.197597Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T09:41:54.19761Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T09:41:54.199015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-04-07T09:41:54.201259Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T09:41:54.201412Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-04-07T09:41:54.201483Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-04-07T09:41:54.201665Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T09:41:54.201712Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T09:41:54.201827Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-04-07T09:41:54.288243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T09:41:54.288306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T09:41:54.288339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-04-07T09:41:54.28836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-04-07T09:41:54.288373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-04-07T09:41:54.288389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-04-07T09:41:54.288409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-04-07T09:41:54.293271Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T09:41:54.293377Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:41:54.293658Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T09:41:54.294312Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:41:54.294337Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T09:41:54.294576Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:41:54.294626Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:41:54.296915Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T09:41:54.297081Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T09:41:54.297142Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T09:41:54.29728Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-04-07T09:42:03.278545Z","caller":"traceutil/trace.go:171","msg":"trace[570698494] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"107.857099ms","start":"2026-04-07T09:42:03.170656Z","end":"2026-04-07T09:42:03.278513Z","steps":["trace[570698494] 'process raft request' (duration: 107.699126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:04.234338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.287898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:42:04.234495Z","caller":"traceutil/trace.go:171","msg":"trace[1617605520] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:322; }","duration":"263.617808ms","start":"2026-04-07T09:42:03.970857Z","end":"2026-04-07T09:42:04.234475Z","steps":["trace[1617605520] 'read index received' (duration: 38.370892ms)","trace[1617605520] 'applied index is now lower than readState.Index' (duration: 225.244876ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:04.234561Z","caller":"traceutil/trace.go:171","msg":"trace[1989989489] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"281.24755ms","start":"2026-04-07T09:42:03.953279Z","end":"2026-04-07T09:42:04.234527Z","steps":["trace[1989989489] 'process raft request' (duration: 55.906302ms)","trace[1989989489] 'compare' (duration: 224.145246ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:04.234645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.786132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-07T09:42:04.234687Z","caller":"traceutil/trace.go:171","msg":"trace[926829469] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:310; }","duration":"263.870702ms","start":"2026-04-07T09:42:03.970803Z","end":"2026-04-07T09:42:04.234674Z","steps":["trace[926829469] 'agreement among raft nodes before linearized reading' (duration: 263.780751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:04.234993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.374331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-07T09:42:04.235051Z","caller":"traceutil/trace.go:171","msg":"trace[208265412] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:310; }","duration":"214.471123ms","start":"2026-04-07T09:42:04.020566Z","end":"2026-04-07T09:42:04.235038Z","steps":["trace[208265412] 'agreement among raft nodes before linearized reading' (duration: 214.382082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:04.235055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.672037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-07T09:42:04.235099Z","caller":"traceutil/trace.go:171","msg":"trace[304868142] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:310; }","duration":"113.773359ms","start":"2026-04-07T09:42:04.121313Z","end":"2026-04-07T09:42:04.235087Z","steps":["trace[304868142] 'agreement among raft nodes before linearized reading' (duration: 113.665767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:04.235209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.371922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-07T09:42:04.235252Z","caller":"traceutil/trace.go:171","msg":"trace[1142850719] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:310; }","duration":"163.450014ms","start":"2026-04-07T09:42:04.071789Z","end":"2026-04-07T09:42:04.235239Z","steps":["trace[1142850719] 'agreement among raft nodes before linearized reading' (duration: 163.375323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:05.616875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15799026s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:42:05.617102Z","caller":"traceutil/trace.go:171","msg":"trace[1600416554] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"1.373649665s","start":"2026-04-07T09:42:04.243413Z","end":"2026-04-07T09:42:05.617063Z","steps":["trace[1600416554] 'process raft request' (duration: 215.402201ms)","trace[1600416554] 'compare' (duration: 1.157797796s)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:05.617249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:04.243394Z","time spent":"1.37378199s","remote":"127.0.0.1:38812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:42:05.617337Z","caller":"traceutil/trace.go:171","msg":"trace[885951070] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:323; }","duration":"1.346147459s","start":"2026-04-07T09:42:04.27117Z","end":"2026-04-07T09:42:05.617317Z","steps":["trace[885951070] 'read index received' (duration: 187.728321ms)","trace[885951070] 'applied index is now lower than readState.Index' (duration: 1.158418098s)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:05.617422Z","caller":"traceutil/trace.go:171","msg":"trace[1581344486] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"1.373224219s","start":"2026-04-07T09:42:04.24419Z","end":"2026-04-07T09:42:05.617414Z","steps":["trace[1581344486] 'process raft request' (duration: 1.372871161s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:05.617474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:04.244182Z","time spent":"1.3732652s","remote":"127.0.0.1:39242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:42:05.617568Z","caller":"traceutil/trace.go:171","msg":"trace[684879159] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"1.373604885s","start":"2026-04-07T09:42:04.243945Z","end":"2026-04-07T09:42:05.61755Z","steps":["trace[684879159] 'process raft request' (duration: 1.373048434s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:05.617629Z","caller":"traceutil/trace.go:171","msg":"trace[1884458072] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"1.372188419s","start":"2026-04-07T09:42:04.245432Z","end":"2026-04-07T09:42:05.61762Z","steps":["trace[1884458072] 'process raft request' (duration: 1.371846992s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:05.617696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:04.243924Z","time spent":"1.373684628s","remote":"127.0.0.1:38812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:42:05.617714Z","caller":"traceutil/trace.go:171","msg":"trace[840032016] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"1.372676618s","start":"2026-04-07T09:42:04.245024Z","end":"2026-04-07T09:42:05.617701Z","steps":["trace[840032016] 'process raft request' (duration: 1.372076906s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:05.617721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:04.245426Z","time spent":"1.37227291s","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:42:05.617792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:04.245015Z","time spent":"1.372731829s","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:42:05.617872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346697919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-07T09:42:05.617903Z","caller":"traceutil/trace.go:171","msg":"trace[1709263370] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:317; }","duration":"1.346753741s","start":"2026-04-07T09:42:04.27114Z","end":"2026-04-07T09:42:05.617894Z","steps":["trace[1709263370] 'agreement among raft nodes before linearized reading' (duration: 1.34669314s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:05.617914Z","caller":"traceutil/trace.go:171","msg":"trace[883525923] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"1.37281429s","start":"2026-04-07T09:42:04.245089Z","end":"2026-04-07T09:42:05.617904Z","steps":["trace[883525923] 'process raft request' (duration: 1.372048256s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:05.617931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:04.271125Z","time spent":"1.346799521s","remote":"127.0.0.1:38912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-04-07T09:42:05.617966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:04.245065Z","time spent":"1.372872592s","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:42:05.61796Z","caller":"traceutil/trace.go:171","msg":"trace[1792138006] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"1.372740859s","start":"2026-04-07T09:42:04.245208Z","end":"2026-04-07T09:42:05.617949Z","steps":["trace[1792138006] 'process raft request' (duration: 1.371967095s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:05.618031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:04.245201Z","time spent":"1.37279292s","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:42:05.618075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166238295s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-04-07T09:42:05.618102Z","caller":"traceutil/trace.go:171","msg":"trace[1867704721] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:317; }","duration":"1.166294146s","start":"2026-04-07T09:42:04.4518Z","end":"2026-04-07T09:42:05.618094Z","steps":["trace[1867704721] 'agreement among raft nodes before linearized reading' (duration: 1.166240575s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:05.618127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:04.451784Z","time spent":"1.166336537s","remote":"127.0.0.1:38894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-07T09:42:05.61827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.743491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-07T09:42:05.618331Z","caller":"traceutil/trace.go:171","msg":"trace[11158724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:317; }","duration":"531.879873ms","start":"2026-04-07T09:42:05.086408Z","end":"2026-04-07T09:42:05.618288Z","steps":["trace[11158724] 'agreement among raft nodes before linearized reading' (duration: 531.829672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:05.618359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:05.086382Z","time spent":"531.970885ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T09:42:05.618438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.618059ms","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-04-07T09:42:05.618492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108701713s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T09:42:05.618492Z","caller":"traceutil/trace.go:171","msg":"trace[2144701047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:317; }","duration":"926.70841ms","start":"2026-04-07T09:42:04.691767Z","end":"2026-04-07T09:42:05.618475Z","steps":["trace[2144701047] 'agreement among raft nodes before linearized reading' (duration: 926.619488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:05.618509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.258236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-07T09:42:05.618537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:04.691735Z","time spent":"926.791432ms","remote":"127.0.0.1:39008","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":"info","ts":"2026-04-07T09:42:05.618551Z","caller":"traceutil/trace.go:171","msg":"trace[1986030503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:317; }","duration":"905.334688ms","start":"2026-04-07T09:42:04.713206Z","end":"2026-04-07T09:42:05.618541Z","steps":["trace[1986030503] 'agreement among raft nodes before linearized reading' (duration: 905.259736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:05.618585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:04.713178Z","time spent":"905.399848ms","remote":"127.0.0.1:39008","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-04-07T09:42:05.618519Z","caller":"traceutil/trace.go:171","msg":"trace[1558950244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:317; }","duration":"1.108754084s","start":"2026-04-07T09:42:04.509757Z","end":"2026-04-07T09:42:05.618511Z","steps":["trace[1558950244] 'agreement among raft nodes before linearized reading' (duration: 1.108707473s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:05.618661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:04.509741Z","time spent":"1.108914097s","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T09:42:06.783641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034799494s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:42:06.784231Z","caller":"traceutil/trace.go:171","msg":"trace[1682898578] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"1.156295888s","start":"2026-04-07T09:42:05.627917Z","end":"2026-04-07T09:42:06.784213Z","steps":["trace[1682898578] 'process raft request' (duration: 1.156252168s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:06.784328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:05.627911Z","time spent":"1.15637677s","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:42:06.784495Z","caller":"traceutil/trace.go:171","msg":"trace[1412369110] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"1.159762713s","start":"2026-04-07T09:42:05.624719Z","end":"2026-04-07T09:42:06.784482Z","steps":["trace[1412369110] 'process raft request' (duration: 1.159210413s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:06.784482Z","caller":"traceutil/trace.go:171","msg":"trace[767553290] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"1.157725535s","start":"2026-04-07T09:42:05.626717Z","end":"2026-04-07T09:42:06.784443Z","steps":["trace[767553290] 'process raft request' (duration: 1.157308447s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:06.784561Z","caller":"traceutil/trace.go:171","msg":"trace[1506272919] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"1.156635624s","start":"2026-04-07T09:42:05.627906Z","end":"2026-04-07T09:42:06.784542Z","steps":["trace[1506272919] 'process raft request' (duration: 1.156221067s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:06.784602Z","caller":"traceutil/trace.go:171","msg":"trace[841720937] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"1.157649273s","start":"2026-04-07T09:42:05.626934Z","end":"2026-04-07T09:42:06.784583Z","steps":["trace[841720937] 'process raft request' (duration: 1.157150194s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:06.784648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:05.627897Z","time spent":"1.156715956s","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:42:06.78466Z","caller":"traceutil/trace.go:171","msg":"trace[624525443] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"1.15907928s","start":"2026-04-07T09:42:05.625545Z","end":"2026-04-07T09:42:06.784624Z","steps":["trace[624525443] 'process raft request' (duration: 1.158430478s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:06.784685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:05.624699Z","time spent":"1.159827524s","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:42:06.784685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:05.626711Z","time spent":"1.157854817s","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:42:06.784721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:05.626928Z","time spent":"1.157739695s","remote":"127.0.0.1:38894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:42:06.784741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:05.625535Z","time spent":"1.159171401s","remote":"127.0.0.1:38894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:42:06.784794Z","caller":"traceutil/trace.go:171","msg":"trace[1041166438] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"1.160778612s","start":"2026-04-07T09:42:05.624006Z","end":"2026-04-07T09:42:06.784785Z","steps":["trace[1041166438] 'process raft request' (duration: 124.751535ms)","trace[1041166438] 'compare' (duration: 1.034667342s)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:06.784854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:05.623982Z","time spent":"1.160834724s","remote":"127.0.0.1:39008","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-04-07T09:42:06.784904Z","caller":"traceutil/trace.go:171","msg":"trace[1071747304] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"1.160335534s","start":"2026-04-07T09:42:05.624562Z","end":"2026-04-07T09:42:06.784898Z","steps":["trace[1071747304] 'process raft request' (duration: 1.159245994s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:06.784932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:05.62455Z","time spent":"1.160366214s","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:42:06.784978Z","caller":"traceutil/trace.go:171","msg":"trace[311472529] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"1.160450816s","start":"2026-04-07T09:42:05.624516Z","end":"2026-04-07T09:42:06.784967Z","steps":["trace[311472529] 'process raft request' (duration: 1.159222454s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:06.784985Z","caller":"traceutil/trace.go:171","msg":"trace[1097676552] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"1.160348974s","start":"2026-04-07T09:42:05.624614Z","end":"2026-04-07T09:42:06.784963Z","steps":["trace[1097676552] 'process raft request' (duration: 1.159248124s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:06.785028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:05.624496Z","time spent":"1.160508577s","remote":"127.0.0.1:38812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:42:06.785083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:05.624605Z","time spent":"1.160427876s","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:42:07.3277Z","caller":"traceutil/trace.go:171","msg":"trace[1331745756] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"747.290766ms","start":"2026-04-07T09:42:06.580374Z","end":"2026-04-07T09:42:07.327665Z","steps":["trace[1331745756] 'process raft request' (duration: 651.200499ms)","trace[1331745756] 'compare' (duration: 95.920424ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:07.327874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:06.580348Z","time spent":"747.435068ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:42:07.327873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.206591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-07T09:42:07.327936Z","caller":"traceutil/trace.go:171","msg":"trace[793261385] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:329; }","duration":"539.310773ms","start":"2026-04-07T09:42:06.788612Z","end":"2026-04-07T09:42:07.327923Z","steps":["trace[793261385] 'agreement among raft nodes before linearized reading' (duration: 539.14472ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:07.327704Z","caller":"traceutil/trace.go:171","msg":"trace[1573307937] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"539.034418ms","start":"2026-04-07T09:42:06.788645Z","end":"2026-04-07T09:42:07.327679Z","steps":["trace[1573307937] 'read index received' (duration: 442.965581ms)","trace[1573307937] 'applied index is now lower than readState.Index' (duration: 96.067957ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:07.327979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:06.788597Z","time spent":"539.373765ms","remote":"127.0.0.1:38912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-04-07T09:42:07.327988Z","caller":"traceutil/trace.go:171","msg":"trace[1079490028] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"535.176086ms","start":"2026-04-07T09:42:06.792792Z","end":"2026-04-07T09:42:07.327969Z","steps":["trace[1079490028] 'process raft request' (duration: 534.993662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:07.328117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:06.792774Z","time spent":"535.276168ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:42:07.328124Z","caller":"traceutil/trace.go:171","msg":"trace[1577145311] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"535.248467ms","start":"2026-04-07T09:42:06.792793Z","end":"2026-04-07T09:42:07.328041Z","steps":["trace[1577145311] 'process raft request' (duration: 534.844849ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:07.32818Z","caller":"traceutil/trace.go:171","msg":"trace[2128639450] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"535.298108ms","start":"2026-04-07T09:42:06.792866Z","end":"2026-04-07T09:42:07.328164Z","steps":["trace[2128639450] 'process raft request' (duration: 534.966152ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:07.328227Z","caller":"traceutil/trace.go:171","msg":"trace[1238062518] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"535.333539ms","start":"2026-04-07T09:42:06.792883Z","end":"2026-04-07T09:42:07.328217Z","steps":["trace[1238062518] 'process raft request' (duration: 535.200087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:07.328243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:06.79286Z","time spent":"535.354079ms","remote":"127.0.0.1:38800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:42:07.32829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:06.79287Z","time spent":"535.39935ms","remote":"127.0.0.1:38812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:42:07.328307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:06.792783Z","time spent":"535.431401ms","remote":"127.0.0.1:38800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:42:07.328504Z","caller":"traceutil/trace.go:171","msg":"trace[343642350] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"535.533272ms","start":"2026-04-07T09:42:06.792959Z","end":"2026-04-07T09:42:07.328492Z","steps":["trace[343642350] 'process raft request' (duration: 535.172606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:07.328565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:06.792952Z","time spent":"535.578453ms","remote":"127.0.0.1:38894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:42:07.328588Z","caller":"traceutil/trace.go:171","msg":"trace[2144616053] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"532.941514ms","start":"2026-04-07T09:42:06.795629Z","end":"2026-04-07T09:42:07.32857Z","steps":["trace[2144616053] 'process raft request' (duration: 532.647898ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:07.328619Z","caller":"traceutil/trace.go:171","msg":"trace[708706965] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"534.799938ms","start":"2026-04-07T09:42:06.793807Z","end":"2026-04-07T09:42:07.328607Z","steps":["trace[708706965] 'process raft request' (duration: 534.36585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:07.328672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:06.795622Z","time spent":"533.004405ms","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:42:07.328684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:06.7938Z","time spent":"534.848449ms","remote":"127.0.0.1:39220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:42:07.331264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.377546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-04-07T09:42:07.331772Z","caller":"traceutil/trace.go:171","msg":"trace[1042493047] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:335; }","duration":"538.892516ms","start":"2026-04-07T09:42:06.792853Z","end":"2026-04-07T09:42:07.331745Z","steps":["trace[1042493047] 'agreement among raft nodes before linearized reading' (duration: 535.468481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:07.331819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:06.792848Z","time spent":"538.957377ms","remote":"127.0.0.1:38894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-04-07T09:42:07.507212Z","caller":"traceutil/trace.go:171","msg":"trace[1795406003] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:348; }","duration":"170.700441ms","start":"2026-04-07T09:42:07.336494Z","end":"2026-04-07T09:42:07.507194Z","steps":["trace[1795406003] 'read index received' (duration: 29.697639ms)","trace[1795406003] 'applied index is now lower than readState.Index' (duration: 141.001782ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:07.507253Z","caller":"traceutil/trace.go:171","msg":"trace[1001579412] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"172.988414ms","start":"2026-04-07T09:42:07.334226Z","end":"2026-04-07T09:42:07.507214Z","steps":["trace[1001579412] 'process raft request' (duration: 126.113372ms)","trace[1001579412] 'compare' (duration: 46.669188ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:07.507306Z","caller":"traceutil/trace.go:171","msg":"trace[2029982325] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"168.414968ms","start":"2026-04-07T09:42:07.338867Z","end":"2026-04-07T09:42:07.507281Z","steps":["trace[2029982325] 'process raft request' (duration: 168.242795ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:07.507337Z","caller":"traceutil/trace.go:171","msg":"trace[1427449917] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"168.356337ms","start":"2026-04-07T09:42:07.338967Z","end":"2026-04-07T09:42:07.507323Z","steps":["trace[1427449917] 'process raft request' (duration: 168.187154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:07.507708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.051937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-04-07T09:42:07.507751Z","caller":"traceutil/trace.go:171","msg":"trace[1494992639] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:339; }","duration":"171.287902ms","start":"2026-04-07T09:42:07.336453Z","end":"2026-04-07T09:42:07.507741Z","steps":["trace[1494992639] 'agreement among raft nodes before linearized reading' (duration: 170.821613ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:07.693752Z","caller":"traceutil/trace.go:171","msg":"trace[307866959] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"124.477712ms","start":"2026-04-07T09:42:07.569244Z","end":"2026-04-07T09:42:07.693722Z","steps":["trace[307866959] 'process raft request' (duration: 68.692253ms)","trace[307866959] 'compare' (duration: 55.592796ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:07.695202Z","caller":"traceutil/trace.go:171","msg":"trace[1371101298] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"116.997229ms","start":"2026-04-07T09:42:07.578189Z","end":"2026-04-07T09:42:07.695186Z","steps":["trace[1371101298] 'process raft request' (duration: 116.957758ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:07.695446Z","caller":"traceutil/trace.go:171","msg":"trace[1062012270] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"119.085939ms","start":"2026-04-07T09:42:07.57632Z","end":"2026-04-07T09:42:07.695406Z","steps":["trace[1062012270] 'process raft request' (duration: 118.757833ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:08.978316Z","caller":"traceutil/trace.go:171","msg":"trace[286414678] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"187.210462ms","start":"2026-04-07T09:42:08.79103Z","end":"2026-04-07T09:42:08.97824Z","steps":["trace[286414678] 'read index received' (duration: 186.295425ms)","trace[286414678] 'applied index is now lower than readState.Index' (duration: 913.657µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:08.979118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.061548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-07T09:42:08.979142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.899425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T09:42:08.979172Z","caller":"traceutil/trace.go:171","msg":"trace[441375700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:354; }","duration":"188.15926ms","start":"2026-04-07T09:42:08.790998Z","end":"2026-04-07T09:42:08.979157Z","steps":["trace[441375700] 'agreement among raft nodes before linearized reading' (duration: 187.451025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:08.979168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.567799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T09:42:08.97922Z","caller":"traceutil/trace.go:171","msg":"trace[1139177784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:354; }","duration":"187.039108ms","start":"2026-04-07T09:42:08.79216Z","end":"2026-04-07T09:42:08.979199Z","steps":["trace[1139177784] 'agreement among raft nodes before linearized reading' (duration: 186.59984ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:08.979238Z","caller":"traceutil/trace.go:171","msg":"trace[1924727249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:354; }","duration":"186.670372ms","start":"2026-04-07T09:42:08.792549Z","end":"2026-04-07T09:42:08.97922Z","steps":["trace[1924727249] 'agreement among raft nodes before linearized reading' (duration: 186.154521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:08.980383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.170049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T09:42:08.980439Z","caller":"traceutil/trace.go:171","msg":"trace[1566131993] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:354; }","duration":"188.265771ms","start":"2026-04-07T09:42:08.79216Z","end":"2026-04-07T09:42:08.980426Z","steps":["trace[1566131993] 'agreement among raft nodes before linearized reading' (duration: 186.421226ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:09.12374Z","caller":"traceutil/trace.go:171","msg":"trace[1503641707] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"139.469943ms","start":"2026-04-07T09:42:08.984252Z","end":"2026-04-07T09:42:09.123722Z","steps":["trace[1503641707] 'process raft request' (duration: 139.423512ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:09.123883Z","caller":"traceutil/trace.go:171","msg":"trace[929653660] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"140.614964ms","start":"2026-04-07T09:42:08.983245Z","end":"2026-04-07T09:42:09.12386Z","steps":["trace[929653660] 'process raft request' (duration: 140.336089ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:09.124512Z","caller":"traceutil/trace.go:171","msg":"trace[536806567] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"139.680777ms","start":"2026-04-07T09:42:08.984811Z","end":"2026-04-07T09:42:09.124492Z","steps":["trace[536806567] 'process raft request' (duration: 138.889371ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:09.124635Z","caller":"traceutil/trace.go:171","msg":"trace[206826199] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"139.679017ms","start":"2026-04-07T09:42:08.984914Z","end":"2026-04-07T09:42:09.124593Z","steps":["trace[206826199] 'process raft request' (duration: 139.35513ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:09.124687Z","caller":"traceutil/trace.go:171","msg":"trace[494842229] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"123.992043ms","start":"2026-04-07T09:42:09.000686Z","end":"2026-04-07T09:42:09.124678Z","steps":["trace[494842229] 'process raft request' (duration: 123.952102ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:09.947851Z","caller":"traceutil/trace.go:171","msg":"trace[647598229] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"248.402481ms","start":"2026-04-07T09:42:09.699428Z","end":"2026-04-07T09:42:09.947831Z","steps":["trace[647598229] 'process raft request' (duration: 248.270329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:10.146113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.494589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:42:10.146488Z","caller":"traceutil/trace.go:171","msg":"trace[1324786476] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"214.970602ms","start":"2026-04-07T09:42:09.931488Z","end":"2026-04-07T09:42:10.146458Z","steps":["trace[1324786476] 'process raft request' (duration: 79.063577ms)","trace[1324786476] 'compare' (duration: 135.357965ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:10.424508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.736519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:42:10.424597Z","caller":"traceutil/trace.go:171","msg":"trace[248402404] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:378; }","duration":"233.248308ms","start":"2026-04-07T09:42:10.191334Z","end":"2026-04-07T09:42:10.424582Z","steps":["trace[248402404] 'read index received' (duration: 109.270145ms)","trace[248402404] 'applied index is now lower than readState.Index' (duration: 123.977103ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:10.424678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.35804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:10.424711Z","caller":"traceutil/trace.go:171","msg":"trace[640898903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:364; }","duration":"233.437531ms","start":"2026-04-07T09:42:10.191265Z","end":"2026-04-07T09:42:10.424703Z","steps":["trace[640898903] 'agreement among raft nodes before linearized reading' (duration: 233.36901ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:10.424993Z","caller":"traceutil/trace.go:171","msg":"trace[893305177] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"269.234944ms","start":"2026-04-07T09:42:10.155745Z","end":"2026-04-07T09:42:10.42498Z","steps":["trace[893305177] 'process raft request' (duration: 144.923026ms)","trace[893305177] 'compare' (duration: 123.625886ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:10.60041Z","caller":"traceutil/trace.go:171","msg":"trace[1256384817] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"165.222477ms","start":"2026-04-07T09:42:10.435139Z","end":"2026-04-07T09:42:10.600361Z","steps":["trace[1256384817] 'process raft request' (duration: 132.395961ms)","trace[1256384817] 'compare' (duration: 32.47117ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:13.477872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566217557642840,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:42:13.978879Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566217557642840,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:42:14.480111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566217557642840,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:42:14.716374Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.061770855s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T09:42:14.716805Z","caller":"traceutil/trace.go:171","msg":"trace[425298244] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"2.062293495s","start":"2026-04-07T09:42:12.654489Z","end":"2026-04-07T09:42:14.716782Z","steps":["trace[425298244] 'process raft request' (duration: 2.062152312s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:14.717172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:12.654473Z","time spent":"2.06261802s","remote":"127.0.0.1:38894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:42:14.957116Z","caller":"traceutil/trace.go:171","msg":"trace[523102584] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:386; }","duration":"1.980161786s","start":"2026-04-07T09:42:12.976925Z","end":"2026-04-07T09:42:14.957087Z","steps":["trace[523102584] 'read index received' (duration: 1.739663172s)","trace[523102584] 'applied index is now lower than readState.Index' (duration: 240.496964ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:14.957315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.98037325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:14.957401Z","caller":"traceutil/trace.go:171","msg":"trace[1752643224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:372; }","duration":"1.980511313s","start":"2026-04-07T09:42:12.976869Z","end":"2026-04-07T09:42:14.95738Z","steps":["trace[1752643224] 'agreement among raft nodes before linearized reading' (duration: 1.98035979s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:14.957457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:12.976848Z","time spent":"1.980598405s","remote":"127.0.0.1:38738","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-04-07T09:42:14.957634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.79397433s","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-04-07T09:42:14.957697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.786880516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-07T09:42:14.957686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.785653364s","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-04-07T09:42:14.957759Z","caller":"traceutil/trace.go:171","msg":"trace[828844627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:372; }","duration":"1.786999859s","start":"2026-04-07T09:42:13.170743Z","end":"2026-04-07T09:42:14.957743Z","steps":["trace[828844627] 'agreement among raft nodes before linearized reading' (duration: 1.786900017s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:14.957822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:13.170722Z","time spent":"1.787082231s","remote":"127.0.0.1:39008","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-04-07T09:42:14.957948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.5885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-04-07T09:42:14.957774Z","caller":"traceutil/trace.go:171","msg":"trace[1348536007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:372; }","duration":"1.785763786s","start":"2026-04-07T09:42:13.171994Z","end":"2026-04-07T09:42:14.957758Z","steps":["trace[1348536007] 'agreement among raft nodes before linearized reading' (duration: 1.785561362s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:14.957998Z","caller":"traceutil/trace.go:171","msg":"trace[1120658754] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:372; }","duration":"237.671701ms","start":"2026-04-07T09:42:14.720314Z","end":"2026-04-07T09:42:14.957986Z","steps":["trace[1120658754] 'agreement among raft nodes before linearized reading' (duration: 237.575979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:14.958049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:13.171987Z","time spent":"1.786042691s","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T09:42:14.958235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.787468468s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-07T09:42:14.958276Z","caller":"traceutil/trace.go:171","msg":"trace[764952744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:372; }","duration":"1.787545229s","start":"2026-04-07T09:42:13.17072Z","end":"2026-04-07T09:42:14.958265Z","steps":["trace[764952744] 'agreement among raft nodes before linearized reading' (duration: 1.787481328s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:14.95834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:13.170696Z","time spent":"1.78763631s","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T09:42:14.958612Z","caller":"traceutil/trace.go:171","msg":"trace[1920050930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:372; }","duration":"1.794141743s","start":"2026-04-07T09:42:13.163587Z","end":"2026-04-07T09:42:14.957729Z","steps":["trace[1920050930] 'agreement among raft nodes before linearized reading' (duration: 1.793775847s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:14.958681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:13.163568Z","time spent":"1.795099211s","remote":"127.0.0.1:39008","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-04-07T09:42:15.098874Z","caller":"traceutil/trace.go:171","msg":"trace[357216413] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"134.928457ms","start":"2026-04-07T09:42:14.963911Z","end":"2026-04-07T09:42:15.09884Z","steps":["trace[357216413] 'process raft request' (duration: 134.320516ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:15.099149Z","caller":"traceutil/trace.go:171","msg":"trace[1810765186] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"134.695633ms","start":"2026-04-07T09:42:14.964425Z","end":"2026-04-07T09:42:15.099121Z","steps":["trace[1810765186] 'process raft request' (duration: 134.320336ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:15.099483Z","caller":"traceutil/trace.go:171","msg":"trace[188608402] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"134.802454ms","start":"2026-04-07T09:42:14.964658Z","end":"2026-04-07T09:42:15.099461Z","steps":["trace[188608402] 'process raft request' (duration: 134.352906ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:15.099482Z","caller":"traceutil/trace.go:171","msg":"trace[957129141] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:389; }","duration":"133.814966ms","start":"2026-04-07T09:42:14.96563Z","end":"2026-04-07T09:42:15.099445Z","steps":["trace[957129141] 'read index received' (duration: 132.784647ms)","trace[957129141] 'applied index is now lower than readState.Index' (duration: 1.027649ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:15.099595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.947048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:15.099651Z","caller":"traceutil/trace.go:171","msg":"trace[1197921438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:376; }","duration":"134.06626ms","start":"2026-04-07T09:42:14.965565Z","end":"2026-04-07T09:42:15.099632Z","steps":["trace[1197921438] 'agreement among raft nodes before linearized reading' (duration: 133.978659ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:15.135585Z","caller":"traceutil/trace.go:171","msg":"trace[1151852508] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"169.338221ms","start":"2026-04-07T09:42:14.96622Z","end":"2026-04-07T09:42:15.135559Z","steps":["trace[1151852508] 'process raft request' (duration: 169.194709ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:17.079593Z","caller":"traceutil/trace.go:171","msg":"trace[1730952271] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:393; }","duration":"100.734246ms","start":"2026-04-07T09:42:16.978837Z","end":"2026-04-07T09:42:17.079571Z","steps":["trace[1730952271] 'read index received' (duration: 27.51µs)","trace[1730952271] 'applied index is now lower than readState.Index' (duration: 100.705786ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:17.079701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.841838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:17.079701Z","caller":"traceutil/trace.go:171","msg":"trace[1819607768] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"100.855469ms","start":"2026-04-07T09:42:16.978814Z","end":"2026-04-07T09:42:17.07967Z","steps":["trace[1819607768] 'process raft request' (duration: 100.618255ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:17.079747Z","caller":"traceutil/trace.go:171","msg":"trace[1605888971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:378; }","duration":"100.91644ms","start":"2026-04-07T09:42:16.978812Z","end":"2026-04-07T09:42:17.079728Z","steps":["trace[1605888971] 'agreement among raft nodes before linearized reading' (duration: 100.846289ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:19.74702Z","caller":"traceutil/trace.go:171","msg":"trace[897080505] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"124.262468ms","start":"2026-04-07T09:42:19.622734Z","end":"2026-04-07T09:42:19.746997Z","steps":["trace[897080505] 'process raft request' (duration: 124.117145ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:21.354078Z","caller":"traceutil/trace.go:171","msg":"trace[1647729077] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:406; }","duration":"148.251387ms","start":"2026-04-07T09:42:21.205794Z","end":"2026-04-07T09:42:21.354046Z","steps":["trace[1647729077] 'read index received' (duration: 148.050664ms)","trace[1647729077] 'applied index is now lower than readState.Index' (duration: 199.363µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:21.354125Z","caller":"traceutil/trace.go:171","msg":"trace[706537352] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"182.323915ms","start":"2026-04-07T09:42:21.171775Z","end":"2026-04-07T09:42:21.354099Z","steps":["trace[706537352] 'process raft request' (duration: 182.095461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:21.354311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.442651ms","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-04-07T09:42:21.354354Z","caller":"traceutil/trace.go:171","msg":"trace[449676324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:390; }","duration":"148.583633ms","start":"2026-04-07T09:42:21.205756Z","end":"2026-04-07T09:42:21.35434Z","steps":["trace[449676324] 'agreement among raft nodes before linearized reading' (duration: 148.44009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:21.354384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.43176ms","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-04-07T09:42:21.354467Z","caller":"traceutil/trace.go:171","msg":"trace[1039152889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:390; }","duration":"148.566363ms","start":"2026-04-07T09:42:21.205876Z","end":"2026-04-07T09:42:21.354443Z","steps":["trace[1039152889] 'agreement among raft nodes before linearized reading' (duration: 148.4ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:21.354516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.446481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-07T09:42:21.354581Z","caller":"traceutil/trace.go:171","msg":"trace[1375191522] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:390; }","duration":"148.529302ms","start":"2026-04-07T09:42:21.206036Z","end":"2026-04-07T09:42:21.354566Z","steps":["trace[1375191522] 'agreement among raft nodes before linearized reading' (duration: 148.41372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:21.354706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.013343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-04-07T09:42:21.354756Z","caller":"traceutil/trace.go:171","msg":"trace[2035554527] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:390; }","duration":"139.087614ms","start":"2026-04-07T09:42:21.215657Z","end":"2026-04-07T09:42:21.354745Z","steps":["trace[2035554527] 'agreement among raft nodes before linearized reading' (duration: 138.788839ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:21.534126Z","caller":"traceutil/trace.go:171","msg":"trace[892129291] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"109.643983ms","start":"2026-04-07T09:42:21.424442Z","end":"2026-04-07T09:42:21.534086Z","steps":["trace[892129291] 'process raft request' (duration: 90.828981ms)","trace[892129291] 'compare' (duration: 18.612508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:21.764707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.741391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:42:21.764888Z","caller":"traceutil/trace.go:171","msg":"trace[1698355474] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"222.206493ms","start":"2026-04-07T09:42:21.542665Z","end":"2026-04-07T09:42:21.764872Z","steps":["trace[1698355474] 'process raft request' (duration: 222.128832ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:21.764951Z","caller":"traceutil/trace.go:171","msg":"trace[2065914011] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"224.543575ms","start":"2026-04-07T09:42:21.54033Z","end":"2026-04-07T09:42:21.764874Z","steps":["trace[2065914011] 'process raft request' (duration: 110.5659ms)","trace[2065914011] 'compare' (duration: 113.603378ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:21.944154Z","caller":"traceutil/trace.go:171","msg":"trace[1159641685] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"171.662546ms","start":"2026-04-07T09:42:21.772459Z","end":"2026-04-07T09:42:21.944121Z","steps":["trace[1159641685] 'process raft request' (duration: 144.507597ms)","trace[1159641685] 'compare' (duration: 26.939665ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:21.944591Z","caller":"traceutil/trace.go:171","msg":"trace[628435150] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"171.267747ms","start":"2026-04-07T09:42:21.773298Z","end":"2026-04-07T09:42:21.944565Z","steps":["trace[628435150] 'process raft request' (duration: 170.992772ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:21.944599Z","caller":"traceutil/trace.go:171","msg":"trace[1876022529] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"171.359609ms","start":"2026-04-07T09:42:21.773204Z","end":"2026-04-07T09:42:21.944564Z","steps":["trace[1876022529] 'process raft request' (duration: 170.8707ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:21.945324Z","caller":"traceutil/trace.go:171","msg":"trace[905659810] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"171.308319ms","start":"2026-04-07T09:42:21.773984Z","end":"2026-04-07T09:42:21.945292Z","steps":["trace[905659810] 'process raft request' (duration: 170.359811ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:22.145392Z","caller":"traceutil/trace.go:171","msg":"trace[1163474790] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"193.611227ms","start":"2026-04-07T09:42:21.951754Z","end":"2026-04-07T09:42:22.145365Z","steps":["trace[1163474790] 'process raft request' (duration: 110.036302ms)","trace[1163474790] 'compare' (duration: 83.298659ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:22.145391Z","caller":"traceutil/trace.go:171","msg":"trace[993563834] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:421; }","duration":"193.348391ms","start":"2026-04-07T09:42:21.952019Z","end":"2026-04-07T09:42:22.145368Z","steps":["trace[993563834] 'read index received' (duration: 16.428417ms)","trace[993563834] 'applied index is now lower than readState.Index' (duration: 176.919014ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:22.145562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.538955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3855"} {"level":"info","ts":"2026-04-07T09:42:22.145586Z","caller":"traceutil/trace.go:171","msg":"trace[1638327866] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:407; }","duration":"193.603056ms","start":"2026-04-07T09:42:21.951978Z","end":"2026-04-07T09:42:22.145581Z","steps":["trace[1638327866] 'agreement among raft nodes before linearized reading' (duration: 193.456624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:22.145598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.146546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:22.145584Z","caller":"traceutil/trace.go:171","msg":"trace[855998689] transaction","detail":"{read_only:false; number_of_response:1; response_revision:407; }","duration":"175.430676ms","start":"2026-04-07T09:42:21.97013Z","end":"2026-04-07T09:42:22.145561Z","steps":["trace[855998689] 'process raft request' (duration: 175.185251ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:22.145635Z","caller":"traceutil/trace.go:171","msg":"trace[1642993069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"170.212758ms","start":"2026-04-07T09:42:21.975413Z","end":"2026-04-07T09:42:22.145625Z","steps":["trace[1642993069] 'agreement among raft nodes before linearized reading' (duration: 170.150987ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:22.145648Z","caller":"traceutil/trace.go:171","msg":"trace[1727776852] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"172.954279ms","start":"2026-04-07T09:42:21.972688Z","end":"2026-04-07T09:42:22.145643Z","steps":["trace[1727776852] 'process raft request' (duration: 172.805737ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:22.398232Z","caller":"traceutil/trace.go:171","msg":"trace[1579352963] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:428; }","duration":"193.31454ms","start":"2026-04-07T09:42:22.20489Z","end":"2026-04-07T09:42:22.398204Z","steps":["trace[1579352963] 'read index received' (duration: 33.849544ms)","trace[1579352963] 'applied index is now lower than readState.Index' (duration: 159.461426ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:22.398402Z","caller":"traceutil/trace.go:171","msg":"trace[1240231243] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"194.225078ms","start":"2026-04-07T09:42:22.204146Z","end":"2026-04-07T09:42:22.398371Z","steps":["trace[1240231243] 'process raft request' (duration: 128.541087ms)","trace[1240231243] 'compare' (duration: 65.149161ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:22.398632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.612856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-04-07T09:42:22.398655Z","caller":"traceutil/trace.go:171","msg":"trace[1045129065] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"192.358563ms","start":"2026-04-07T09:42:22.206241Z","end":"2026-04-07T09:42:22.3986Z","steps":["trace[1045129065] 'process raft request' (duration: 191.867644ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:22.398746Z","caller":"traceutil/trace.go:171","msg":"trace[317832534] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:414; }","duration":"193.826189ms","start":"2026-04-07T09:42:22.204858Z","end":"2026-04-07T09:42:22.398684Z","steps":["trace[317832534] 'agreement among raft nodes before linearized reading' (duration: 193.535864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:22.697669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.086585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:42:22.697757Z","caller":"traceutil/trace.go:171","msg":"trace[778117852] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"231.852333ms","start":"2026-04-07T09:42:22.465892Z","end":"2026-04-07T09:42:22.697744Z","steps":["trace[778117852] 'read index received' (duration: 116.552504ms)","trace[778117852] 'applied index is now lower than readState.Index' (duration: 115.297119ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:22.697806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.914924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-04-07T09:42:22.697827Z","caller":"traceutil/trace.go:171","msg":"trace[160541569] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:420; }","duration":"231.971355ms","start":"2026-04-07T09:42:22.46585Z","end":"2026-04-07T09:42:22.697821Z","steps":["trace[160541569] 'agreement among raft nodes before linearized reading' (duration: 231.926864ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:22.697863Z","caller":"traceutil/trace.go:171","msg":"trace[1992197197] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"231.983245ms","start":"2026-04-07T09:42:22.465851Z","end":"2026-04-07T09:42:22.697834Z","steps":["trace[1992197197] 'process raft request' (duration: 116.665706ms)","trace[1992197197] 'compare' (duration: 114.985533ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:22.716099Z","caller":"traceutil/trace.go:171","msg":"trace[116526778] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"244.481589ms","start":"2026-04-07T09:42:22.471599Z","end":"2026-04-07T09:42:22.71608Z","steps":["trace[116526778] 'process raft request' (duration: 244.086421ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:35.003201Z","caller":"traceutil/trace.go:171","msg":"trace[344208907] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"121.799801ms","start":"2026-04-07T09:42:34.881372Z","end":"2026-04-07T09:42:35.003172Z","steps":["trace[344208907] 'process raft request' (duration: 68.37933ms)","trace[344208907] 'compare' (duration: 52.426973ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:40.885162Z","caller":"traceutil/trace.go:171","msg":"trace[2112424433] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"136.255372ms","start":"2026-04-07T09:42:40.748885Z","end":"2026-04-07T09:42:40.885141Z","steps":["trace[2112424433] 'process raft request' (duration: 136.077659ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:41.433124Z","caller":"traceutil/trace.go:171","msg":"trace[2061709316] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"384.702886ms","start":"2026-04-07T09:42:41.048391Z","end":"2026-04-07T09:42:41.433094Z","steps":["trace[2061709316] 'process raft request' (duration: 384.471692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:41.433642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:41.048377Z","time spent":"385.068023ms","remote":"127.0.0.1:38894","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-04-07T09:43:51.238051Z","caller":"traceutil/trace.go:171","msg":"trace[699332388] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"245.144607ms","start":"2026-04-07T09:43:50.992885Z","end":"2026-04-07T09:43:51.238029Z","steps":["trace[699332388] 'process raft request' (duration: 154.214432ms)","trace[699332388] 'compare' (duration: 90.634669ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:52.025725Z","caller":"traceutil/trace.go:171","msg":"trace[1403348684] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"133.121626ms","start":"2026-04-07T09:43:51.892576Z","end":"2026-04-07T09:43:52.025698Z","steps":["trace[1403348684] 'read index received' (duration: 132.890101ms)","trace[1403348684] 'applied index is now lower than readState.Index' (duration: 230.304µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:52.02582Z","caller":"traceutil/trace.go:171","msg":"trace[2122346165] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"138.067268ms","start":"2026-04-07T09:43:51.887722Z","end":"2026-04-07T09:43:52.025789Z","steps":["trace[2122346165] 'process raft request' (duration: 137.828594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:52.025953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.35524ms","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-04-07T09:43:52.025964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.937597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T09:43:52.026035Z","caller":"traceutil/trace.go:171","msg":"trace[1251317185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1037; }","duration":"133.493972ms","start":"2026-04-07T09:43:51.892521Z","end":"2026-04-07T09:43:52.026015Z","steps":["trace[1251317185] 'agreement among raft nodes before linearized reading' (duration: 133.268878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:52.026037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.582084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T09:43:52.026053Z","caller":"traceutil/trace.go:171","msg":"trace[284714475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1037; }","duration":"113.04602ms","start":"2026-04-07T09:43:51.912982Z","end":"2026-04-07T09:43:52.026028Z","steps":["trace[284714475] 'agreement among raft nodes before linearized reading' (duration: 112.867406ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:52.026085Z","caller":"traceutil/trace.go:171","msg":"trace[1080648910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1037; }","duration":"109.675196ms","start":"2026-04-07T09:43:51.916397Z","end":"2026-04-07T09:43:52.026072Z","steps":["trace[1080648910] 'agreement among raft nodes before linearized reading' (duration: 109.468713ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:52.165878Z","caller":"traceutil/trace.go:171","msg":"trace[1741889570] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"135.874307ms","start":"2026-04-07T09:43:52.029986Z","end":"2026-04-07T09:43:52.16586Z","steps":["trace[1741889570] 'process raft request' (duration: 135.773875ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:52.170367Z","caller":"traceutil/trace.go:171","msg":"trace[1255053963] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"130.758302ms","start":"2026-04-07T09:43:52.039601Z","end":"2026-04-07T09:43:52.170359Z","steps":["trace[1255053963] 'process raft request' (duration: 130.732431ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:52.170446Z","caller":"traceutil/trace.go:171","msg":"trace[972109436] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"139.521586ms","start":"2026-04-07T09:43:52.0309Z","end":"2026-04-07T09:43:52.170422Z","steps":["trace[972109436] 'process raft request' (duration: 139.414714ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:52.170446Z","caller":"traceutil/trace.go:171","msg":"trace[573960681] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"139.71569ms","start":"2026-04-07T09:43:52.030691Z","end":"2026-04-07T09:43:52.170407Z","steps":["trace[573960681] 'process raft request' (duration: 139.570437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:53.209658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.981825ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T09:43:53.210527Z","caller":"traceutil/trace.go:171","msg":"trace[1774447444] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1085; }","duration":"233.817923ms","start":"2026-04-07T09:43:52.976695Z","end":"2026-04-07T09:43:53.210513Z","steps":["trace[1774447444] 'read index received' (duration: 14.034593ms)","trace[1774447444] 'applied index is now lower than readState.Index' (duration: 219.78274ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:53.210771Z","caller":"traceutil/trace.go:171","msg":"trace[724334449] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"239.362868ms","start":"2026-04-07T09:43:52.971396Z","end":"2026-04-07T09:43:53.210758Z","steps":["trace[724334449] 'process raft request' (duration: 238.9389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:53.210913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.925395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:43:53.211054Z","caller":"traceutil/trace.go:171","msg":"trace[1014561680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"234.394724ms","start":"2026-04-07T09:43:52.976648Z","end":"2026-04-07T09:43:53.211042Z","steps":["trace[1014561680] 'agreement among raft nodes before linearized reading' (duration: 233.937265ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:05.629798Z","caller":"traceutil/trace.go:171","msg":"trace[2018571661] linearizableReadLoop","detail":"{readStateIndex:1182; appliedIndex:1181; }","duration":"340.586375ms","start":"2026-04-07T09:44:05.289189Z","end":"2026-04-07T09:44:05.629775Z","steps":["trace[2018571661] 'read index received' (duration: 340.380071ms)","trace[2018571661] 'applied index is now lower than readState.Index' (duration: 205.054µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:44:05.629958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.752028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:44:05.629934Z","caller":"traceutil/trace.go:171","msg":"trace[1931596700] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"377.333003ms","start":"2026-04-07T09:44:05.252549Z","end":"2026-04-07T09:44:05.629882Z","steps":["trace[1931596700] 'process raft request' (duration: 377.097478ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:05.629993Z","caller":"traceutil/trace.go:171","msg":"trace[1901702019] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1133; }","duration":"340.832419ms","start":"2026-04-07T09:44:05.28915Z","end":"2026-04-07T09:44:05.629982Z","steps":["trace[1901702019] 'agreement among raft nodes before linearized reading' (duration: 340.754117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:05.63003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:05.289138Z","time spent":"340.8835ms","remote":"127.0.0.1:49518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:05.630135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.539314ms","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-04-07T09:44:05.63014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:05.252521Z","time spent":"377.485296ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:44:05.630192Z","caller":"traceutil/trace.go:171","msg":"trace[622982775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1133; }","duration":"284.659786ms","start":"2026-04-07T09:44:05.345519Z","end":"2026-04-07T09:44:05.630179Z","steps":["trace[622982775] 'agreement among raft nodes before linearized reading' (duration: 284.447312ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:22.470818Z","caller":"traceutil/trace.go:171","msg":"trace[1360373446] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"109.332139ms","start":"2026-04-07T09:44:22.361463Z","end":"2026-04-07T09:44:22.470796Z","steps":["trace[1360373446] 'process raft request' (duration: 109.179176ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:22.493374Z","caller":"traceutil/trace.go:171","msg":"trace[798600773] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"130.321013ms","start":"2026-04-07T09:44:22.363036Z","end":"2026-04-07T09:44:22.493357Z","steps":["trace[798600773] 'process raft request' (duration: 130.205131ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:22.493741Z","caller":"traceutil/trace.go:171","msg":"trace[786865567] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"130.349704ms","start":"2026-04-07T09:44:22.363326Z","end":"2026-04-07T09:44:22.493676Z","steps":["trace[786865567] 'process raft request' (duration: 129.995088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:23.13026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.520988ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T09:44:23.130391Z","caller":"traceutil/trace.go:171","msg":"trace[95414255] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1260; }","duration":"154.456629ms","start":"2026-04-07T09:44:22.975914Z","end":"2026-04-07T09:44:23.130371Z","steps":["trace[95414255] 'read index received' (duration: 44.421µs)","trace[95414255] 'applied index is now lower than readState.Index' (duration: 154.410608ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:44:23.130469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.54438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:23.130501Z","caller":"traceutil/trace.go:171","msg":"trace[621630426] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1208; }","duration":"154.583571ms","start":"2026-04-07T09:44:22.975909Z","end":"2026-04-07T09:44:23.130492Z","steps":["trace[621630426] 'agreement among raft nodes before linearized reading' (duration: 154.51768ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:36.863894Z","caller":"traceutil/trace.go:171","msg":"trace[1359056182] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"240.695502ms","start":"2026-04-07T09:44:36.62317Z","end":"2026-04-07T09:44:36.863866Z","steps":["trace[1359056182] 'process raft request' (duration: 161.576083ms)","trace[1359056182] 'compare' (duration: 78.891325ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:44:36.881485Z","caller":"traceutil/trace.go:171","msg":"trace[800731241] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"257.198932ms","start":"2026-04-07T09:44:36.624268Z","end":"2026-04-07T09:44:36.881467Z","steps":["trace[800731241] 'process raft request' (duration: 257.017419ms)"],"step_count":1}