{"level":"warn","ts":"2026-02-10T18:53:11.306876Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T18:53:11.307013Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-02-10T18:53:11.307076Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T18:53:11.307089Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-02-10T18:53:11.307107Z","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-02-10T18:53:11.309993Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-02-10T18:53:11.310077Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-02-10T18:53:11.315454Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.944028ms"} {"level":"info","ts":"2026-02-10T18:53:11.323355Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-02-10T18:53:11.323413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T18:53:11.32344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-02-10T18:53:11.323447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T18:53:11.323454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-02-10T18:53:11.323482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-02-10T18:53:11.33218Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T18:53:11.354493Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T18:53:11.355846Z","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-02-10T18:53:11.370958Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T18:53:11.371891Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T18:53:11.37203Z","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-02-10T18:53:11.372073Z","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-02-10T18:53:11.372085Z","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-02-10T18:53:11.374791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-02-10T18:53:11.37486Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-02-10T18:53:11.376528Z","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-02-10T18:53:11.377075Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-02-10T18:53:11.377088Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-02-10T18:53:11.377611Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T18:53:11.377634Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T18:53:11.624609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T18:53:11.624649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T18:53:11.624676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-02-10T18:53:11.624687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-02-10T18:53:11.624696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-02-10T18:53:11.62471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-02-10T18:53:11.62472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-02-10T18:53:11.654633Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T18:53:11.654681Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T18:53:11.655019Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:53:11.658587Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T18:53:11.658802Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:53:11.658881Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:53:11.658898Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:53:11.658906Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T18:53:11.661929Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-02-10T18:53:11.67562Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T18:53:11.675639Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T18:53:21.698926Z","caller":"traceutil/trace.go:171","msg":"trace[400042483] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:357; }","duration":"105.653217ms","start":"2026-02-10T18:53:21.593253Z","end":"2026-02-10T18:53:21.698906Z","steps":["trace[400042483] 'read index received' (duration: 58.051603ms)","trace[400042483] 'applied index is now lower than readState.Index' (duration: 47.600384ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:53:21.699167Z","caller":"traceutil/trace.go:171","msg":"trace[12487720] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"117.583059ms","start":"2026-02-10T18:53:21.581569Z","end":"2026-02-10T18:53:21.699152Z","steps":["trace[12487720] 'process raft request' (duration: 69.75975ms)","trace[12487720] 'compare' (duration: 47.471292ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:21.699387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.116907ms","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-02-10T18:53:21.699456Z","caller":"traceutil/trace.go:171","msg":"trace[2070232878] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:350; }","duration":"106.20101ms","start":"2026-02-10T18:53:21.593217Z","end":"2026-02-10T18:53:21.699418Z","steps":["trace[2070232878] 'agreement among raft nodes before linearized reading' (duration: 106.099148ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:53:27.242274Z","caller":"traceutil/trace.go:171","msg":"trace[788147152] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"274.68717ms","start":"2026-02-10T18:53:26.967562Z","end":"2026-02-10T18:53:27.242249Z","steps":["trace[788147152] 'process raft request' (duration: 274.487626ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:53:27.442646Z","caller":"traceutil/trace.go:171","msg":"trace[2678336] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"462.276827ms","start":"2026-02-10T18:53:26.980086Z","end":"2026-02-10T18:53:27.442363Z","steps":["trace[2678336] 'process raft request' (duration: 462.114083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:27.443418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:26.98007Z","time spent":"462.659106ms","remote":"127.0.0.1:53590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4583,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:53:42.922327Z","caller":"traceutil/trace.go:171","msg":"trace[2123750569] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"176.339897ms","start":"2026-02-10T18:53:42.745969Z","end":"2026-02-10T18:53:42.922309Z","steps":["trace[2123750569] 'process raft request' (duration: 176.226305ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:53:44.327748Z","caller":"traceutil/trace.go:171","msg":"trace[780467318] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"104.739654ms","start":"2026-02-10T18:53:44.222989Z","end":"2026-02-10T18:53:44.327729Z","steps":["trace[780467318] 'process raft request' (duration: 104.625112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:45.565024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.080769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:53:45.565116Z","caller":"traceutil/trace.go:171","msg":"trace[1216760464] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"171.659715ms","start":"2026-02-10T18:53:45.39344Z","end":"2026-02-10T18:53:45.5651Z","steps":["trace[1216760464] 'compare' (duration: 164.953586ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:53:50.338827Z","caller":"traceutil/trace.go:171","msg":"trace[1821998392] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"210.714207ms","start":"2026-02-10T18:53:50.128073Z","end":"2026-02-10T18:53:50.338787Z","steps":["trace[1821998392] 'read index received' (duration: 210.4006ms)","trace[1821998392] 'applied index is now lower than readState.Index' (duration: 312.567µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:50.339005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.910091ms","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-02-10T18:53:50.339043Z","caller":"traceutil/trace.go:171","msg":"trace[955921186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"211.002803ms","start":"2026-02-10T18:53:50.128029Z","end":"2026-02-10T18:53:50.339031Z","steps":["trace[955921186] 'agreement among raft nodes before linearized reading' (duration: 210.903331ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:53:50.339188Z","caller":"traceutil/trace.go:171","msg":"trace[575392973] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"228.851148ms","start":"2026-02-10T18:53:50.110306Z","end":"2026-02-10T18:53:50.339157Z","steps":["trace[575392973] 'process raft request' (duration: 228.275966ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:53:51.936755Z","caller":"traceutil/trace.go:171","msg":"trace[844435645] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"126.045876ms","start":"2026-02-10T18:53:51.810686Z","end":"2026-02-10T18:53:51.936732Z","steps":["trace[844435645] 'process raft request' (duration: 125.923363ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:04.960405Z","caller":"traceutil/trace.go:171","msg":"trace[1958532817] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"204.602352ms","start":"2026-02-10T18:54:04.755778Z","end":"2026-02-10T18:54:04.96038Z","steps":["trace[1958532817] 'process raft request' (duration: 191.501673ms)","trace[1958532817] 'compare' (duration: 12.773762ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:05.324472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.885278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-10T18:54:05.324528Z","caller":"traceutil/trace.go:171","msg":"trace[954755313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:710; }","duration":"243.011611ms","start":"2026-02-10T18:54:05.081503Z","end":"2026-02-10T18:54:05.324515Z","steps":["trace[954755313] 'range keys from in-memory index tree' (duration: 242.729995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:05.324616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.430109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-h6sls\" ","response":"range_response_count:1 size:4824"} {"level":"info","ts":"2026-02-10T18:54:05.324677Z","caller":"traceutil/trace.go:171","msg":"trace[992158397] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-h6sls; range_end:; response_count:1; response_revision:710; }","duration":"100.525952ms","start":"2026-02-10T18:54:05.224139Z","end":"2026-02-10T18:54:05.324665Z","steps":["trace[992158397] 'range keys from in-memory index tree' (duration: 100.280257ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:05.526096Z","caller":"traceutil/trace.go:171","msg":"trace[549817814] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"300.480271ms","start":"2026-02-10T18:54:05.225598Z","end":"2026-02-10T18:54:05.526078Z","steps":["trace[549817814] 'process raft request' (duration: 300.372688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:05.526193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:05.225581Z","time spent":"300.559862ms","remote":"127.0.0.1:53494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:54:05.526415Z","caller":"traceutil/trace.go:171","msg":"trace[983203606] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:729; }","duration":"260.945817ms","start":"2026-02-10T18:54:05.265464Z","end":"2026-02-10T18:54:05.52641Z","steps":["trace[983203606] 'read index received' (duration: 260.943557ms)","trace[983203606] 'applied index is now lower than readState.Index' (duration: 1.81µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:05.526521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.053119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T18:54:05.526536Z","caller":"traceutil/trace.go:171","msg":"trace[1273345929] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:711; }","duration":"261.09799ms","start":"2026-02-10T18:54:05.265433Z","end":"2026-02-10T18:54:05.526531Z","steps":["trace[1273345929] 'agreement among raft nodes before linearized reading' (duration: 260.999068ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:05.545563Z","caller":"traceutil/trace.go:171","msg":"trace[760159598] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"218.09281ms","start":"2026-02-10T18:54:05.327462Z","end":"2026-02-10T18:54:05.545555Z","steps":["trace[760159598] 'process raft request' (duration: 218.027529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:06.583198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.507238ms","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-02-10T18:54:06.583257Z","caller":"traceutil/trace.go:171","msg":"trace[2135785492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"118.58594ms","start":"2026-02-10T18:54:06.464657Z","end":"2026-02-10T18:54:06.583243Z","steps":["trace[2135785492] 'range keys from in-memory index tree' (duration: 118.376786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:08.935192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.838649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:54:08.935456Z","caller":"traceutil/trace.go:171","msg":"trace[2046065409] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"215.229246ms","start":"2026-02-10T18:54:08.720029Z","end":"2026-02-10T18:54:08.935258Z","steps":["trace[2046065409] 'process raft request' (duration: 85.253674ms)","trace[2046065409] 'compare' (duration: 129.741637ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:09.293767Z","caller":"traceutil/trace.go:171","msg":"trace[848245285] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"188.092717ms","start":"2026-02-10T18:54:09.105653Z","end":"2026-02-10T18:54:09.293746Z","steps":["trace[848245285] 'process raft request' (duration: 187.342121ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:10.564805Z","caller":"traceutil/trace.go:171","msg":"trace[499252041] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"200.107053ms","start":"2026-02-10T18:54:10.364677Z","end":"2026-02-10T18:54:10.564784Z","steps":["trace[499252041] 'process raft request' (duration: 199.925509ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:23.774426Z","caller":"traceutil/trace.go:171","msg":"trace[1417637414] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"173.699819ms","start":"2026-02-10T18:54:23.600709Z","end":"2026-02-10T18:54:23.774409Z","steps":["trace[1417637414] 'process raft request' (duration: 173.590357ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:24.8164Z","caller":"traceutil/trace.go:171","msg":"trace[2126942476] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"128.587772ms","start":"2026-02-10T18:54:24.687786Z","end":"2026-02-10T18:54:24.816374Z","steps":["trace[2126942476] 'process raft request' (duration: 128.271055ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:27.009771Z","caller":"traceutil/trace.go:171","msg":"trace[198235489] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"187.251814ms","start":"2026-02-10T18:54:26.822504Z","end":"2026-02-10T18:54:27.009756Z","steps":["trace[198235489] 'process raft request' (duration: 187.129312ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:27.009958Z","caller":"traceutil/trace.go:171","msg":"trace[890370152] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:801; }","duration":"184.755954ms","start":"2026-02-10T18:54:26.825193Z","end":"2026-02-10T18:54:27.009949Z","steps":["trace[890370152] 'read index received' (duration: 184.753094ms)","trace[890370152] 'applied index is now lower than readState.Index' (duration: 2.36µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:27.010221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.01526ms","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-02-10T18:54:27.010251Z","caller":"traceutil/trace.go:171","msg":"trace[850052740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"185.064721ms","start":"2026-02-10T18:54:26.825178Z","end":"2026-02-10T18:54:27.010243Z","steps":["trace[850052740] 'agreement among raft nodes before linearized reading' (duration: 184.982379ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:28.764714Z","caller":"traceutil/trace.go:171","msg":"trace[1829644199] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"122.405841ms","start":"2026-02-10T18:54:28.642293Z","end":"2026-02-10T18:54:28.764699Z","steps":["trace[1829644199] 'process raft request' (duration: 122.177214ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:29.218667Z","caller":"traceutil/trace.go:171","msg":"trace[1954333871] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"175.836297ms","start":"2026-02-10T18:54:29.042806Z","end":"2026-02-10T18:54:29.218642Z","steps":["trace[1954333871] 'process raft request' (duration: 162.658667ms)","trace[1954333871] 'compare' (duration: 13.046427ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:29.235093Z","caller":"traceutil/trace.go:171","msg":"trace[1262816578] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"191.019976ms","start":"2026-02-10T18:54:29.044062Z","end":"2026-02-10T18:54:29.235082Z","steps":["trace[1262816578] 'process raft request' (duration: 190.816121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:31.09141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.416727ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T18:54:31.091496Z","caller":"traceutil/trace.go:171","msg":"trace[1647090265] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"556.398523ms","start":"2026-02-10T18:54:30.535082Z","end":"2026-02-10T18:54:31.09148Z","steps":["trace[1647090265] 'read index received' (duration: 31.830063ms)","trace[1647090265] 'applied index is now lower than readState.Index' (duration: 524.56725ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:31.091935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.841233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:31.091993Z","caller":"traceutil/trace.go:171","msg":"trace[994683255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"556.925695ms","start":"2026-02-10T18:54:30.535056Z","end":"2026-02-10T18:54:31.091981Z","steps":["trace[994683255] 'agreement among raft nodes before linearized reading' (duration: 556.827213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:31.092028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:30.535042Z","time spent":"556.977427ms","remote":"127.0.0.1:53412","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-02-10T18:54:31.09252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.743962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T18:54:31.092561Z","caller":"traceutil/trace.go:171","msg":"trace[1958615311] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:787; }","duration":"324.822414ms","start":"2026-02-10T18:54:30.767729Z","end":"2026-02-10T18:54:31.092552Z","steps":["trace[1958615311] 'agreement among raft nodes before linearized reading' (duration: 324.693599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:31.092591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:30.76771Z","time spent":"324.874024ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-10T18:54:31.656373Z","caller":"traceutil/trace.go:171","msg":"trace[552060992] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"434.35624ms","start":"2026-02-10T18:54:31.221992Z","end":"2026-02-10T18:54:31.656349Z","steps":["trace[552060992] 'read index received' (duration: 434.076154ms)","trace[552060992] 'applied index is now lower than readState.Index' (duration: 279.216µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:31.656876Z","caller":"traceutil/trace.go:171","msg":"trace[1143977719] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"556.979277ms","start":"2026-02-10T18:54:31.099883Z","end":"2026-02-10T18:54:31.656862Z","steps":["trace[1143977719] 'process raft request' (duration: 556.244949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:31.656975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:31.099878Z","time spent":"557.031708ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:54:31.65722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.234342ms","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-02-10T18:54:31.657251Z","caller":"traceutil/trace.go:171","msg":"trace[1696698185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"435.296633ms","start":"2026-02-10T18:54:31.221944Z","end":"2026-02-10T18:54:31.657241Z","steps":["trace[1696698185] 'agreement among raft nodes before linearized reading' (duration: 435.18799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:31.657279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:31.221928Z","time spent":"435.345034ms","remote":"127.0.0.1:49320","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-02-10T18:54:31.659248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.61898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:31.659281Z","caller":"traceutil/trace.go:171","msg":"trace[1330284437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"123.701441ms","start":"2026-02-10T18:54:31.53557Z","end":"2026-02-10T18:54:31.659272Z","steps":["trace[1330284437] 'agreement among raft nodes before linearized reading' (duration: 123.65433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:31.65745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.044659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T18:54:31.6594Z","caller":"traceutil/trace.go:171","msg":"trace[1177883632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"421.039947ms","start":"2026-02-10T18:54:31.238351Z","end":"2026-02-10T18:54:31.659391Z","steps":["trace[1177883632] 'agreement among raft nodes before linearized reading' (duration: 419.041288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:31.659553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:31.238341Z","time spent":"421.072759ms","remote":"127.0.0.1:49320","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-02-10T18:54:31.659816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.47003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:54:31.659852Z","caller":"traceutil/trace.go:171","msg":"trace[481103131] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:789; }","duration":"281.533512ms","start":"2026-02-10T18:54:31.378312Z","end":"2026-02-10T18:54:31.659846Z","steps":["trace[481103131] 'agreement among raft nodes before linearized reading' (duration: 281.475351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:31.946148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.418552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:54:31.946242Z","caller":"traceutil/trace.go:171","msg":"trace[614160840] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"139.565407ms","start":"2026-02-10T18:54:31.80666Z","end":"2026-02-10T18:54:31.946225Z","steps":["trace[614160840] 'read index received' (duration: 41.901µs)","trace[614160840] 'applied index is now lower than readState.Index' (duration: 139.522336ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:31.946408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.745382ms","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-02-10T18:54:31.946442Z","caller":"traceutil/trace.go:171","msg":"trace[1826733042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:791; }","duration":"139.841984ms","start":"2026-02-10T18:54:31.806592Z","end":"2026-02-10T18:54:31.946434Z","steps":["trace[1826733042] 'agreement among raft nodes before linearized reading' (duration: 139.69476ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:31.946724Z","caller":"traceutil/trace.go:171","msg":"trace[1609559605] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"280.602279ms","start":"2026-02-10T18:54:31.66611Z","end":"2026-02-10T18:54:31.946712Z","steps":["trace[1609559605] 'process raft request' (duration: 84.515881ms)","trace[1609559605] 'compare' (duration: 195.32825ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:41.84003Z","caller":"traceutil/trace.go:171","msg":"trace[1669438848] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"128.305624ms","start":"2026-02-10T18:54:41.7117Z","end":"2026-02-10T18:54:41.840006Z","steps":["trace[1669438848] 'process raft request' (duration: 128.080919ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:41.894157Z","caller":"traceutil/trace.go:171","msg":"trace[1997698172] linearizableReadLoop","detail":"{readStateIndex:894; appliedIndex:892; }","duration":"137.174709ms","start":"2026-02-10T18:54:41.75696Z","end":"2026-02-10T18:54:41.894135Z","steps":["trace[1997698172] 'read index received' (duration: 82.897403ms)","trace[1997698172] 'applied index is now lower than readState.Index' (duration: 54.276396ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:41.894389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.396194ms","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-02-10T18:54:41.894428Z","caller":"traceutil/trace.go:171","msg":"trace[1078493607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:866; }","duration":"137.476046ms","start":"2026-02-10T18:54:41.756942Z","end":"2026-02-10T18:54:41.894418Z","steps":["trace[1078493607] 'agreement among raft nodes before linearized reading' (duration: 137.278741ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:41.894738Z","caller":"traceutil/trace.go:171","msg":"trace[1541462511] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"138.87821ms","start":"2026-02-10T18:54:41.755849Z","end":"2026-02-10T18:54:41.894728Z","steps":["trace[1541462511] 'process raft request' (duration: 138.189433ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:42.04244Z","caller":"traceutil/trace.go:171","msg":"trace[1544959597] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"131.569813ms","start":"2026-02-10T18:54:41.910852Z","end":"2026-02-10T18:54:42.042421Z","steps":["trace[1544959597] 'process raft request' (duration: 89.040281ms)","trace[1544959597] 'compare' (duration: 42.254725ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:44.309002Z","caller":"traceutil/trace.go:171","msg":"trace[675140957] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"389.779159ms","start":"2026-02-10T18:54:43.919205Z","end":"2026-02-10T18:54:44.308984Z","steps":["trace[675140957] 'read index received' (duration: 389.648666ms)","trace[675140957] 'applied index is now lower than readState.Index' (duration: 129.803µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:44.309104Z","caller":"traceutil/trace.go:171","msg":"trace[127841738] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"462.728629ms","start":"2026-02-10T18:54:43.846366Z","end":"2026-02-10T18:54:44.309095Z","steps":["trace[127841738] 'process raft request' (duration: 462.501594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:44.309193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:43.846352Z","time spent":"462.77535ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:54:44.309334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.637117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-10T18:54:44.309424Z","caller":"traceutil/trace.go:171","msg":"trace[1093112266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:874; }","duration":"244.72908ms","start":"2026-02-10T18:54:44.064642Z","end":"2026-02-10T18:54:44.309371Z","steps":["trace[1093112266] 'agreement among raft nodes before linearized reading' (duration: 244.581686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:44.309529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.318263ms","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-02-10T18:54:44.309559Z","caller":"traceutil/trace.go:171","msg":"trace[1859543242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:874; }","duration":"390.368034ms","start":"2026-02-10T18:54:43.919182Z","end":"2026-02-10T18:54:44.30955Z","steps":["trace[1859543242] 'agreement among raft nodes before linearized reading' (duration: 390.272262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:44.30958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:43.919172Z","time spent":"390.404015ms","remote":"127.0.0.1:49320","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":"warn","ts":"2026-02-10T18:54:44.309587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.733704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T18:54:44.309607Z","caller":"traceutil/trace.go:171","msg":"trace[1320862367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:874; }","duration":"231.788896ms","start":"2026-02-10T18:54:44.077813Z","end":"2026-02-10T18:54:44.309601Z","steps":["trace[1320862367] 'agreement among raft nodes before linearized reading' (duration: 231.729664ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:46.145425Z","caller":"traceutil/trace.go:171","msg":"trace[1050432975] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"218.39425ms","start":"2026-02-10T18:54:45.927014Z","end":"2026-02-10T18:54:46.145408Z","steps":["trace[1050432975] 'process raft request' (duration: 218.283998ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:47.653083Z","caller":"traceutil/trace.go:171","msg":"trace[105721243] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:913; }","duration":"120.806311ms","start":"2026-02-10T18:54:47.532257Z","end":"2026-02-10T18:54:47.653063Z","steps":["trace[105721243] 'read index received' (duration: 120.608986ms)","trace[105721243] 'applied index is now lower than readState.Index' (duration: 196.495µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:47.653218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.938845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:47.653256Z","caller":"traceutil/trace.go:171","msg":"trace[633588447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:885; }","duration":"121.059937ms","start":"2026-02-10T18:54:47.532187Z","end":"2026-02-10T18:54:47.653247Z","steps":["trace[633588447] 'agreement among raft nodes before linearized reading' (duration: 120.955255ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:47.653534Z","caller":"traceutil/trace.go:171","msg":"trace[2067577558] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"390.372183ms","start":"2026-02-10T18:54:47.263149Z","end":"2026-02-10T18:54:47.653521Z","steps":["trace[2067577558] 'process raft request' (duration: 389.768539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:47.653623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:47.26314Z","time spent":"390.430615ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:54:48.848002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.237661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:48.848061Z","caller":"traceutil/trace.go:171","msg":"trace[482349617] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:885; }","duration":"104.330952ms","start":"2026-02-10T18:54:48.743719Z","end":"2026-02-10T18:54:48.84805Z","steps":["trace[482349617] 'count revisions from in-memory index tree' (duration: 104.185059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:48.848663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.073034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T18:54:48.848682Z","caller":"traceutil/trace.go:171","msg":"trace[519202975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:885; }","duration":"525.129395ms","start":"2026-02-10T18:54:48.323545Z","end":"2026-02-10T18:54:48.848675Z","steps":["trace[519202975] 'range keys from in-memory index tree' (duration: 524.937231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:48.8487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:48.323528Z","time spent":"525.165706ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:54:48.848824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.853123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:48.848837Z","caller":"traceutil/trace.go:171","msg":"trace[1638587943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:885; }","duration":"312.900944ms","start":"2026-02-10T18:54:48.535932Z","end":"2026-02-10T18:54:48.848833Z","steps":["trace[1638587943] 'range keys from in-memory index tree' (duration: 312.779461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:48.848826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.370304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-10T18:54:48.848886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.903332ms","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-02-10T18:54:48.848887Z","caller":"traceutil/trace.go:171","msg":"trace[2084206035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:885; }","duration":"519.477137ms","start":"2026-02-10T18:54:48.329391Z","end":"2026-02-10T18:54:48.848868Z","steps":["trace[2084206035] 'range keys from in-memory index tree' (duration: 519.239851ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:48.8489Z","caller":"traceutil/trace.go:171","msg":"trace[1533376669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:885; }","duration":"506.967854ms","start":"2026-02-10T18:54:48.341929Z","end":"2026-02-10T18:54:48.848897Z","steps":["trace[1533376669] 'range keys from in-memory index tree' (duration: 506.777919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:48.848916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:48.341918Z","time spent":"506.995294ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:54:48.84892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:48.329369Z","time spent":"519.545468ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T18:54:48.848985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.766535ms","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-02-10T18:54:48.848998Z","caller":"traceutil/trace.go:171","msg":"trace[1478226687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:885; }","duration":"505.800156ms","start":"2026-02-10T18:54:48.343195Z","end":"2026-02-10T18:54:48.848995Z","steps":["trace[1478226687] 'range keys from in-memory index tree' (duration: 505.709733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:48.849014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:48.343183Z","time spent":"505.823776ms","remote":"127.0.0.1:49320","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":"warn","ts":"2026-02-10T18:54:48.84885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:48.535913Z","time spent":"312.933084ms","remote":"127.0.0.1:53422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:54:49.061988Z","caller":"traceutil/trace.go:171","msg":"trace[105078771] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"207.875985ms","start":"2026-02-10T18:54:48.854097Z","end":"2026-02-10T18:54:49.061973Z","steps":["trace[105078771] 'process raft request' (duration: 207.788142ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:49.062239Z","caller":"traceutil/trace.go:171","msg":"trace[9451864] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:915; }","duration":"208.08247ms","start":"2026-02-10T18:54:48.854151Z","end":"2026-02-10T18:54:49.062233Z","steps":["trace[9451864] 'read index received' (duration: 208.08015ms)","trace[9451864] 'applied index is now lower than readState.Index' (duration: 1.92µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:49.062277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.11571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:49.06229Z","caller":"traceutil/trace.go:171","msg":"trace[2122574509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"208.227883ms","start":"2026-02-10T18:54:48.854058Z","end":"2026-02-10T18:54:49.062286Z","steps":["trace[2122574509] 'agreement among raft nodes before linearized reading' (duration: 208.193612ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:49.078364Z","caller":"traceutil/trace.go:171","msg":"trace[372025704] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"221.041574ms","start":"2026-02-10T18:54:48.857311Z","end":"2026-02-10T18:54:49.078352Z","steps":["trace[372025704] 'process raft request' (duration: 221.015954ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:49.078562Z","caller":"traceutil/trace.go:171","msg":"trace[496896057] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"224.321974ms","start":"2026-02-10T18:54:48.854234Z","end":"2026-02-10T18:54:49.078556Z","steps":["trace[496896057] 'process raft request' (duration: 224.004287ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:49.078624Z","caller":"traceutil/trace.go:171","msg":"trace[1787964789] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"224.341415ms","start":"2026-02-10T18:54:48.854279Z","end":"2026-02-10T18:54:49.07862Z","steps":["trace[1787964789] 'process raft request' (duration: 224.026077ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:56.178231Z","caller":"traceutil/trace.go:171","msg":"trace[2063108178] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"483.542644ms","start":"2026-02-10T18:54:55.694671Z","end":"2026-02-10T18:54:56.178213Z","steps":["trace[2063108178] 'process raft request' (duration: 483.397121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:56.178378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:55.69466Z","time spent":"483.657147ms","remote":"127.0.0.1:49320","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-02-10T18:54:57.091777Z","caller":"traceutil/trace.go:171","msg":"trace[607569128] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"550.51377ms","start":"2026-02-10T18:54:56.541249Z","end":"2026-02-10T18:54:57.091763Z","steps":["trace[607569128] 'process raft request' (duration: 550.417447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:57.091898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:56.541235Z","time spent":"550.608872ms","remote":"127.0.0.1:49320","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-02-10T18:54:57.656082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603754039059136,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T18:54:57.768254Z","caller":"traceutil/trace.go:171","msg":"trace[1515480530] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"612.694739ms","start":"2026-02-10T18:54:57.155542Z","end":"2026-02-10T18:54:57.768236Z","steps":["trace[1515480530] 'read index received' (duration: 556.906496ms)","trace[1515480530] 'applied index is now lower than readState.Index' (duration: 55.787593ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:57.768395Z","caller":"traceutil/trace.go:171","msg":"trace[755793106] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"621.369729ms","start":"2026-02-10T18:54:57.147019Z","end":"2026-02-10T18:54:57.768389Z","steps":["trace[755793106] 'process raft request' (duration: 565.473513ms)","trace[755793106] 'compare' (duration: 55.614349ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:57.768466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:57.146996Z","time spent":"621.413931ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:54:57.7686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.058528ms","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-02-10T18:54:57.768616Z","caller":"traceutil/trace.go:171","msg":"trace[831692571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:909; }","duration":"613.096048ms","start":"2026-02-10T18:54:57.155515Z","end":"2026-02-10T18:54:57.768611Z","steps":["trace[831692571] 'agreement among raft nodes before linearized reading' (duration: 613.034197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:57.768629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:57.155501Z","time spent":"613.125039ms","remote":"127.0.0.1:49320","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-02-10T18:54:57.76872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.954975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-10T18:54:57.768733Z","caller":"traceutil/trace.go:171","msg":"trace[1703294682] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:909; }","duration":"612.975905ms","start":"2026-02-10T18:54:57.155753Z","end":"2026-02-10T18:54:57.768729Z","steps":["trace[1703294682] 'agreement among raft nodes before linearized reading' (duration: 612.941234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:57.768744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:57.155749Z","time spent":"612.992797ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:54:57.768893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.909775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:54:57.768908Z","caller":"traceutil/trace.go:171","msg":"trace[1176695450] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:909; }","duration":"118.926235ms","start":"2026-02-10T18:54:57.649978Z","end":"2026-02-10T18:54:57.768904Z","steps":["trace[1176695450] 'agreement among raft nodes before linearized reading' (duration: 118.857404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:57.769021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.98514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:57.769075Z","caller":"traceutil/trace.go:171","msg":"trace[966206057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:909; }","duration":"237.073082ms","start":"2026-02-10T18:54:57.53198Z","end":"2026-02-10T18:54:57.769053Z","steps":["trace[966206057] 'agreement among raft nodes before linearized reading' (duration: 236.98643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:57.769109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.313023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T18:54:57.769126Z","caller":"traceutil/trace.go:171","msg":"trace[1416904550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:909; }","duration":"606.352325ms","start":"2026-02-10T18:54:57.162768Z","end":"2026-02-10T18:54:57.76912Z","steps":["trace[1416904550] 'agreement among raft nodes before linearized reading' (duration: 606.311514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:57.769139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:57.162754Z","time spent":"606.381875ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-10T18:54:58.302997Z","caller":"traceutil/trace.go:171","msg":"trace[1716639354] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"119.334317ms","start":"2026-02-10T18:54:58.183647Z","end":"2026-02-10T18:54:58.302981Z","steps":["trace[1716639354] 'process raft request' (duration: 119.246185ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:59.500876Z","caller":"traceutil/trace.go:171","msg":"trace[2092408567] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"250.799396ms","start":"2026-02-10T18:54:59.250053Z","end":"2026-02-10T18:54:59.500853Z","steps":["trace[2092408567] 'process raft request' (duration: 250.673702ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:59.894281Z","caller":"traceutil/trace.go:171","msg":"trace[1173537352] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"107.14269ms","start":"2026-02-10T18:54:59.787122Z","end":"2026-02-10T18:54:59.894265Z","steps":["trace[1173537352] 'read index received' (duration: 106.976546ms)","trace[1173537352] 'applied index is now lower than readState.Index' (duration: 165.424µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:59.894523Z","caller":"traceutil/trace.go:171","msg":"trace[1215716253] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"118.907136ms","start":"2026-02-10T18:54:59.775608Z","end":"2026-02-10T18:54:59.894515Z","steps":["trace[1215716253] 'process raft request' (duration: 118.549558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:59.894694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.55697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T18:54:59.894719Z","caller":"traceutil/trace.go:171","msg":"trace[1156277237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"107.600311ms","start":"2026-02-10T18:54:59.787109Z","end":"2026-02-10T18:54:59.894709Z","steps":["trace[1156277237] 'agreement among raft nodes before linearized reading' (duration: 107.519709ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:00.241393Z","caller":"traceutil/trace.go:171","msg":"trace[399535483] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"343.22481ms","start":"2026-02-10T18:54:59.898142Z","end":"2026-02-10T18:55:00.241366Z","steps":["trace[399535483] 'process raft request' (duration: 323.45673ms)","trace[399535483] 'compare' (duration: 19.639247ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:55:00.241541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:59.898125Z","time spent":"343.337353ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:55:00.266844Z","caller":"traceutil/trace.go:171","msg":"trace[2116869784] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"366.204758ms","start":"2026-02-10T18:54:59.900627Z","end":"2026-02-10T18:55:00.266832Z","steps":["trace[2116869784] 'process raft request' (duration: 366.184357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:00.26693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:59.900623Z","time spent":"366.258669ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:55:00.267128Z","caller":"traceutil/trace.go:171","msg":"trace[1558376787] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"366.539736ms","start":"2026-02-10T18:54:59.900583Z","end":"2026-02-10T18:55:00.267123Z","steps":["trace[1558376787] 'process raft request' (duration: 366.166957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:00.26716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:59.900575Z","time spent":"366.566196ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:55:04.495428Z","caller":"traceutil/trace.go:171","msg":"trace[724139306] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"242.31685ms","start":"2026-02-10T18:55:04.253088Z","end":"2026-02-10T18:55:04.495405Z","steps":["trace[724139306] 'process raft request' (duration: 242.170686ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:04.495877Z","caller":"traceutil/trace.go:171","msg":"trace[1603048243] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:958; }","duration":"217.760973ms","start":"2026-02-10T18:55:04.278087Z","end":"2026-02-10T18:55:04.495848Z","steps":["trace[1603048243] 'read index received' (duration: 217.749953ms)","trace[1603048243] 'applied index is now lower than readState.Index' (duration: 9.03µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:55:04.496376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.267947ms","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-02-10T18:55:04.496659Z","caller":"traceutil/trace.go:171","msg":"trace[1044797041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:926; }","duration":"218.565964ms","start":"2026-02-10T18:55:04.278078Z","end":"2026-02-10T18:55:04.496644Z","steps":["trace[1044797041] 'agreement among raft nodes before linearized reading' (duration: 218.123454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:04.497143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.988851ms","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-02-10T18:55:04.497197Z","caller":"traceutil/trace.go:171","msg":"trace[384732102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:926; }","duration":"218.138844ms","start":"2026-02-10T18:55:04.279045Z","end":"2026-02-10T18:55:04.497184Z","steps":["trace[384732102] 'agreement among raft nodes before linearized reading' (duration: 217.97972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:04.497343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.007259ms","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-02-10T18:55:04.497378Z","caller":"traceutil/trace.go:171","msg":"trace[1197193402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:926; }","duration":"105.074651ms","start":"2026-02-10T18:55:04.392295Z","end":"2026-02-10T18:55:04.49737Z","steps":["trace[1197193402] 'agreement among raft nodes before linearized reading' (duration: 104.994659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:04.79686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.962051ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:55:04.796992Z","caller":"traceutil/trace.go:171","msg":"trace[936894843] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:926; }","duration":"113.112425ms","start":"2026-02-10T18:55:04.683863Z","end":"2026-02-10T18:55:04.796975Z","steps":["trace[936894843] 'range keys from in-memory index tree' (duration: 112.943761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:04.797982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.03182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:55:04.798054Z","caller":"traceutil/trace.go:171","msg":"trace[871906753] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"293.528593ms","start":"2026-02-10T18:55:04.504512Z","end":"2026-02-10T18:55:04.798041Z","steps":["trace[871906753] 'process raft request' (duration: 126.89919ms)","trace[871906753] 'compare' (duration: 165.818364ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:55:04.799606Z","caller":"traceutil/trace.go:171","msg":"trace[519307733] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:958; }","duration":"263.455573ms","start":"2026-02-10T18:55:04.536134Z","end":"2026-02-10T18:55:04.799589Z","steps":["trace[519307733] 'read index received' (duration: 95.288532ms)","trace[519307733] 'applied index is now lower than readState.Index' (duration: 168.166541ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:55:04.799712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.570527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:55:04.799735Z","caller":"traceutil/trace.go:171","msg":"trace[1915068540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"263.633508ms","start":"2026-02-10T18:55:04.536096Z","end":"2026-02-10T18:55:04.799729Z","steps":["trace[1915068540] 'agreement among raft nodes before linearized reading' (duration: 263.556336ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:04.799914Z","caller":"traceutil/trace.go:171","msg":"trace[1364918227] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"290.001419ms","start":"2026-02-10T18:55:04.509907Z","end":"2026-02-10T18:55:04.799908Z","steps":["trace[1364918227] 'process raft request' (duration: 289.567198ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:04.799983Z","caller":"traceutil/trace.go:171","msg":"trace[72839507] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"289.519915ms","start":"2026-02-10T18:55:04.510459Z","end":"2026-02-10T18:55:04.799979Z","steps":["trace[72839507] 'process raft request' (duration: 289.108176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:05.836252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:55:05.268397Z","time spent":"567.849521ms","remote":"127.0.0.1:53446","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-10T18:55:06.38428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.176322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:55:06.384358Z","caller":"traceutil/trace.go:171","msg":"trace[2086418073] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"428.095259ms","start":"2026-02-10T18:55:05.956249Z","end":"2026-02-10T18:55:06.384344Z","steps":["trace[2086418073] 'read index received' (duration: 310.628739ms)","trace[2086418073] 'applied index is now lower than readState.Index' (duration: 117.46529ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:55:06.384445Z","caller":"traceutil/trace.go:171","msg":"trace[755008542] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"547.017405ms","start":"2026-02-10T18:55:05.837413Z","end":"2026-02-10T18:55:06.38443Z","steps":["trace[755008542] 'process raft request' (duration: 429.508034ms)","trace[755008542] 'compare' (duration: 116.987408ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:55:06.384496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:55:05.83739Z","time spent":"547.078057ms","remote":"127.0.0.1:53446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:55:06.384783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.52977ms","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-02-10T18:55:06.384816Z","caller":"traceutil/trace.go:171","msg":"trace[890230748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:930; }","duration":"428.639282ms","start":"2026-02-10T18:55:05.956168Z","end":"2026-02-10T18:55:06.384807Z","steps":["trace[890230748] 'agreement among raft nodes before linearized reading' (duration: 428.499179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:06.384842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:55:05.956144Z","time spent":"428.690293ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T18:55:06.385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.708041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:55:06.385033Z","caller":"traceutil/trace.go:171","msg":"trace[26220587] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:930; }","duration":"146.777843ms","start":"2026-02-10T18:55:06.238247Z","end":"2026-02-10T18:55:06.385025Z","steps":["trace[26220587] 'agreement among raft nodes before linearized reading' (duration: 146.729861ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:06.868737Z","caller":"traceutil/trace.go:171","msg":"trace[1382006615] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"368.213115ms","start":"2026-02-10T18:55:06.500503Z","end":"2026-02-10T18:55:06.868716Z","steps":["trace[1382006615] 'read index received' (duration: 325.894519ms)","trace[1382006615] 'applied index is now lower than readState.Index' (duration: 42.317666ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:55:06.86894Z","caller":"traceutil/trace.go:171","msg":"trace[1483082782] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"476.518666ms","start":"2026-02-10T18:55:06.39241Z","end":"2026-02-10T18:55:06.868928Z","steps":["trace[1483082782] 'process raft request' (duration: 434.022603ms)","trace[1483082782] 'compare' (duration: 41.898447ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:55:06.869266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:55:06.392394Z","time spent":"476.581017ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:55:06.869383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.539383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:55:06.869463Z","caller":"traceutil/trace.go:171","msg":"trace[583731977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"335.642916ms","start":"2026-02-10T18:55:06.533791Z","end":"2026-02-10T18:55:06.869434Z","steps":["trace[583731977] 'agreement among raft nodes before linearized reading' (duration: 335.531143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:06.869516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:55:06.533778Z","time spent":"335.725897ms","remote":"127.0.0.1:53422","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-02-10T18:55:06.869801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.291692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-10T18:55:06.8699Z","caller":"traceutil/trace.go:171","msg":"trace[1191907912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:931; }","duration":"369.413965ms","start":"2026-02-10T18:55:06.500466Z","end":"2026-02-10T18:55:06.86988Z","steps":["trace[1191907912] 'agreement among raft nodes before linearized reading' (duration: 369.270652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:06.869932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:55:06.500449Z","time spent":"369.474806ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:55:09.112605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.875361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:55:09.112727Z","caller":"traceutil/trace.go:171","msg":"trace[1479222772] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"148.427792ms","start":"2026-02-10T18:55:08.96428Z","end":"2026-02-10T18:55:09.112708Z","steps":["trace[1479222772] 'read index received' (duration: 44.263904ms)","trace[1479222772] 'applied index is now lower than readState.Index' (duration: 104.162628ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:55:09.112732Z","caller":"traceutil/trace.go:171","msg":"trace[1723330405] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"150.462881ms","start":"2026-02-10T18:55:08.962247Z","end":"2026-02-10T18:55:09.11271Z","steps":["trace[1723330405] 'process raft request' (duration: 46.355784ms)","trace[1723330405] 'compare' (duration: 103.721988ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:55:09.112943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.654288ms","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-02-10T18:55:09.11298Z","caller":"traceutil/trace.go:171","msg":"trace[1772951904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:946; }","duration":"148.70224ms","start":"2026-02-10T18:55:08.964265Z","end":"2026-02-10T18:55:09.112967Z","steps":["trace[1772951904] 'agreement among raft nodes before linearized reading' (duration: 148.544896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:09.113085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.633797ms","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-02-10T18:55:09.113113Z","caller":"traceutil/trace.go:171","msg":"trace[1219051345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:946; }","duration":"148.710209ms","start":"2026-02-10T18:55:08.964394Z","end":"2026-02-10T18:55:09.113104Z","steps":["trace[1219051345] 'agreement among raft nodes before linearized reading' (duration: 148.586486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:09.113291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.31702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T18:55:09.113326Z","caller":"traceutil/trace.go:171","msg":"trace[1730298218] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:946; }","duration":"148.36488ms","start":"2026-02-10T18:55:08.964951Z","end":"2026-02-10T18:55:09.113316Z","steps":["trace[1730298218] 'agreement among raft nodes before linearized reading' (duration: 148.250298ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:09.258087Z","caller":"traceutil/trace.go:171","msg":"trace[175357323] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"139.381512ms","start":"2026-02-10T18:55:09.118685Z","end":"2026-02-10T18:55:09.258067Z","steps":["trace[175357323] 'process raft request' (duration: 133.236092ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:09.25818Z","caller":"traceutil/trace.go:171","msg":"trace[1164853255] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"139.446294ms","start":"2026-02-10T18:55:09.118716Z","end":"2026-02-10T18:55:09.258162Z","steps":["trace[1164853255] 'process raft request' (duration: 139.30045ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:09.783909Z","caller":"traceutil/trace.go:171","msg":"trace[1431621480] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"163.520367ms","start":"2026-02-10T18:55:09.620369Z","end":"2026-02-10T18:55:09.783889Z","steps":["trace[1431621480] 'process raft request' (duration: 87.155225ms)","trace[1431621480] 'compare' (duration: 76.28095ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:55:10.336394Z","caller":"traceutil/trace.go:171","msg":"trace[1007285162] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"206.498121ms","start":"2026-02-10T18:55:10.129879Z","end":"2026-02-10T18:55:10.336377Z","steps":["trace[1007285162] 'process raft request' (duration: 206.340007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:23.467766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603754039059410,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:55:23.968674Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603754039059410,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:55:24.143555Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.191892685s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T18:55:24.365436Z","caller":"traceutil/trace.go:171","msg":"trace[1498936732] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1077; }","duration":"1.398349525s","start":"2026-02-10T18:55:22.96707Z","end":"2026-02-10T18:55:24.365419Z","steps":["trace[1498936732] 'read index received' (duration: 1.398217521s)","trace[1498936732] 'applied index is now lower than readState.Index' (duration: 131.374µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:55:24.365651Z","caller":"traceutil/trace.go:171","msg":"trace[889179229] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"1.414055585s","start":"2026-02-10T18:55:22.951586Z","end":"2026-02-10T18:55:24.365641Z","steps":["trace[889179229] 'process raft request' (duration: 1.413715787s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:24.36573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:55:22.951569Z","time spent":"1.414104397s","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:55:24.365883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.398818226s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:55:24.365915Z","caller":"traceutil/trace.go:171","msg":"trace[1305238242] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1042; }","duration":"1.398888848s","start":"2026-02-10T18:55:22.967016Z","end":"2026-02-10T18:55:24.365905Z","steps":["trace[1305238242] 'agreement among raft nodes before linearized reading' (duration: 1.398841587s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:24.365938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:55:22.966995Z","time spent":"1.398936929s","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:55:24.366227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047603043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T18:55:24.366249Z","caller":"traceutil/trace.go:171","msg":"trace[608853679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1042; }","duration":"1.047647993s","start":"2026-02-10T18:55:23.318595Z","end":"2026-02-10T18:55:24.366243Z","steps":["trace[608853679] 'agreement among raft nodes before linearized reading' (duration: 1.047576062s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:24.366274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:55:23.318585Z","time spent":"1.047684025s","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:55:24.366374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.372469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:55:24.366398Z","caller":"traceutil/trace.go:171","msg":"trace[722323278] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1042; }","duration":"199.436641ms","start":"2026-02-10T18:55:24.166955Z","end":"2026-02-10T18:55:24.366391Z","steps":["trace[722323278] 'agreement among raft nodes before linearized reading' (duration: 199.39328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:24.366535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.489902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-10T18:55:24.366561Z","caller":"traceutil/trace.go:171","msg":"trace[2144232575] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1042; }","duration":"492.551604ms","start":"2026-02-10T18:55:23.874002Z","end":"2026-02-10T18:55:24.366554Z","steps":["trace[2144232575] 'agreement among raft nodes before linearized reading' (duration: 492.486152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:24.36658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:55:23.873984Z","time spent":"492.590524ms","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-10T18:55:24.366886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090125386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T18:55:24.366908Z","caller":"traceutil/trace.go:171","msg":"trace[1276367213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1042; }","duration":"1.090184027s","start":"2026-02-10T18:55:23.276718Z","end":"2026-02-10T18:55:24.366902Z","steps":["trace[1276367213] 'agreement among raft nodes before linearized reading' (duration: 1.090124856s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:24.366927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:55:23.2767Z","time spent":"1.090222498s","remote":"127.0.0.1:49320","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-02-10T18:55:24.367057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053265461s","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-02-10T18:55:24.367077Z","caller":"traceutil/trace.go:171","msg":"trace[1807702009] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1042; }","duration":"1.053286251s","start":"2026-02-10T18:55:23.313785Z","end":"2026-02-10T18:55:24.367071Z","steps":["trace[1807702009] 'agreement among raft nodes before linearized reading' (duration: 1.0532298s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:24.367107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:55:23.313733Z","time spent":"1.053369954s","remote":"127.0.0.1:49320","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-02-10T18:55:24.367308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19704707s","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-02-10T18:55:24.367325Z","caller":"traceutil/trace.go:171","msg":"trace[371588955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1042; }","duration":"1.197099311s","start":"2026-02-10T18:55:23.170221Z","end":"2026-02-10T18:55:24.36732Z","steps":["trace[371588955] 'agreement among raft nodes before linearized reading' (duration: 1.19704596s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:24.367343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:55:23.170204Z","time spent":"1.197134872s","remote":"127.0.0.1:49320","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-02-10T18:55:24.370508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.352481922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-10T18:55:24.370725Z","caller":"traceutil/trace.go:171","msg":"trace[2020184477] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1042; }","duration":"1.352732968s","start":"2026-02-10T18:55:23.017972Z","end":"2026-02-10T18:55:24.370705Z","steps":["trace[2020184477] 'agreement among raft nodes before linearized reading' (duration: 1.347939231s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:24.370902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:55:23.017958Z","time spent":"1.352930324s","remote":"127.0.0.1:49320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-10T18:55:34.701878Z","caller":"traceutil/trace.go:171","msg":"trace[1603796922] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"154.108633ms","start":"2026-02-10T18:55:34.547753Z","end":"2026-02-10T18:55:34.701862Z","steps":["trace[1603796922] 'process raft request' (duration: 98.097183ms)","trace[1603796922] 'compare' (duration: 55.631492ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:55:34.708043Z","caller":"traceutil/trace.go:171","msg":"trace[184100890] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"159.923856ms","start":"2026-02-10T18:55:34.548105Z","end":"2026-02-10T18:55:34.708029Z","steps":["trace[184100890] 'process raft request' (duration: 159.496266ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:34.709134Z","caller":"traceutil/trace.go:171","msg":"trace[1326683234] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"160.94636ms","start":"2026-02-10T18:55:34.548175Z","end":"2026-02-10T18:55:34.709121Z","steps":["trace[1326683234] 'process raft request' (duration: 159.531297ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:34.710253Z","caller":"traceutil/trace.go:171","msg":"trace[553674045] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"160.064729ms","start":"2026-02-10T18:55:34.550173Z","end":"2026-02-10T18:55:34.710238Z","steps":["trace[553674045] 'process raft request' (duration: 158.597035ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:34.710759Z","caller":"traceutil/trace.go:171","msg":"trace[1208291464] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"159.465325ms","start":"2026-02-10T18:55:34.55128Z","end":"2026-02-10T18:55:34.710745Z","steps":["trace[1208291464] 'process raft request' (duration: 157.796768ms)"],"step_count":1}