{"level":"warn","ts":"2026-10-10T10:24:25.707018Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-10T10:24:25.707094Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.22:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.22:2380","--initial-cluster=instance=https://162.253.55.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.22: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-10-10T10:24:25.707153Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-10T10:24:25.707162Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.22:2380"]} {"level":"info","ts":"2026-10-10T10:24:25.707191Z","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-10-10T10:24:25.707776Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"]} {"level":"info","ts":"2026-10-10T10:24:25.707844Z","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://162.253.55.22:2380"],"listen-peer-urls":["https://162.253.55.22:2380"],"advertise-client-urls":["https://162.253.55.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.22: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-10-10T10:24:25.713454Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.442767ms"} {"level":"info","ts":"2026-10-10T10:24:25.727409Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8608f4af3693a53b","cluster-id":"c541111188849f0e"} {"level":"info","ts":"2026-10-10T10:24:25.727464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=()"} {"level":"info","ts":"2026-10-10T10:24:25.727486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became follower at term 0"} {"level":"info","ts":"2026-10-10T10:24:25.727496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8608f4af3693a53b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-10T10:24:25.727503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became follower at term 1"} {"level":"info","ts":"2026-10-10T10:24:25.727533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=(9658238434268128571)"} {"level":"warn","ts":"2026-10-10T10:24:25.740798Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-10T10:24:25.74441Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-10T10:24:25.745779Z","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-10-10T10:24:25.748222Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8608f4af3693a53b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-10T10:24:25.748591Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8608f4af3693a53b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-10T10:24:25.74866Z","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-10-10T10:24:25.748683Z","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-10-10T10:24:25.748689Z","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-10-10T10:24:25.751843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=(9658238434268128571)"} {"level":"info","ts":"2026-10-10T10:24:25.751965Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c541111188849f0e","local-member-id":"8608f4af3693a53b","added-peer-id":"8608f4af3693a53b","added-peer-peer-urls":["https://162.253.55.22:2380"]} {"level":"info","ts":"2026-10-10T10:24:25.755402Z","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-10-10T10:24:25.755506Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.22:2380"} {"level":"info","ts":"2026-10-10T10:24:25.755547Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.22:2380"} {"level":"info","ts":"2026-10-10T10:24:25.755624Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8608f4af3693a53b","initial-advertise-peer-urls":["https://162.253.55.22:2380"],"listen-peer-urls":["https://162.253.55.22:2380"],"advertise-client-urls":["https://162.253.55.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-10T10:24:25.755664Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-10T10:24:26.628401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b is starting a new election at term 1"} {"level":"info","ts":"2026-10-10T10:24:26.628462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-10T10:24:26.628495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b received MsgPreVoteResp from 8608f4af3693a53b at term 1"} {"level":"info","ts":"2026-10-10T10:24:26.628577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became candidate at term 2"} {"level":"info","ts":"2026-10-10T10:24:26.628589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b received MsgVoteResp from 8608f4af3693a53b at term 2"} {"level":"info","ts":"2026-10-10T10:24:26.628641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became leader at term 2"} {"level":"info","ts":"2026-10-10T10:24:26.628663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8608f4af3693a53b elected leader 8608f4af3693a53b at term 2"} {"level":"info","ts":"2026-10-10T10:24:26.629911Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8608f4af3693a53b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.22:2379]}","request-path":"/0/members/8608f4af3693a53b/attributes","cluster-id":"c541111188849f0e","publish-timeout":"7s"} {"level":"info","ts":"2026-10-10T10:24:26.629957Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-10T10:24:26.630103Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-10T10:24:26.630331Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-10T10:24:26.630414Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-10T10:24:26.63041Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-10T10:24:26.631324Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c541111188849f0e","local-member-id":"8608f4af3693a53b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-10T10:24:26.631429Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-10T10:24:26.631465Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-10T10:24:26.633046Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.22:2379"} {"level":"info","ts":"2026-10-10T10:24:26.633847Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-10T10:24:29.365518Z","caller":"traceutil/trace.go:171","msg":"trace[986894041] transaction","detail":"{read_only:false; response_revision:126; number_of_response:1; }","duration":"128.217069ms","start":"2026-10-10T10:24:29.237234Z","end":"2026-10-10T10:24:29.365451Z","steps":["trace[986894041] 'process raft request' (duration: 128.053996ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:24:34.787657Z","caller":"traceutil/trace.go:171","msg":"trace[293241071] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:344; }","duration":"304.041982ms","start":"2026-10-10T10:24:34.483595Z","end":"2026-10-10T10:24:34.787637Z","steps":["trace[293241071] 'read index received' (duration: 303.872829ms)","trace[293241071] 'applied index is now lower than readState.Index' (duration: 168.383µs)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:24:34.787799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.180924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-10-10T10:24:34.78787Z","caller":"traceutil/trace.go:171","msg":"trace[1195829928] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:330; }","duration":"304.296826ms","start":"2026-10-10T10:24:34.483562Z","end":"2026-10-10T10:24:34.787859Z","steps":["trace[1195829928] 'agreement among raft nodes before linearized reading' (duration: 304.163684ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:24:34.787838Z","caller":"traceutil/trace.go:171","msg":"trace[1748500905] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"309.890845ms","start":"2026-10-10T10:24:34.477876Z","end":"2026-10-10T10:24:34.787767Z","steps":["trace[1748500905] 'process raft request' (duration: 309.651941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:24:34.787902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:24:34.483544Z","time spent":"304.349907ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-10T10:24:34.788781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:24:34.47786Z","time spent":"310.089739ms","remote":"127.0.0.1:54670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:24:34.981109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.340265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:24:34.981245Z","caller":"traceutil/trace.go:171","msg":"trace[409771297] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"189.602115ms","start":"2026-10-10T10:24:34.791621Z","end":"2026-10-10T10:24:34.981224Z","steps":["trace[409771297] 'compare' (duration: 178.163472ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:24:35.200859Z","caller":"traceutil/trace.go:171","msg":"trace[1110799191] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"210.469015ms","start":"2026-10-10T10:24:34.990365Z","end":"2026-10-10T10:24:35.200834Z","steps":["trace[1110799191] 'process raft request' (duration: 189.27246ms)","trace[1110799191] 'compare' (duration: 21.091383ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:25:07.817193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.205796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:25:07.817302Z","caller":"traceutil/trace.go:171","msg":"trace[1153096343] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"379.945424ms","start":"2026-10-10T10:25:07.437335Z","end":"2026-10-10T10:25:07.817281Z","steps":["trace[1153096343] 'process raft request' (duration: 128.562065ms)","trace[1153096343] 'compare' (duration: 251.100504ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:25:07.81748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:25:07.437319Z","time spent":"380.123428ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":734,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-10T10:25:08.278453Z","caller":"traceutil/trace.go:171","msg":"trace[224071511] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:536; }","duration":"205.888253ms","start":"2026-10-10T10:25:08.072536Z","end":"2026-10-10T10:25:08.278424Z","steps":["trace[224071511] 'read index received' (duration: 205.395304ms)","trace[224071511] 'applied index is now lower than readState.Index' (duration: 491.849µs)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:25:08.278594Z","caller":"traceutil/trace.go:171","msg":"trace[156135032] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"246.964091ms","start":"2026-10-10T10:25:08.031594Z","end":"2026-10-10T10:25:08.278558Z","steps":["trace[156135032] 'process raft request' (duration: 246.705106ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:25:08.278716Z","caller":"traceutil/trace.go:171","msg":"trace[1945762931] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"246.164317ms","start":"2026-10-10T10:25:08.032517Z","end":"2026-10-10T10:25:08.278681Z","steps":["trace[1945762931] 'process raft request' (duration: 245.844801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:25:08.278796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.233199ms","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-10-10T10:25:08.278603Z","caller":"traceutil/trace.go:171","msg":"trace[1021382156] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"247.845956ms","start":"2026-10-10T10:25:08.030735Z","end":"2026-10-10T10:25:08.278581Z","steps":["trace[1021382156] 'process raft request' (duration: 247.170244ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:25:08.278868Z","caller":"traceutil/trace.go:171","msg":"trace[2055116195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"206.354672ms","start":"2026-10-10T10:25:08.072496Z","end":"2026-10-10T10:25:08.27885Z","steps":["trace[2055116195] 'agreement among raft nodes before linearized reading' (duration: 206.206729ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:25:08.286725Z","caller":"traceutil/trace.go:171","msg":"trace[310195822] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"201.248502ms","start":"2026-10-10T10:25:08.085452Z","end":"2026-10-10T10:25:08.286701Z","steps":["trace[310195822] 'process raft request' (duration: 201.001557ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:25:08.288309Z","caller":"traceutil/trace.go:171","msg":"trace[1045340870] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"200.474919ms","start":"2026-10-10T10:25:08.087782Z","end":"2026-10-10T10:25:08.288257Z","steps":["trace[1045340870] 'process raft request' (duration: 198.808829ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:25:08.289329Z","caller":"traceutil/trace.go:171","msg":"trace[2139229961] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"203.697624ms","start":"2026-10-10T10:25:08.085602Z","end":"2026-10-10T10:25:08.2893Z","steps":["trace[2139229961] 'process raft request' (duration: 200.917395ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:25:08.289968Z","caller":"traceutil/trace.go:171","msg":"trace[1816036144] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"212.532192ms","start":"2026-10-10T10:25:08.074052Z","end":"2026-10-10T10:25:08.286584Z","steps":["trace[1816036144] 'process raft request' (duration: 211.253949ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:25:10.437676Z","caller":"traceutil/trace.go:171","msg":"trace[1219678698] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"259.631905ms","start":"2026-10-10T10:25:10.178024Z","end":"2026-10-10T10:25:10.437656Z","steps":["trace[1219678698] 'process raft request' (duration: 254.497034ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:25:10.438671Z","caller":"traceutil/trace.go:171","msg":"trace[529287293] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"259.89489ms","start":"2026-10-10T10:25:10.178103Z","end":"2026-10-10T10:25:10.437998Z","steps":["trace[529287293] 'process raft request' (duration: 259.703796ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:25:10.440177Z","caller":"traceutil/trace.go:171","msg":"trace[753579974] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:599; }","duration":"186.783936ms","start":"2026-10-10T10:25:10.253317Z","end":"2026-10-10T10:25:10.440101Z","steps":["trace[753579974] 'read index received' (duration: 179.254213ms)","trace[753579974] 'applied index is now lower than readState.Index' (duration: 7.528163ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:25:10.440784Z","caller":"traceutil/trace.go:171","msg":"trace[327399684] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"213.805754ms","start":"2026-10-10T10:25:10.226823Z","end":"2026-10-10T10:25:10.440628Z","steps":["trace[327399684] 'process raft request' (duration: 211.771549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:25:10.441333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.358565ms","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-10-10T10:25:10.441397Z","caller":"traceutil/trace.go:171","msg":"trace[261587247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"144.456988ms","start":"2026-10-10T10:25:10.296914Z","end":"2026-10-10T10:25:10.441371Z","steps":["trace[261587247] 'agreement among raft nodes before linearized reading' (duration: 143.839506ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:25:10.442539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.213518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:25:10.442601Z","caller":"traceutil/trace.go:171","msg":"trace[1501173704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"189.28949ms","start":"2026-10-10T10:25:10.253282Z","end":"2026-10-10T10:25:10.442572Z","steps":["trace[1501173704] 'agreement among raft nodes before linearized reading' (duration: 187.102452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:25:16.897186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.270923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:25:16.89746Z","caller":"traceutil/trace.go:171","msg":"trace[277140999] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:671; }","duration":"134.984398ms","start":"2026-10-10T10:25:16.762459Z","end":"2026-10-10T10:25:16.897443Z","steps":["trace[277140999] 'read index received' (duration: 42.621µs)","trace[277140999] 'applied index is now lower than readState.Index' (duration: 134.940797ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:25:16.897438Z","caller":"traceutil/trace.go:171","msg":"trace[318968744] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"370.203782ms","start":"2026-10-10T10:25:16.527194Z","end":"2026-10-10T10:25:16.897398Z","steps":["trace[318968744] 'process raft request' (duration: 146.578414ms)","trace[318968744] 'compare' (duration: 223.098529ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:25:16.89758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:25:16.52717Z","time spent":"370.377044ms","remote":"127.0.0.1:54670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:25:16.897647Z","caller":"traceutil/trace.go:171","msg":"trace[1068324142] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"303.592283ms","start":"2026-10-10T10:25:16.594046Z","end":"2026-10-10T10:25:16.897638Z","steps":["trace[1068324142] 'process raft request' (duration: 303.337769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:25:16.897753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:25:16.594021Z","time spent":"303.665943ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-10T10:25:16.898018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.529118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hc5x8\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-10T10:25:16.898063Z","caller":"traceutil/trace.go:171","msg":"trace[1238175496] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hc5x8; range_end:; response_count:1; response_revision:648; }","duration":"135.63612ms","start":"2026-10-10T10:25:16.762414Z","end":"2026-10-10T10:25:16.89805Z","steps":["trace[1238175496] 'agreement among raft nodes before linearized reading' (duration: 135.525458ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:25:16.897521Z","caller":"traceutil/trace.go:171","msg":"trace[1150505039] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"364.602752ms","start":"2026-10-10T10:25:16.532909Z","end":"2026-10-10T10:25:16.897512Z","steps":["trace[1150505039] 'process raft request' (duration: 364.39622ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:25:16.898199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:25:16.532887Z","time spent":"365.265704ms","remote":"127.0.0.1:54670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:25:17.681915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.724905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:25:17.682102Z","caller":"traceutil/trace.go:171","msg":"trace[1373656915] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"765.824353ms","start":"2026-10-10T10:25:16.916249Z","end":"2026-10-10T10:25:17.682073Z","steps":["trace[1373656915] 'process raft request' (duration: 170.862584ms)","trace[1373656915] 'compare' (duration: 594.591222ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:25:17.682331Z","caller":"traceutil/trace.go:171","msg":"trace[836534807] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"679.360424ms","start":"2026-10-10T10:25:17.002906Z","end":"2026-10-10T10:25:17.682267Z","steps":["trace[836534807] 'read index received' (duration: 84.14399ms)","trace[836534807] 'applied index is now lower than readState.Index' (duration: 595.212534ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:25:17.682445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:25:16.916229Z","time spent":"766.019127ms","remote":"127.0.0.1:54622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:25:17.682601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.681929ms","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-10-10T10:25:17.68293Z","caller":"traceutil/trace.go:171","msg":"trace[1675472404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"679.996285ms","start":"2026-10-10T10:25:17.002878Z","end":"2026-10-10T10:25:17.682874Z","steps":["trace[1675472404] 'agreement among raft nodes before linearized reading' (duration: 679.539526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:25:17.683032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:25:17.002848Z","time spent":"680.153128ms","remote":"127.0.0.1:54670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-10T10:25:17.683463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.203957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:25:17.683526Z","caller":"traceutil/trace.go:171","msg":"trace[1876282410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"429.295018ms","start":"2026-10-10T10:25:17.254213Z","end":"2026-10-10T10:25:17.683508Z","steps":["trace[1876282410] 'agreement among raft nodes before linearized reading' (duration: 429.163156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:25:17.683561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:25:17.254197Z","time spent":"429.35428ms","remote":"127.0.0.1:54420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-10T10:25:17.683739Z","caller":"traceutil/trace.go:171","msg":"trace[736508047] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"298.995673ms","start":"2026-10-10T10:25:17.384711Z","end":"2026-10-10T10:25:17.683707Z","steps":["trace[736508047] 'process raft request' (duration: 297.849552ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:25:19.062491Z","caller":"traceutil/trace.go:171","msg":"trace[1649022187] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"131.955455ms","start":"2026-10-10T10:25:18.93051Z","end":"2026-10-10T10:25:19.062465Z","steps":["trace[1649022187] 'read index received' (duration: 129.249637ms)","trace[1649022187] 'applied index is now lower than readState.Index' (duration: 2.704698ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:25:19.062557Z","caller":"traceutil/trace.go:171","msg":"trace[993293308] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"133.087435ms","start":"2026-10-10T10:25:18.929442Z","end":"2026-10-10T10:25:19.062529Z","steps":["trace[993293308] 'process raft request' (duration: 130.308066ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:25:19.062711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.16934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:25:19.063088Z","caller":"traceutil/trace.go:171","msg":"trace[963810375] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:675; }","duration":"132.569627ms","start":"2026-10-10T10:25:18.930494Z","end":"2026-10-10T10:25:19.063064Z","steps":["trace[963810375] 'agreement among raft nodes before linearized reading' (duration: 132.081638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:25:19.06506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.49419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:25:19.065122Z","caller":"traceutil/trace.go:171","msg":"trace[1218395580] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:677; }","duration":"134.576522ms","start":"2026-10-10T10:25:18.930528Z","end":"2026-10-10T10:25:19.065105Z","steps":["trace[1218395580] 'agreement among raft nodes before linearized reading' (duration: 134.46782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:25:19.06518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.259663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:25:19.065243Z","caller":"traceutil/trace.go:171","msg":"trace[731135986] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:677; }","duration":"131.352785ms","start":"2026-10-10T10:25:18.933871Z","end":"2026-10-10T10:25:19.065223Z","steps":["trace[731135986] 'agreement among raft nodes before linearized reading' (duration: 131.230383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:25:19.065307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.055182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:25:19.065339Z","caller":"traceutil/trace.go:171","msg":"trace[1233861435] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:677; }","duration":"134.094743ms","start":"2026-10-10T10:25:18.931235Z","end":"2026-10-10T10:25:19.06533Z","steps":["trace[1233861435] 'agreement among raft nodes before linearized reading' (duration: 134.045162ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:25:19.065372Z","caller":"traceutil/trace.go:171","msg":"trace[1706848967] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"132.559866ms","start":"2026-10-10T10:25:18.932797Z","end":"2026-10-10T10:25:19.065357Z","steps":["trace[1706848967] 'process raft request' (duration: 132.060527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:25:19.065447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.214936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:25:19.065484Z","caller":"traceutil/trace.go:171","msg":"trace[1074672076] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:677; }","duration":"134.262046ms","start":"2026-10-10T10:25:18.931214Z","end":"2026-10-10T10:25:19.065476Z","steps":["trace[1074672076] 'agreement among raft nodes before linearized reading' (duration: 134.213186ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:25:19.065569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.48929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:25:19.065682Z","caller":"traceutil/trace.go:171","msg":"trace[1201248314] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:677; }","duration":"134.534462ms","start":"2026-10-10T10:25:18.93106Z","end":"2026-10-10T10:25:19.065595Z","steps":["trace[1201248314] 'agreement among raft nodes before linearized reading' (duration: 134.487631ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:25:19.065727Z","caller":"traceutil/trace.go:171","msg":"trace[2000243107] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"129.951421ms","start":"2026-10-10T10:25:18.935667Z","end":"2026-10-10T10:25:19.065618Z","steps":["trace[2000243107] 'process raft request' (duration: 129.285159ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:26:11.920965Z","caller":"traceutil/trace.go:171","msg":"trace[85113744] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"104.417167ms","start":"2026-10-10T10:26:11.816233Z","end":"2026-10-10T10:26:11.92065Z","steps":["trace[85113744] 'process raft request' (duration: 104.216603ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:26:13.589049Z","caller":"traceutil/trace.go:171","msg":"trace[771421278] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1139; }","duration":"325.78769ms","start":"2026-10-10T10:26:13.263221Z","end":"2026-10-10T10:26:13.589009Z","steps":["trace[771421278] 'read index received' (duration: 325.630307ms)","trace[771421278] 'applied index is now lower than readState.Index' (duration: 156.513µs)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:26:13.589163Z","caller":"traceutil/trace.go:171","msg":"trace[787208432] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"328.053043ms","start":"2026-10-10T10:26:13.261101Z","end":"2026-10-10T10:26:13.589154Z","steps":["trace[787208432] 'process raft request' (duration: 327.765508ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:26:13.589254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:26:13.261086Z","time spent":"328.098474ms","remote":"127.0.0.1:54670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:26:13.58927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.886701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-10T10:26:13.589322Z","caller":"traceutil/trace.go:171","msg":"trace[1035368141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1099; }","duration":"168.967773ms","start":"2026-10-10T10:26:13.42034Z","end":"2026-10-10T10:26:13.589308Z","steps":["trace[1035368141] 'agreement among raft nodes before linearized reading' (duration: 168.83071ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:26:13.589429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.209329ms","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-10-10T10:26:13.589444Z","caller":"traceutil/trace.go:171","msg":"trace[1751421845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1099; }","duration":"326.246879ms","start":"2026-10-10T10:26:13.263192Z","end":"2026-10-10T10:26:13.589439Z","steps":["trace[1751421845] 'agreement among raft nodes before linearized reading' (duration: 326.202919ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:26:13.589461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:26:13.263176Z","time spent":"326.27796ms","remote":"127.0.0.1:54670","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-10-10T10:26:13.827347Z","caller":"traceutil/trace.go:171","msg":"trace[493497238] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"234.151029ms","start":"2026-10-10T10:26:13.593174Z","end":"2026-10-10T10:26:13.827325Z","steps":["trace[493497238] 'process raft request' (duration: 229.786539ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:26:13.833039Z","caller":"traceutil/trace.go:171","msg":"trace[756745085] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"106.079097ms","start":"2026-10-10T10:26:13.726938Z","end":"2026-10-10T10:26:13.833017Z","steps":["trace[756745085] 'process raft request' (duration: 106.031136ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:26:13.833402Z","caller":"traceutil/trace.go:171","msg":"trace[1416287786] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"240.156319ms","start":"2026-10-10T10:26:13.59321Z","end":"2026-10-10T10:26:13.833367Z","steps":["trace[1416287786] 'process raft request' (duration: 239.647609ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:26:34.355612Z","caller":"traceutil/trace.go:171","msg":"trace[1076697845] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1248; }","duration":"217.086526ms","start":"2026-10-10T10:26:34.138505Z","end":"2026-10-10T10:26:34.355591Z","steps":["trace[1076697845] 'read index received' (duration: 153.565246ms)","trace[1076697845] 'applied index is now lower than readState.Index' (duration: 63.52058ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:26:34.355641Z","caller":"traceutil/trace.go:171","msg":"trace[636904831] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"230.213702ms","start":"2026-10-10T10:26:34.1254Z","end":"2026-10-10T10:26:34.355614Z","steps":["trace[636904831] 'process raft request' (duration: 166.735844ms)","trace[636904831] 'compare' (duration: 63.365276ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:26:34.355778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.251979ms","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-10-10T10:26:34.355806Z","caller":"traceutil/trace.go:171","msg":"trace[1962061648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1203; }","duration":"217.336711ms","start":"2026-10-10T10:26:34.138461Z","end":"2026-10-10T10:26:34.355798Z","steps":["trace[1962061648] 'agreement among raft nodes before linearized reading' (duration: 217.210528ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:26:34.558346Z","caller":"traceutil/trace.go:171","msg":"trace[251356638] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"381.452365ms","start":"2026-10-10T10:26:34.176869Z","end":"2026-10-10T10:26:34.558322Z","steps":["trace[251356638] 'process raft request' (duration: 381.307283ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:26:34.558507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:26:34.176854Z","time spent":"381.559287ms","remote":"127.0.0.1:54606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6147,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:26:34.586339Z","caller":"traceutil/trace.go:171","msg":"trace[1371011236] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1249; }","duration":"170.230658ms","start":"2026-10-10T10:26:34.416093Z","end":"2026-10-10T10:26:34.586324Z","steps":["trace[1371011236] 'read index received' (duration: 142.07028ms)","trace[1371011236] 'applied index is now lower than readState.Index' (duration: 28.157238ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:26:34.586568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.471592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-10T10:26:34.586631Z","caller":"traceutil/trace.go:171","msg":"trace[1595127136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1205; }","duration":"170.543174ms","start":"2026-10-10T10:26:34.416051Z","end":"2026-10-10T10:26:34.586594Z","steps":["trace[1595127136] 'agreement among raft nodes before linearized reading' (duration: 170.354351ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:26:34.58704Z","caller":"traceutil/trace.go:171","msg":"trace[278735353] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"227.870138ms","start":"2026-10-10T10:26:34.359158Z","end":"2026-10-10T10:26:34.587028Z","steps":["trace[278735353] 'process raft request' (duration: 227.087824ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:26:35.156922Z","caller":"traceutil/trace.go:171","msg":"trace[1949662714] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"188.724185ms","start":"2026-10-10T10:26:34.968173Z","end":"2026-10-10T10:26:35.156897Z","steps":["trace[1949662714] 'process raft request' (duration: 188.573982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:26:35.362896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.679626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:26:35.362965Z","caller":"traceutil/trace.go:171","msg":"trace[1387625025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1207; }","duration":"108.786048ms","start":"2026-10-10T10:26:35.254163Z","end":"2026-10-10T10:26:35.362949Z","steps":["trace[1387625025] 'range keys from in-memory index tree' (duration: 108.597494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:26:35.950547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.553957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-10T10:26:35.950648Z","caller":"traceutil/trace.go:171","msg":"trace[888273082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1208; }","duration":"208.692969ms","start":"2026-10-10T10:26:35.741934Z","end":"2026-10-10T10:26:35.950626Z","steps":["trace[888273082] 'range keys from in-memory index tree' (duration: 208.381843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:26:35.950648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.259275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-10T10:26:35.95071Z","caller":"traceutil/trace.go:171","msg":"trace[942194610] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1208; }","duration":"236.398717ms","start":"2026-10-10T10:26:35.714296Z","end":"2026-10-10T10:26:35.950695Z","steps":["trace[942194610] 'count revisions from in-memory index tree' (duration: 236.150413ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:26:35.950732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.166965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:26:35.950777Z","caller":"traceutil/trace.go:171","msg":"trace[426305701] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1208; }","duration":"274.244457ms","start":"2026-10-10T10:26:35.676518Z","end":"2026-10-10T10:26:35.950762Z","steps":["trace[426305701] 'range keys from in-memory index tree' (duration: 274.071884ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:26:43.703626Z","caller":"traceutil/trace.go:171","msg":"trace[1512299307] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"126.945878ms","start":"2026-10-10T10:26:43.576648Z","end":"2026-10-10T10:26:43.703593Z","steps":["trace[1512299307] 'process raft request' (duration: 126.760534ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:31:17.680965Z","caller":"traceutil/trace.go:171","msg":"trace[1717863042] transaction","detail":"{read_only:false; response_revision:2551; number_of_response:1; }","duration":"128.701887ms","start":"2026-10-10T10:31:17.552235Z","end":"2026-10-10T10:31:17.680936Z","steps":["trace[1717863042] 'process raft request' (duration: 87.551931ms)","trace[1717863042] 'compare' (duration: 41.015283ms)"],"step_count":2}