{"level":"warn","ts":"2026-03-18T13:11:21.090958Z","caller":"embed/config.go:1209","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"warn","ts":"2026-03-18T13:11:21.091090Z","caller":"etcdmain/config.go:270","msg":"--snapshot-count is deprecated in 3.6 and will be decommissioned in 3.7."} {"level":"info","ts":"2026-03-18T13:11:21.091102Z","caller":"etcdmain/etcd.go:64","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.117:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--feature-gates=InitialCorruptCheck=true","--initial-advertise-peer-urls=https://199.19.213.117:2380","--initial-cluster=controller=https://199.19.213.117:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.117:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.117:2380","--name=controller","--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","--watch-progress-notify-interval=5s"]} {"level":"info","ts":"2026-03-18T13:11:21.091164Z","caller":"etcdmain/etcd.go:124","msg":"Initialize and start etcd server","data-dir":"/var/lib/etcd","dir-type":"empty"} {"level":"warn","ts":"2026-03-18T13:11:21.091180Z","caller":"embed/config.go:1209","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T13:11:21.091189Z","caller":"embed/etcd.go:138","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.117:2380"]} {"level":"info","ts":"2026-03-18T13:11:21.091216Z","caller":"embed/etcd.go:544","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-03-18T13:11:21.091800Z","caller":"embed/etcd.go:146","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"]} {"level":"info","ts":"2026-03-18T13:11:21.093182Z","caller":"embed/etcd.go:323","msg":"starting an etcd server","etcd-version":"3.6.4","git-sha":"5400cdc","go-version":"go1.23.11","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"controller","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.19.213.117:2380"],"listen-peer-urls":["https://199.19.213.117:2380"],"advertise-client-urls":["https://199.19.213.117:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"experimental-local-address":"","cors":["*"],"host-whitelist":["*"],"initial-cluster":"controller=https://199.19.213.117: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,"feature-gates":"InitialCorruptCheck=true","initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","discovery-token":"","discovery-endpoints":"","discovery-dial-timeout":"2s","discovery-request-timeout":"5s","discovery-keepalive-time":"2s","discovery-keepalive-timeout":"6s","discovery-insecure-transport":true,"discovery-insecure-skip-tls-verify":false,"discovery-cert":"","discovery-key":"","discovery-cacert":"","discovery-user":"","downgrade-check-interval":"5s","max-learners":1,"v2-deprecation":"write-only"} {"level":"info","ts":"2026-03-18T13:11:21.094013Z","logger":"bbolt","caller":"backend/backend.go:203","msg":"Opening db file (/var/lib/etcd/member/snap/db) with mode -rw------- and with options: {Timeout: 0s, NoGrowSync: false, NoFreelistSync: true, PreLoadFreelist: false, FreelistType: hashmap, ReadOnly: false, MmapFlags: 8000, InitialMmapSize: 10737418240, PageSize: 0, NoSync: false, OpenFile: 0x0, Mlock: false, Logger: 0xc000152a78}"} {"level":"info","ts":"2026-03-18T13:11:21.100823Z","logger":"bbolt","caller":"bbolt@v1.4.2/db.go:321","msg":"Opening bbolt db (/var/lib/etcd/member/snap/db) successfully"} {"level":"info","ts":"2026-03-18T13:11:21.101835Z","caller":"storage/backend.go:80","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.947272ms"} {"level":"info","ts":"2026-03-18T13:11:21.101872Z","caller":"etcdserver/bootstrap.go:220","msg":"restore consistentIndex","index":0} {"level":"info","ts":"2026-03-18T13:11:21.101885Z","caller":"etcdserver/bootstrap.go:94","msg":"bootstrapping cluster"} {"level":"info","ts":"2026-03-18T13:11:21.101924Z","caller":"etcdserver/bootstrap.go:101","msg":"bootstrapping storage"} {"level":"info","ts":"2026-03-18T13:11:21.108002Z","caller":"etcdserver/bootstrap.go:109","msg":"bootstrapping raft"} {"level":"info","ts":"2026-03-18T13:11:21.108101Z","caller":"etcdserver/bootstrap.go:499","msg":"starting local member","local-member-id":"5f9f905057b5b268","cluster-id":"293172f52aeb3f03"} {"level":"info","ts":"2026-03-18T13:11:21.108126Z","caller":"etcdserver/server.go:312","msg":"bootstrap successfully"} {"level":"info","ts":"2026-03-18T13:11:21.108196Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"5f9f905057b5b268 switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T13:11:21.108265Z","logger":"raft","caller":"v3@v3.6.0/raft.go:897","msg":"5f9f905057b5b268 became follower at term 0"} {"level":"info","ts":"2026-03-18T13:11:21.108276Z","logger":"raft","caller":"v3@v3.6.0/raft.go:493","msg":"newRaft 5f9f905057b5b268 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T13:11:21.108287Z","logger":"raft","caller":"v3@v3.6.0/raft.go:897","msg":"5f9f905057b5b268 became follower at term 1"} {"level":"info","ts":"2026-03-18T13:11:21.108318Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"5f9f905057b5b268 switched to configuration voters=(6890384629643457128)"} {"level":"warn","ts":"2026-03-18T13:11:21.121152Z","caller":"auth/store.go:1135","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T13:11:21.123281Z","caller":"mvcc/kvstore.go:408","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T13:11:21.127367Z","caller":"storage/quota.go:93","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-18T13:11:21.129559Z","caller":"etcdserver/server.go:598","msg":"starting etcd server","local-member-id":"5f9f905057b5b268","local-server-version":"3.6.4","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T13:11:21.129968Z","caller":"fileutil/purge.go:49","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-18T13:11:21.130278Z","caller":"fileutil/purge.go:49","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-18T13:11:21.130299Z","caller":"fileutil/purge.go:49","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-18T13:11:21.130879Z","caller":"etcdserver/server.go:483","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f9f905057b5b268","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T13:11:21.131272Z","caller":"embed/etcd.go:766","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-03-18T13:11:21.131465Z","caller":"embed/etcd.go:640","msg":"serving peer traffic","address":"199.19.213.117:2380"} {"level":"info","ts":"2026-03-18T13:11:21.131547Z","caller":"embed/etcd.go:611","msg":"cmux::serve","address":"199.19.213.117:2380"} {"level":"info","ts":"2026-03-18T13:11:21.132874Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"5f9f905057b5b268 switched to configuration voters=(6890384629643457128)"} {"level":"info","ts":"2026-03-18T13:11:21.133400Z","caller":"membership/cluster.go:424","msg":"added member","cluster-id":"293172f52aeb3f03","local-member-id":"5f9f905057b5b268","added-peer-id":"5f9f905057b5b268","added-peer-peer-urls":["https://199.19.213.117:2380"],"added-peer-is-learner":false} {"level":"info","ts":"2026-03-18T13:11:21.133543Z","caller":"embed/etcd.go:292","msg":"now serving peer/client/metrics","local-member-id":"5f9f905057b5b268","initial-advertise-peer-urls":["https://199.19.213.117:2380"],"listen-peer-urls":["https://199.19.213.117:2380"],"advertise-client-urls":["https://199.19.213.117:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T13:11:21.133610Z","caller":"embed/etcd.go:890","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T13:11:21.208938Z","logger":"raft","caller":"v3@v3.6.0/raft.go:988","msg":"5f9f905057b5b268 is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T13:11:21.209061Z","logger":"raft","caller":"v3@v3.6.0/raft.go:930","msg":"5f9f905057b5b268 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T13:11:21.209131Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1077","msg":"5f9f905057b5b268 received MsgPreVoteResp from 5f9f905057b5b268 at term 1"} {"level":"info","ts":"2026-03-18T13:11:21.209153Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1693","msg":"5f9f905057b5b268 has received 1 MsgPreVoteResp votes and 0 vote rejections"} {"level":"info","ts":"2026-03-18T13:11:21.209182Z","logger":"raft","caller":"v3@v3.6.0/raft.go:912","msg":"5f9f905057b5b268 became candidate at term 2"} {"level":"info","ts":"2026-03-18T13:11:21.209942Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1077","msg":"5f9f905057b5b268 received MsgVoteResp from 5f9f905057b5b268 at term 2"} {"level":"info","ts":"2026-03-18T13:11:21.210057Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1693","msg":"5f9f905057b5b268 has received 1 MsgVoteResp votes and 0 vote rejections"} {"level":"info","ts":"2026-03-18T13:11:21.210095Z","logger":"raft","caller":"v3@v3.6.0/raft.go:970","msg":"5f9f905057b5b268 became leader at term 2"} {"level":"info","ts":"2026-03-18T13:11:21.210118Z","logger":"raft","caller":"v3@v3.6.0/node.go:370","msg":"raft.node: 5f9f905057b5b268 elected leader 5f9f905057b5b268 at term 2"} {"level":"info","ts":"2026-03-18T13:11:21.211808Z","caller":"etcdserver/server.go:1804","msg":"published local member to cluster through raft","local-member-id":"5f9f905057b5b268","local-member-attributes":"{Name:controller ClientURLs:[https://199.19.213.117:2379]}","cluster-id":"293172f52aeb3f03","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T13:11:21.211859Z","caller":"embed/serve.go:138","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T13:11:21.211845Z","caller":"embed/serve.go:138","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T13:11:21.212460Z","caller":"etcdserver/server.go:2404","msg":"setting up initial cluster version using v3 API","cluster-version":"3.6"} {"level":"info","ts":"2026-03-18T13:11:21.213978Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T13:11:21.214066Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-03-18T13:11:21.213995Z","caller":"v3rpc/grpc.go:52","msg":"etcdserver: failed to register grpc metrics","error":"duplicate metrics collector registration attempted"} {"level":"info","ts":"2026-03-18T13:11:21.213967Z","caller":"v3rpc/health.go:63","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-03-18T13:11:21.214259Z","caller":"membership/cluster.go:682","msg":"set initial cluster version","cluster-id":"293172f52aeb3f03","local-member-id":"5f9f905057b5b268","cluster-version":"3.6"} {"level":"info","ts":"2026-03-18T13:11:21.214402Z","caller":"v3rpc/health.go:63","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-03-18T13:11:21.214755Z","caller":"api/capability.go:76","msg":"enabled capabilities for version","cluster-version":"3.6"} {"level":"info","ts":"2026-03-18T13:11:21.215067Z","caller":"etcdserver/server.go:2424","msg":"cluster version is updated","cluster-version":"3.6"} {"level":"info","ts":"2026-03-18T13:11:21.215418Z","caller":"version/monitor.go:116","msg":"cluster version differs from storage version.","cluster-version":"3.6.0","storage-version":"3.5.0"} {"level":"info","ts":"2026-03-18T13:11:21.216419Z","caller":"schema/migration.go:65","msg":"updated storage version","new-storage-version":"3.6.0"} {"level":"info","ts":"2026-03-18T13:11:21.219391Z","caller":"embed/serve.go:283","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.117:2379"} {"level":"info","ts":"2026-03-18T13:11:21.219982Z","caller":"embed/serve.go:283","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-18T13:11:21.731326Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:43924","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.735539Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:43942","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.753287Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:43966","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.763367Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:43988","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.780100Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44022","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.798746Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44044","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.809528Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44086","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.816951Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44100","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.828495Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44118","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.838204Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44126","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.851603Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44148","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.867077Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44156","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.875789Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44164","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.886950Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44200","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.898756Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44208","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.908726Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44228","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.923178Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44246","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.932912Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44272","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.941844Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44288","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.952828Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44312","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.971921Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44330","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:21.980116Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44346","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.015860Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44360","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.025193Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44368","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.036889Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44390","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.049419Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44420","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.056244Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44436","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.065358Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44464","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.073552Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44478","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.085105Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44480","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.099655Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44490","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.108183Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44504","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.123635Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44514","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.136497Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44532","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.148752Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44536","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.157530Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44544","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.170489Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44574","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.181097Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44580","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.190902Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44608","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.219932Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44612","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.229823Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44630","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.245468Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44634","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.249885Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44648","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.262707Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44680","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.274563Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44692","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.284420Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44724","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.314788Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44748","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.326929Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44776","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.337215Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44790","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.347243Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44810","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.356504Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44838","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.367450Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44868","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.378047Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44874","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.391169Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44900","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.398579Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44948","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.407201Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44954","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.416099Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44966","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.429577Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44970","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.465311Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:44990","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.472884Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45012","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.485577Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45034","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:11:22.524338Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45058","server-name":"","error":"EOF"} {"level":"info","ts":"2026-03-18T13:11:25.111697Z","caller":"traceutil/trace.go:172","msg":"trace[1580614113] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"209.7206ms","start":"2026-03-18T13:11:24.901954Z","end":"2026-03-18T13:11:25.111675Z","steps":["trace[1580614113] 'process raft request' (duration: 209.587038ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:25.231697Z","caller":"traceutil/trace.go:172","msg":"trace[1889993460] transaction","detail":"{read_only:false; number_of_response:0; response_revision:214; }","duration":"118.70933ms","start":"2026-03-18T13:11:25.112973Z","end":"2026-03-18T13:11:25.231682Z","steps":["trace[1889993460] 'process raft request' (duration: 115.972613ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:25.238549Z","caller":"traceutil/trace.go:172","msg":"trace[1699832507] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"121.779931ms","start":"2026-03-18T13:11:25.116744Z","end":"2026-03-18T13:11:25.238524Z","steps":["trace[1699832507] 'process raft request' (duration: 116.927636ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:25.609906Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"350.345503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-18T13:11:25.610007Z","caller":"traceutil/trace.go:172","msg":"trace[632484761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:215; }","duration":"350.492745ms","start":"2026-03-18T13:11:25.259493Z","end":"2026-03-18T13:11:25.609986Z","steps":["trace[632484761] 'agreement among raft nodes before linearized reading' (duration: 96.996771ms)","trace[632484761] 'range keys from in-memory index tree' (duration: 253.23496ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:25.610043Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:25.259474Z","time spent":"350.560895ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:11:25.610153Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"253.329731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-18T13:11:25.610238Z","caller":"traceutil/trace.go:172","msg":"trace[1620667471] linearizableReadLoop","detail":"{readStateIndex:222; appliedIndex:221; }","duration":"253.802828ms","start":"2026-03-18T13:11:25.356423Z","end":"2026-03-18T13:11:25.610226Z","steps":["trace[1620667471] 'read index received' (duration: 74.871µs)","trace[1620667471] 'applied index is now lower than readState.Index' (duration: 253.727017ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:25.610346Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"346.314839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-18T13:11:25.610369Z","caller":"traceutil/trace.go:172","msg":"trace[309161925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:216; }","duration":"346.34015ms","start":"2026-03-18T13:11:25.264022Z","end":"2026-03-18T13:11:25.610362Z","steps":["trace[309161925] 'agreement among raft nodes before linearized reading' (duration: 346.241298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:25.610403Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:25.264009Z","time spent":"346.38228ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-03-18T13:11:25.610584Z","caller":"traceutil/trace.go:172","msg":"trace[1652214694] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"366.713981ms","start":"2026-03-18T13:11:25.243601Z","end":"2026-03-18T13:11:25.610315Z","steps":["trace[1652214694] 'process raft request' (duration: 112.883293ms)","trace[1652214694] 'compare' (duration: 253.21688ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:25.610958Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:25.243577Z","time spent":"367.227278ms","remote":"127.0.0.1:44560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":342,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T13:11:26.247433Z","caller":"traceutil/trace.go:172","msg":"trace[1066420584] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"633.10093ms","start":"2026-03-18T13:11:25.614305Z","end":"2026-03-18T13:11:26.247406Z","steps":["trace[1066420584] 'process raft request' (duration: 581.008816ms)","trace[1066420584] 'compare' (duration: 51.917341ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:26.247594Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:25.678264Z","time spent":"569.326922ms","remote":"127.0.0.1:44070","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-18T13:11:26.247626Z","caller":"traceutil/trace.go:172","msg":"trace[968142925] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"632.541542ms","start":"2026-03-18T13:11:25.615064Z","end":"2026-03-18T13:11:26.247606Z","steps":["trace[968142925] 'process raft request' (duration: 632.287719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:26.247692Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:25.614282Z","time spent":"633.214191ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:11:26.247762Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:25.615055Z","time spent":"632.627593ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T13:11:32.122739Z","caller":"traceutil/trace.go:172","msg":"trace[377941583] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:376; }","duration":"114.752362ms","start":"2026-03-18T13:11:32.007833Z","end":"2026-03-18T13:11:32.122585Z","steps":["trace[377941583] 'read index received' (duration: 114.745722ms)","trace[377941583] 'applied index is now lower than readState.Index' (duration: 5.54µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:32.123150Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"115.281399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" limit:1 ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-03-18T13:11:32.123372Z","caller":"traceutil/trace.go:172","msg":"trace[1362598214] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:359; }","duration":"115.446101ms","start":"2026-03-18T13:11:32.007829Z","end":"2026-03-18T13:11:32.123275Z","steps":["trace[1362598214] 'agreement among raft nodes before linearized reading' (duration: 114.960215ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:32.123534Z","caller":"traceutil/trace.go:172","msg":"trace[1974350022] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"122.552487ms","start":"2026-03-18T13:11:32.000929Z","end":"2026-03-18T13:11:32.123482Z","steps":["trace[1974350022] 'process raft request' (duration: 121.786668ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:32.302869Z","caller":"traceutil/trace.go:172","msg":"trace[1511863926] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:377; }","duration":"179.991865ms","start":"2026-03-18T13:11:32.122841Z","end":"2026-03-18T13:11:32.302833Z","steps":["trace[1511863926] 'read index received' (duration: 179.981315ms)","trace[1511863926] 'applied index is now lower than readState.Index' (duration: 9.07µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:32.303200Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"195.365452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" limit:1 ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-18T13:11:32.303295Z","caller":"traceutil/trace.go:172","msg":"trace[517039153] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:360; }","duration":"195.480843ms","start":"2026-03-18T13:11:32.107796Z","end":"2026-03-18T13:11:32.303277Z","steps":["trace[517039153] 'agreement among raft nodes before linearized reading' (duration: 195.050878ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:32.303396Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"146.171614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-cidrs-controller\" limit:1 ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-18T13:11:32.303497Z","caller":"traceutil/trace.go:172","msg":"trace[1519008992] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-cidrs-controller; range_end:; response_count:1; response_revision:361; }","duration":"146.304856ms","start":"2026-03-18T13:11:32.157172Z","end":"2026-03-18T13:11:32.303477Z","steps":["trace[1519008992] 'agreement among raft nodes before linearized reading' (duration: 146.052933ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:32.303742Z","caller":"traceutil/trace.go:172","msg":"trace[562501592] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"297.381951ms","start":"2026-03-18T13:11:32.006344Z","end":"2026-03-18T13:11:32.303726Z","steps":["trace[562501592] 'process raft request' (duration: 296.54547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:32.303456Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"245.573911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" limit:1 ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-03-18T13:11:32.303913Z","caller":"traceutil/trace.go:172","msg":"trace[1654688737] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:361; }","duration":"246.031947ms","start":"2026-03-18T13:11:32.057865Z","end":"2026-03-18T13:11:32.303897Z","steps":["trace[1654688737] 'agreement among raft nodes before linearized reading' (duration: 245.48509ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:32.453679Z","caller":"traceutil/trace.go:172","msg":"trace[735358282] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"140.543276ms","start":"2026-03-18T13:11:32.313106Z","end":"2026-03-18T13:11:32.453649Z","steps":["trace[735358282] 'process raft request' (duration: 94.7276ms)","trace[735358282] 'compare' (duration: 45.503293ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:32.613637Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"106.30828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/legacy-service-account-token-cleaner\" limit:1 ","response":"range_response_count:1 size:238"} {"level":"info","ts":"2026-03-18T13:11:32.613723Z","caller":"traceutil/trace.go:172","msg":"trace[2125140] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/legacy-service-account-token-cleaner; range_end:; response_count:1; response_revision:364; }","duration":"106.409571ms","start":"2026-03-18T13:11:32.507296Z","end":"2026-03-18T13:11:32.613705Z","steps":["trace[2125140] 'agreement among raft nodes before linearized reading' (duration: 49.090775ms)","trace[2125140] 'range keys from in-memory index tree' (duration: 57.120113ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:11:32.614049Z","caller":"traceutil/trace.go:172","msg":"trace[139961150] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"115.910146ms","start":"2026-03-18T13:11:32.498121Z","end":"2026-03-18T13:11:32.614031Z","steps":["trace[139961150] 'process raft request' (duration: 58.328777ms)","trace[139961150] 'compare' (duration: 56.997422ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:11:32.614432Z","caller":"traceutil/trace.go:172","msg":"trace[499018651] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"113.732531ms","start":"2026-03-18T13:11:32.500671Z","end":"2026-03-18T13:11:32.614403Z","steps":["trace[499018651] 'process raft request' (duration: 113.474908ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:32.831970Z","caller":"traceutil/trace.go:172","msg":"trace[50589597] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:386; }","duration":"144.24262ms","start":"2026-03-18T13:11:32.687696Z","end":"2026-03-18T13:11:32.831938Z","steps":["trace[50589597] 'read index received' (duration: 144.23001ms)","trace[50589597] 'applied index is now lower than readState.Index' (duration: 10.8µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:11:32.881698Z","caller":"traceutil/trace.go:172","msg":"trace[392159727] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"224.173401ms","start":"2026-03-18T13:11:32.657508Z","end":"2026-03-18T13:11:32.881681Z","steps":["trace[392159727] 'process raft request' (duration: 174.454137ms)","trace[392159727] 'compare' (duration: 49.630273ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:32.881626Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"193.893682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" limit:1 ","response":"range_response_count:1 size:4299"} {"level":"info","ts":"2026-03-18T13:11:32.882709Z","caller":"traceutil/trace.go:172","msg":"trace[731632913] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:369; }","duration":"194.994896ms","start":"2026-03-18T13:11:32.687687Z","end":"2026-03-18T13:11:32.882682Z","steps":["trace[731632913] 'agreement among raft nodes before linearized reading' (duration: 144.341381ms)","trace[731632913] 'range keys from in-memory index tree' (duration: 49.471411ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:32.893417Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"135.14671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" limit:1 ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-18T13:11:32.893478Z","caller":"traceutil/trace.go:172","msg":"trace[763659878] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:370; }","duration":"135.21641ms","start":"2026-03-18T13:11:32.758249Z","end":"2026-03-18T13:11:32.893465Z","steps":["trace[763659878] 'agreement among raft nodes before linearized reading' (duration: 135.045518ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:32.893972Z","caller":"traceutil/trace.go:172","msg":"trace[1690706490] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"206.248083ms","start":"2026-03-18T13:11:32.687714Z","end":"2026-03-18T13:11:32.893963Z","steps":["trace[1690706490] 'process raft request' (duration: 205.740506ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:32.894116Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"186.197269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" limit:1 ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-18T13:11:32.894724Z","caller":"traceutil/trace.go:172","msg":"trace[862083312] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:370; }","duration":"186.614314ms","start":"2026-03-18T13:11:32.707717Z","end":"2026-03-18T13:11:32.894332Z","steps":["trace[862083312] 'agreement among raft nodes before linearized reading' (duration: 185.622102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:32.960015Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"102.365902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" limit:1 ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-18T13:11:32.960107Z","caller":"traceutil/trace.go:172","msg":"trace[481143998] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:371; }","duration":"102.469284ms","start":"2026-03-18T13:11:32.857620Z","end":"2026-03-18T13:11:32.960089Z","steps":["trace[481143998] 'agreement among raft nodes before linearized reading' (duration: 102.241251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:33.101311Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"189.475316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" limit:1 ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-18T13:11:33.101662Z","caller":"traceutil/trace.go:172","msg":"trace[475965677] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:375; }","duration":"189.730419ms","start":"2026-03-18T13:11:32.911792Z","end":"2026-03-18T13:11:33.101522Z","steps":["trace[475965677] 'agreement among raft nodes before linearized reading' (duration: 92.006617ms)","trace[475965677] 'range keys from in-memory index tree' (duration: 97.344728ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:11:33.102242Z","caller":"traceutil/trace.go:172","msg":"trace[1711042303] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"198.434014ms","start":"2026-03-18T13:11:32.903780Z","end":"2026-03-18T13:11:33.102214Z","steps":["trace[1711042303] 'process raft request' (duration: 198.375484ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.102364Z","caller":"traceutil/trace.go:172","msg":"trace[1920606476] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"199.586829ms","start":"2026-03-18T13:11:32.902752Z","end":"2026-03-18T13:11:33.102339Z","steps":["trace[1920606476] 'process raft request' (duration: 199.315386ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.102544Z","caller":"traceutil/trace.go:172","msg":"trace[2026261255] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"199.604458ms","start":"2026-03-18T13:11:32.902924Z","end":"2026-03-18T13:11:33.102528Z","steps":["trace[2026261255] 'process raft request' (duration: 199.180693ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.103508Z","caller":"traceutil/trace.go:172","msg":"trace[569081336] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"200.367758ms","start":"2026-03-18T13:11:32.902042Z","end":"2026-03-18T13:11:33.102410Z","steps":["trace[569081336] 'process raft request' (duration: 199.885172ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.103496Z","caller":"traceutil/trace.go:172","msg":"trace[1250516204] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"201.178818ms","start":"2026-03-18T13:11:32.901360Z","end":"2026-03-18T13:11:33.102539Z","steps":["trace[1250516204] 'process raft request' (duration: 102.509845ms)","trace[1250516204] 'compare' (duration: 97.270507ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:33.104050Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"146.13961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" limit:1 ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-03-18T13:11:33.104182Z","caller":"traceutil/trace.go:172","msg":"trace[1376172566] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:375; }","duration":"146.377983ms","start":"2026-03-18T13:11:32.957772Z","end":"2026-03-18T13:11:33.104150Z","steps":["trace[1376172566] 'agreement among raft nodes before linearized reading' (duration: 46.056929ms)","trace[1376172566] 'range keys from in-memory index tree' (duration: 97.706512ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:11:33.104837Z","caller":"traceutil/trace.go:172","msg":"trace[492119270] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"200.699582ms","start":"2026-03-18T13:11:32.901373Z","end":"2026-03-18T13:11:33.102072Z","steps":["trace[492119270] 'process raft request' (duration: 200.486469ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.160185Z","caller":"traceutil/trace.go:172","msg":"trace[1124808957] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"188.533286ms","start":"2026-03-18T13:11:32.971623Z","end":"2026-03-18T13:11:33.160156Z","steps":["trace[1124808957] 'process raft request' (duration: 188.472556ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.160239Z","caller":"traceutil/trace.go:172","msg":"trace[915120685] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"193.139662ms","start":"2026-03-18T13:11:32.967073Z","end":"2026-03-18T13:11:33.160213Z","steps":["trace[915120685] 'process raft request' (duration: 192.887639ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.160717Z","caller":"traceutil/trace.go:172","msg":"trace[314505184] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:400; }","duration":"153.835155ms","start":"2026-03-18T13:11:33.006869Z","end":"2026-03-18T13:11:33.160704Z","steps":["trace[314505184] 'read index received' (duration: 153.830795ms)","trace[314505184] 'applied index is now lower than readState.Index' (duration: 3.56µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:33.160914Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"154.039287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" limit:1 ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-18T13:11:33.160955Z","caller":"traceutil/trace.go:172","msg":"trace[723239238] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:383; }","duration":"154.092168ms","start":"2026-03-18T13:11:33.006852Z","end":"2026-03-18T13:11:33.160945Z","steps":["trace[723239238] 'agreement among raft nodes before linearized reading' (duration: 153.944206ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.267523Z","caller":"traceutil/trace.go:172","msg":"trace[2007439393] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:400; }","duration":"106.7193ms","start":"2026-03-18T13:11:33.160775Z","end":"2026-03-18T13:11:33.267495Z","steps":["trace[2007439393] 'read index received' (duration: 106.70582ms)","trace[2007439393] 'applied index is now lower than readState.Index' (duration: 11.58µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:33.275484Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"165.139521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" limit:1 ","response":"range_response_count:1 size:2208"} {"level":"info","ts":"2026-03-18T13:11:33.275906Z","caller":"traceutil/trace.go:172","msg":"trace[1796214641] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:383; }","duration":"165.565526ms","start":"2026-03-18T13:11:33.110320Z","end":"2026-03-18T13:11:33.275886Z","steps":["trace[1796214641] 'agreement among raft nodes before linearized reading' (duration: 157.235896ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:33.275518Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"250.716347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-vvkjd\" limit:1 ","response":"range_response_count:1 size:3425"} {"level":"info","ts":"2026-03-18T13:11:33.276077Z","caller":"traceutil/trace.go:172","msg":"trace[295120964] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-vvkjd; range_end:; response_count:1; response_revision:383; }","duration":"251.273053ms","start":"2026-03-18T13:11:33.024791Z","end":"2026-03-18T13:11:33.276064Z","steps":["trace[295120964] 'agreement among raft nodes before linearized reading' (duration: 242.832682ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.275692Z","caller":"traceutil/trace.go:172","msg":"trace[631831463] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"165.687297ms","start":"2026-03-18T13:11:33.109922Z","end":"2026-03-18T13:11:33.275609Z","steps":["trace[631831463] 'process raft request' (duration: 157.61447ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.275691Z","caller":"traceutil/trace.go:172","msg":"trace[1227679652] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"163.135527ms","start":"2026-03-18T13:11:33.112533Z","end":"2026-03-18T13:11:33.275669Z","steps":["trace[1227679652] 'process raft request' (duration: 163.089926ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.275705Z","caller":"traceutil/trace.go:172","msg":"trace[20002282] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"165.431154ms","start":"2026-03-18T13:11:33.110245Z","end":"2026-03-18T13:11:33.275676Z","steps":["trace[20002282] 'process raft request' (duration: 165.281302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:33.275541Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"164.964689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" limit:1 ","response":"range_response_count:1 size:2313"} {"level":"info","ts":"2026-03-18T13:11:33.276545Z","caller":"traceutil/trace.go:172","msg":"trace[1390241804] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:383; }","duration":"165.986991ms","start":"2026-03-18T13:11:33.110543Z","end":"2026-03-18T13:11:33.276530Z","steps":["trace[1390241804] 'agreement among raft nodes before linearized reading' (duration: 156.999763ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.275797Z","caller":"traceutil/trace.go:172","msg":"trace[1934081175] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"163.984877ms","start":"2026-03-18T13:11:33.111797Z","end":"2026-03-18T13:11:33.275782Z","steps":["trace[1934081175] 'process raft request' (duration: 163.781975ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:33.344423Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"179.020127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" limit:1 ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-18T13:11:33.344481Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"166.070772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" limit:1 ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-03-18T13:11:33.344531Z","caller":"traceutil/trace.go:172","msg":"trace[2101873164] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:387; }","duration":"166.122252ms","start":"2026-03-18T13:11:33.178395Z","end":"2026-03-18T13:11:33.344517Z","steps":["trace[2101873164] 'agreement among raft nodes before linearized reading' (duration: 165.95352ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.344522Z","caller":"traceutil/trace.go:172","msg":"trace[1666488843] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:387; }","duration":"179.135918ms","start":"2026-03-18T13:11:33.165366Z","end":"2026-03-18T13:11:33.344502Z","steps":["trace[1666488843] 'agreement among raft nodes before linearized reading' (duration: 178.880905ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.344553Z","caller":"traceutil/trace.go:172","msg":"trace[1324805412] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"179.848286ms","start":"2026-03-18T13:11:33.164687Z","end":"2026-03-18T13:11:33.344535Z","steps":["trace[1324805412] 'process raft request' (duration: 179.741585ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.442775Z","caller":"traceutil/trace.go:172","msg":"trace[652754196] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"161.962242ms","start":"2026-03-18T13:11:33.280782Z","end":"2026-03-18T13:11:33.442745Z","steps":["trace[652754196] 'process raft request' (duration: 141.904802ms)","trace[652754196] 'compare' (duration: 19.800807ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:11:33.442955Z","caller":"traceutil/trace.go:172","msg":"trace[1342209525] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"114.154519ms","start":"2026-03-18T13:11:33.328788Z","end":"2026-03-18T13:11:33.442943Z","steps":["trace[1342209525] 'process raft request' (duration: 113.967146ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.442967Z","caller":"traceutil/trace.go:172","msg":"trace[1861843874] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"157.038994ms","start":"2026-03-18T13:11:33.285912Z","end":"2026-03-18T13:11:33.442951Z","steps":["trace[1861843874] 'process raft request' (duration: 156.805651ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.443069Z","caller":"traceutil/trace.go:172","msg":"trace[1108731906] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"157.800572ms","start":"2026-03-18T13:11:33.285256Z","end":"2026-03-18T13:11:33.443057Z","steps":["trace[1108731906] 'process raft request' (duration: 157.414777ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.683109Z","caller":"traceutil/trace.go:172","msg":"trace[896363424] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"227.930044ms","start":"2026-03-18T13:11:33.455171Z","end":"2026-03-18T13:11:33.683101Z","steps":["trace[896363424] 'process raft request' (duration: 227.670361ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.683251Z","caller":"traceutil/trace.go:172","msg":"trace[611868959] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"225.224171ms","start":"2026-03-18T13:11:33.458005Z","end":"2026-03-18T13:11:33.683230Z","steps":["trace[611868959] 'process raft request' (duration: 225.031429ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.683447Z","caller":"traceutil/trace.go:172","msg":"trace[1223356928] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"223.220587ms","start":"2026-03-18T13:11:33.460219Z","end":"2026-03-18T13:11:33.683440Z","steps":["trace[1223356928] 'process raft request' (duration: 222.869353ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.683460Z","caller":"traceutil/trace.go:172","msg":"trace[30836648] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"226.315934ms","start":"2026-03-18T13:11:33.457129Z","end":"2026-03-18T13:11:33.683445Z","steps":["trace[30836648] 'process raft request' (duration: 225.840078ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:33.682774Z","caller":"traceutil/trace.go:172","msg":"trace[816163949] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"231.764779ms","start":"2026-03-18T13:11:33.450972Z","end":"2026-03-18T13:11:33.682736Z","steps":["trace[816163949] 'process raft request' (duration: 174.845547ms)","trace[816163949] 'compare' (duration: 56.593968ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:11:33.683652Z","caller":"traceutil/trace.go:172","msg":"trace[1022790962] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"231.433166ms","start":"2026-03-18T13:11:33.451325Z","end":"2026-03-18T13:11:33.682758Z","steps":["trace[1022790962] 'process raft request' (duration: 231.329424ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:40.233718Z","caller":"traceutil/trace.go:172","msg":"trace[737222602] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"212.590267ms","start":"2026-03-18T13:11:40.021102Z","end":"2026-03-18T13:11:40.233692Z","steps":["trace[737222602] 'process raft request' (duration: 212.377765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:40.441252Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"176.930752ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-18T13:11:40.455624Z","caller":"traceutil/trace.go:172","msg":"trace[1681107498] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"183.569585ms","start":"2026-03-18T13:11:40.272030Z","end":"2026-03-18T13:11:40.455600Z","steps":["trace[1681107498] 'process raft request' (duration: 183.376153ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:47.516289Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855697764259534937,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-18T13:11:47.993728Z","caller":"traceutil/trace.go:172","msg":"trace[882097435] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:503; }","duration":"977.521206ms","start":"2026-03-18T13:11:47.016169Z","end":"2026-03-18T13:11:47.993690Z","steps":["trace[882097435] 'read index received' (duration: 977.511006ms)","trace[882097435] 'applied index is now lower than readState.Index' (duration: 8.73µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:47.993935Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"977.741148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:11:47.994001Z","caller":"traceutil/trace.go:172","msg":"trace[368116141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"977.826589ms","start":"2026-03-18T13:11:47.016158Z","end":"2026-03-18T13:11:47.993984Z","steps":["trace[368116141] 'agreement among raft nodes before linearized reading' (duration: 977.677028ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:47.994049Z","caller":"traceutil/trace.go:172","msg":"trace[1139970408] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"978.979561ms","start":"2026-03-18T13:11:47.015020Z","end":"2026-03-18T13:11:47.994000Z","steps":["trace[1139970408] 'process raft request' (duration: 978.787349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:47.994321Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:47.014997Z","time spent":"979.150533ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:11:47.994088Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:47.016137Z","time spent":"977.93498ms","remote":"127.0.0.1:43954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T13:11:48.303758Z","caller":"traceutil/trace.go:172","msg":"trace[487863717] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:504; }","duration":"309.921273ms","start":"2026-03-18T13:11:47.993813Z","end":"2026-03-18T13:11:48.303734Z","steps":["trace[487863717] 'read index received' (duration: 309.914123ms)","trace[487863717] 'applied index is now lower than readState.Index' (duration: 5.95µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:48.424605Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"604.678266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-18T13:11:48.424679Z","caller":"traceutil/trace.go:172","msg":"trace[1982603618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:482; }","duration":"604.757477ms","start":"2026-03-18T13:11:47.819904Z","end":"2026-03-18T13:11:48.424661Z","steps":["trace[1982603618] 'agreement among raft nodes before linearized reading' (duration: 483.975547ms)","trace[1982603618] 'range keys from in-memory index tree' (duration: 120.604619ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:48.424716Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:47.819883Z","time spent":"604.824658ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:11:48.425310Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"121.323606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T13:11:48.425627Z","caller":"traceutil/trace.go:172","msg":"trace[217079788] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:504; }","duration":"121.771281ms","start":"2026-03-18T13:11:48.303837Z","end":"2026-03-18T13:11:48.425608Z","steps":["trace[217079788] 'read index received' (duration: 23.311µs)","trace[217079788] 'applied index is now lower than readState.Index' (duration: 121.74605ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:48.425709Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"428.687803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:11:48.425734Z","caller":"traceutil/trace.go:172","msg":"trace[164470355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"428.714613ms","start":"2026-03-18T13:11:47.997011Z","end":"2026-03-18T13:11:48.425725Z","steps":["trace[164470355] 'agreement among raft nodes before linearized reading' (duration: 428.664833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:48.425759Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:47.996990Z","time spent":"428.762384ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T13:11:48.425947Z","caller":"traceutil/trace.go:172","msg":"trace[1011735164] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"1.236720524s","start":"2026-03-18T13:11:47.189213Z","end":"2026-03-18T13:11:48.425934Z","steps":["trace[1011735164] 'process raft request' (duration: 1.114723801s)","trace[1011735164] 'compare' (duration: 121.253566ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:11:48.425994Z","caller":"traceutil/trace.go:172","msg":"trace[1744250111] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"893.806692ms","start":"2026-03-18T13:11:47.532167Z","end":"2026-03-18T13:11:48.425973Z","steps":["trace[1744250111] 'process raft request' (duration: 893.209596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:48.426013Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:47.189184Z","time spent":"1.236796465s","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":528,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:11:48.426070Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:47.532123Z","time spent":"893.908192ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:11:48.424955Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.015529313s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:11:48.426199Z","caller":"traceutil/trace.go:172","msg":"trace[1708941154] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:482; }","duration":"1.016758316s","start":"2026-03-18T13:11:47.409401Z","end":"2026-03-18T13:11:48.426160Z","steps":["trace[1708941154] 'agreement among raft nodes before linearized reading' (duration: 894.49655ms)","trace[1708941154] 'range keys from in-memory index tree' (duration: 121.022493ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:50.659239Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"114.290254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T13:11:50.659439Z","caller":"traceutil/trace.go:172","msg":"trace[99923965] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"138.023563ms","start":"2026-03-18T13:11:50.521398Z","end":"2026-03-18T13:11:50.659422Z","steps":["trace[99923965] 'process raft request' (duration: 137.965222ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:50.659608Z","caller":"traceutil/trace.go:172","msg":"trace[649606223] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"138.604159ms","start":"2026-03-18T13:11:50.520935Z","end":"2026-03-18T13:11:50.659539Z","steps":["trace[649606223] 'process raft request' (duration: 23.931451ms)","trace[649606223] 'compare' (duration: 114.164893ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:53.456872Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"622.086023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T13:11:53.456970Z","caller":"traceutil/trace.go:172","msg":"trace[1368588097] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"441.627402ms","start":"2026-03-18T13:11:53.015327Z","end":"2026-03-18T13:11:53.456954Z","steps":["trace[1368588097] 'read index received' (duration: 26.49µs)","trace[1368588097] 'applied index is now lower than readState.Index' (duration: 441.599852ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:11:53.457186Z","caller":"traceutil/trace.go:172","msg":"trace[1977937804] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"788.258913ms","start":"2026-03-18T13:11:52.668914Z","end":"2026-03-18T13:11:53.457173Z","steps":["trace[1977937804] 'process raft request' (duration: 165.804626ms)","trace[1977937804] 'compare' (duration: 621.982802ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:53.457249Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:52.668889Z","time spent":"788.329684ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":424,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:11:53.457533Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"442.199637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:11:53.457575Z","caller":"traceutil/trace.go:172","msg":"trace[1740904164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"442.240658ms","start":"2026-03-18T13:11:53.015324Z","end":"2026-03-18T13:11:53.457564Z","steps":["trace[1740904164] 'agreement among raft nodes before linearized reading' (duration: 442.171727ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:53.457601Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:53.015305Z","time spent":"442.291148ms","remote":"127.0.0.1:43954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T13:11:53.818649Z","caller":"traceutil/trace.go:172","msg":"trace[1678928923] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"112.231346ms","start":"2026-03-18T13:11:53.706349Z","end":"2026-03-18T13:11:53.818581Z","steps":["trace[1678928923] 'process raft request' (duration: 112.057885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:54.820447Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"108.32579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-18T13:11:54.820519Z","caller":"traceutil/trace.go:172","msg":"trace[609559285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:497; }","duration":"108.41098ms","start":"2026-03-18T13:11:54.712093Z","end":"2026-03-18T13:11:54.820504Z","steps":["trace[609559285] 'range keys from in-memory index tree' (duration: 108.174998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:54.820875Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"105.128668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.117\" limit:1 ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-18T13:11:54.820907Z","caller":"traceutil/trace.go:172","msg":"trace[265110901] range","detail":"{range_begin:/registry/masterleases/199.19.213.117; range_end:; response_count:1; response_revision:497; }","duration":"105.164288ms","start":"2026-03-18T13:11:54.715733Z","end":"2026-03-18T13:11:54.820897Z","steps":["trace[265110901] 'range keys from in-memory index tree' (duration: 105.050307ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:57.164334Z","caller":"traceutil/trace.go:172","msg":"trace[1168194796] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"183.74508ms","start":"2026-03-18T13:11:56.980564Z","end":"2026-03-18T13:11:57.164309Z","steps":["trace[1168194796] 'process raft request' (duration: 91.353686ms)","trace[1168194796] 'compare' (duration: 92.256602ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:11:58.530032Z","caller":"traceutil/trace.go:172","msg":"trace[255497207] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:584; }","duration":"121.475444ms","start":"2026-03-18T13:11:58.408401Z","end":"2026-03-18T13:11:58.529876Z","steps":["trace[255497207] 'read index received' (duration: 121.465894ms)","trace[255497207] 'applied index is now lower than readState.Index' (duration: 7.93µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:58.785825Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"377.399405ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:11:58.785929Z","caller":"traceutil/trace.go:172","msg":"trace[628728248] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:558; }","duration":"377.506716ms","start":"2026-03-18T13:11:58.408396Z","end":"2026-03-18T13:11:58.785903Z","steps":["trace[628728248] 'agreement among raft nodes before linearized reading' (duration: 121.575385ms)","trace[628728248] 'range keys from in-memory index tree' (duration: 255.7885ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:58.786874Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"256.520047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T13:11:58.786983Z","caller":"traceutil/trace.go:172","msg":"trace[1049806376] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"416.071593ms","start":"2026-03-18T13:11:58.370891Z","end":"2026-03-18T13:11:58.786962Z","steps":["trace[1049806376] 'process raft request' (duration: 159.381635ms)","trace[1049806376] 'compare' (duration: 255.8279ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:58.787073Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:58.370854Z","time spent":"416.176134ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:11:58.807527Z","caller":"traceutil/trace.go:172","msg":"trace[756705227] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"427.933705ms","start":"2026-03-18T13:11:58.379580Z","end":"2026-03-18T13:11:58.807514Z","steps":["trace[756705227] 'process raft request' (duration: 427.583742ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:58.807566Z","caller":"traceutil/trace.go:172","msg":"trace[1953869092] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"409.43014ms","start":"2026-03-18T13:11:58.398101Z","end":"2026-03-18T13:11:58.807531Z","steps":["trace[1953869092] 'process raft request' (duration: 409.265708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:58.807685Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:58.379557Z","time spent":"428.074728ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:11:58.807698Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:58.398079Z","time spent":"409.560311ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T13:11:58.807728Z","caller":"traceutil/trace.go:172","msg":"trace[525463528] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"409.4591ms","start":"2026-03-18T13:11:58.398244Z","end":"2026-03-18T13:11:58.807703Z","steps":["trace[525463528] 'process raft request' (duration: 409.144137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:58.807865Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:58.398238Z","time spent":"409.573561ms","remote":"127.0.0.1:45048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T13:11:58.807974Z","caller":"traceutil/trace.go:172","msg":"trace[281282429] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"381.317613ms","start":"2026-03-18T13:11:58.426650Z","end":"2026-03-18T13:11:58.807967Z","steps":["trace[281282429] 'process raft request' (duration: 381.289972ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:58.807960Z","caller":"traceutil/trace.go:172","msg":"trace[1795312096] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"408.654602ms","start":"2026-03-18T13:11:58.399255Z","end":"2026-03-18T13:11:58.807910Z","steps":["trace[1795312096] 'process raft request' (duration: 408.367459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:58.808011Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:58.426625Z","time spent":"381.371973ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T13:11:58.808057Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:58.399246Z","time spent":"408.763153ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:11:58.808055Z","caller":"traceutil/trace.go:172","msg":"trace[1989557602] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"397.193593ms","start":"2026-03-18T13:11:58.410857Z","end":"2026-03-18T13:11:58.808051Z","steps":["trace[1989557602] 'process raft request' (duration: 396.958321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:58.808124Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:58.410823Z","time spent":"397.287014ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:11:58.808204Z","caller":"traceutil/trace.go:172","msg":"trace[1666176397] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"392.193146ms","start":"2026-03-18T13:11:58.415948Z","end":"2026-03-18T13:11:58.808142Z","steps":["trace[1666176397] 'process raft request' (duration: 391.934194ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:58.807831Z","caller":"traceutil/trace.go:172","msg":"trace[1789563894] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"427.543192ms","start":"2026-03-18T13:11:58.380273Z","end":"2026-03-18T13:11:58.807816Z","steps":["trace[1789563894] 'process raft request' (duration: 427.056908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:58.808402Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:58.415925Z","time spent":"392.394008ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:11:58.808407Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:58.380260Z","time spent":"428.070937ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:11:58.817109Z","caller":"traceutil/trace.go:172","msg":"trace[1120544175] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"122.374222ms","start":"2026-03-18T13:11:58.694717Z","end":"2026-03-18T13:11:58.817091Z","steps":["trace[1120544175] 'process raft request' (duration: 122.323812ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:58.817227Z","caller":"traceutil/trace.go:172","msg":"trace[1763900609] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"186.954517ms","start":"2026-03-18T13:11:58.630259Z","end":"2026-03-18T13:11:58.817213Z","steps":["trace[1763900609] 'process raft request' (duration: 186.26789ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:58.817156Z","caller":"traceutil/trace.go:172","msg":"trace[533734019] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"263.837077ms","start":"2026-03-18T13:11:58.553298Z","end":"2026-03-18T13:11:58.817135Z","steps":["trace[533734019] 'process raft request' (duration: 262.987869ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:11:59.370563Z","caller":"traceutil/trace.go:172","msg":"trace[641780132] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:609; }","duration":"414.446564ms","start":"2026-03-18T13:11:58.956090Z","end":"2026-03-18T13:11:59.370537Z","steps":["trace[641780132] 'read index received' (duration: 414.438174ms)","trace[641780132] 'applied index is now lower than readState.Index' (duration: 6.79µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:59.868812Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"912.683745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-18T13:11:59.868906Z","caller":"traceutil/trace.go:172","msg":"trace[1483981811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"912.798376ms","start":"2026-03-18T13:11:58.956086Z","end":"2026-03-18T13:11:59.868885Z","steps":["trace[1483981811] 'agreement among raft nodes before linearized reading' (duration: 414.568555ms)","trace[1483981811] 'range keys from in-memory index tree' (duration: 497.942498ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:59.868951Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:58.956077Z","time spent":"912.863446ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:11:59.869052Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"459.677867ms","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-03-18T13:11:59.869137Z","caller":"traceutil/trace.go:172","msg":"trace[1849233033] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:583; }","duration":"459.771958ms","start":"2026-03-18T13:11:59.409345Z","end":"2026-03-18T13:11:59.869117Z","steps":["trace[1849233033] 'range keys from in-memory index tree' (duration: 459.637257ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:59.871352Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"498.780307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T13:11:59.871534Z","caller":"traceutil/trace.go:172","msg":"trace[640306782] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"920.54067ms","start":"2026-03-18T13:11:58.950969Z","end":"2026-03-18T13:11:59.871509Z","steps":["trace[640306782] 'process raft request' (duration: 419.746954ms)","trace[640306782] 'compare' (duration: 498.186341ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:11:59.871772Z","caller":"traceutil/trace.go:172","msg":"trace[1787541068] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"918.928734ms","start":"2026-03-18T13:11:58.952825Z","end":"2026-03-18T13:11:59.871754Z","steps":["trace[1787541068] 'process raft request' (duration: 918.672251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:59.872468Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:58.952815Z","time spent":"919.59393ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:11:59.872259Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:58.950952Z","time spent":"920.659821ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:11:59.872959Z","caller":"traceutil/trace.go:172","msg":"trace[1099378752] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"373.750336ms","start":"2026-03-18T13:11:59.497926Z","end":"2026-03-18T13:11:59.871677Z","steps":["trace[1099378752] 'read index received' (duration: 371.410444ms)","trace[1099378752] 'applied index is now lower than readState.Index' (duration: 2.335432ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:11:59.873573Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"375.635234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-18T13:11:59.873659Z","caller":"traceutil/trace.go:172","msg":"trace[1508927910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"375.706955ms","start":"2026-03-18T13:11:59.497914Z","end":"2026-03-18T13:11:59.873621Z","steps":["trace[1508927910] 'agreement among raft nodes before linearized reading' (duration: 373.787787ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:59.873706Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:59.497890Z","time spent":"375.803036ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-03-18T13:11:59.872605Z","caller":"traceutil/trace.go:172","msg":"trace[112162712] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"918.005035ms","start":"2026-03-18T13:11:58.953698Z","end":"2026-03-18T13:11:59.871703Z","steps":["trace[112162712] 'process raft request' (duration: 917.956175ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:59.874314Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:58.953691Z","time spent":"920.420818ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:11:59.875097Z","caller":"traceutil/trace.go:172","msg":"trace[1167255978] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"902.919392ms","start":"2026-03-18T13:11:58.972158Z","end":"2026-03-18T13:11:59.875077Z","steps":["trace[1167255978] 'process raft request' (duration: 901.514239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:11:59.875150Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:58.972129Z","time spent":"902.998973ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T13:12:00.269007Z","caller":"traceutil/trace.go:172","msg":"trace[1201313433] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:618; }","duration":"255.445554ms","start":"2026-03-18T13:12:00.013514Z","end":"2026-03-18T13:12:00.268960Z","steps":["trace[1201313433] 'read index received' (duration: 255.427644ms)","trace[1201313433] 'applied index is now lower than readState.Index' (duration: 15.87µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:00.307397Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"293.857674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:00.307485Z","caller":"traceutil/trace.go:172","msg":"trace[1911897070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"293.958045ms","start":"2026-03-18T13:12:00.013507Z","end":"2026-03-18T13:12:00.307465Z","steps":["trace[1911897070] 'agreement among raft nodes before linearized reading' (duration: 255.608975ms)","trace[1911897070] 'range keys from in-memory index tree' (duration: 38.210918ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:12:00.308087Z","caller":"traceutil/trace.go:172","msg":"trace[2131921322] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"339.574175ms","start":"2026-03-18T13:11:59.968497Z","end":"2026-03-18T13:12:00.308071Z","steps":["trace[2131921322] 'process raft request' (duration: 300.67306ms)","trace[2131921322] 'compare' (duration: 38.497101ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:00.308201Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:59.968473Z","time spent":"339.645885ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T13:12:00.309205Z","caller":"traceutil/trace.go:172","msg":"trace[1995322563] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"338.419744ms","start":"2026-03-18T13:11:59.970775Z","end":"2026-03-18T13:12:00.309194Z","steps":["trace[1995322563] 'process raft request' (duration: 338.084701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:00.309271Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:59.970762Z","time spent":"338.466794ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:12:00.309334Z","caller":"traceutil/trace.go:172","msg":"trace[473891579] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"323.238651ms","start":"2026-03-18T13:11:59.986060Z","end":"2026-03-18T13:12:00.309299Z","steps":["trace[473891579] 'process raft request' (duration: 323.026209ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:00.309365Z","caller":"traceutil/trace.go:172","msg":"trace[1680421625] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"192.287322ms","start":"2026-03-18T13:12:00.117042Z","end":"2026-03-18T13:12:00.309329Z","steps":["trace[1680421625] 'process raft request' (duration: 192.121861ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:00.309449Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:11:59.986026Z","time spent":"323.365132ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:12:01.788711Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58488","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:01.940738Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58496","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.036071Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58542","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.081076Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58558","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.121124Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58574","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.160314Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58602","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.180058Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58608","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.195754Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58634","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.240368Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58650","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.261371Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58674","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.285848Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58694","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.296636Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58714","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.314799Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58722","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.335557Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58736","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.489363Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58746","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.556223Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58776","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.638421Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58786","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.665931Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58804","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.687524Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58826","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.711599Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58838","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.730398Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58866","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.744298Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58878","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:02.760877Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58908","server-name":"","error":"EOF"} {"level":"info","ts":"2026-03-18T13:12:07.300632Z","caller":"traceutil/trace.go:172","msg":"trace[2068182447] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:681; }","duration":"287.05274ms","start":"2026-03-18T13:12:07.013539Z","end":"2026-03-18T13:12:07.300592Z","steps":["trace[2068182447] 'read index received' (duration: 287.04241ms)","trace[2068182447] 'applied index is now lower than readState.Index' (duration: 8.5µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:07.306206Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"292.6601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:07.306241Z","caller":"traceutil/trace.go:172","msg":"trace[1303596876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"292.712801ms","start":"2026-03-18T13:12:07.013521Z","end":"2026-03-18T13:12:07.306234Z","steps":["trace[1303596876] 'agreement among raft nodes before linearized reading' (duration: 287.14511ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:07.306751Z","caller":"traceutil/trace.go:172","msg":"trace[1118228579] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"436.047836ms","start":"2026-03-18T13:12:06.870682Z","end":"2026-03-18T13:12:07.306730Z","steps":["trace[1118228579] 'process raft request' (duration: 430.128333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:07.306953Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:06.870646Z","time spent":"436.184818ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:12:07.311411Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"213.011895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z4wdv\" limit:1 ","response":"range_response_count:1 size:18350"} {"level":"info","ts":"2026-03-18T13:12:07.311486Z","caller":"traceutil/trace.go:172","msg":"trace[404834372] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z4wdv; range_end:; response_count:1; response_revision:654; }","duration":"213.122816ms","start":"2026-03-18T13:12:07.098347Z","end":"2026-03-18T13:12:07.311470Z","steps":["trace[404834372] 'agreement among raft nodes before linearized reading' (duration: 212.902854ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:07.312199Z","caller":"traceutil/trace.go:172","msg":"trace[1499181308] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"415.976415ms","start":"2026-03-18T13:12:06.896209Z","end":"2026-03-18T13:12:07.312185Z","steps":["trace[1499181308] 'process raft request' (duration: 415.861484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:07.312424Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:06.896181Z","time spent":"416.145546ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:12:08.240124Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"830.716106ms","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-03-18T13:12:08.240197Z","caller":"traceutil/trace.go:172","msg":"trace[2062093482] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:655; }","duration":"830.799126ms","start":"2026-03-18T13:12:07.409382Z","end":"2026-03-18T13:12:08.240182Z","steps":["trace[2062093482] 'range keys from in-memory index tree' (duration: 830.676266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:08.443777Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855697764259535328,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T13:12:08.478669Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.143715238s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T13:12:08.478943Z","caller":"traceutil/trace.go:172","msg":"trace[1238633159] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:683; }","duration":"536.022706ms","start":"2026-03-18T13:12:07.942888Z","end":"2026-03-18T13:12:08.478910Z","steps":["trace[1238633159] 'read index received' (duration: 536.011486ms)","trace[1238633159] 'applied index is now lower than readState.Index' (duration: 9.68µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:12:08.479295Z","caller":"traceutil/trace.go:172","msg":"trace[131520578] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.144478085s","start":"2026-03-18T13:12:07.334783Z","end":"2026-03-18T13:12:08.479261Z","steps":["trace[131520578] 'process raft request' (duration: 1.144210223s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:08.479447Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:07.334761Z","time spent":"1.144588546s","remote":"127.0.0.1:44300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18507,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:12:08.479186Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"536.286518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-18T13:12:08.480101Z","caller":"traceutil/trace.go:172","msg":"trace[44987869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"537.217066ms","start":"2026-03-18T13:12:07.942874Z","end":"2026-03-18T13:12:08.480091Z","steps":["trace[44987869] 'agreement among raft nodes before linearized reading' (duration: 536.159627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:08.480136Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:07.942850Z","time spent":"537.275747ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:12:08.979881Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855697764259535332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T13:12:09.480861Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855697764259535332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T13:12:09.981248Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855697764259535332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T13:12:10.014014Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.000258604s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-18T13:12:10.014079Z","caller":"traceutil/trace.go:172","msg":"trace[1422218723] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000342465s","start":"2026-03-18T13:12:08.013719Z","end":"2026-03-18T13:12:10.014061Z","steps":["trace[1422218723] 'agreement among raft nodes before linearized reading' (duration: 2.000255804s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:10.014152Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:08.013700Z","time spent":"2.000442336s","remote":"127.0.0.1:43954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/18 13:12:10 WARNING: [core] [Server #6]grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-18T13:12:10.481856Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855697764259535332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T13:12:10.567140Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"2.087418963s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T13:12:10.567294Z","caller":"traceutil/trace.go:172","msg":"trace[713921672] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:684; }","duration":"2.08827683s","start":"2026-03-18T13:12:08.478994Z","end":"2026-03-18T13:12:10.567271Z","steps":["trace[713921672] 'read index received' (duration: 2.08826705s)","trace[713921672] 'applied index is now lower than readState.Index' (duration: 8.42µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:10.633644Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.393389822s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:10.633718Z","caller":"traceutil/trace.go:172","msg":"trace[646394634] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:656; }","duration":"2.393470123s","start":"2026-03-18T13:12:08.240231Z","end":"2026-03-18T13:12:10.633702Z","steps":["trace[646394634] 'agreement among raft nodes before linearized reading' (duration: 2.327044505s)","trace[646394634] 'range keys from in-memory index tree' (duration: 66.325187ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:10.634531Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.685595698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-18T13:12:10.634779Z","caller":"traceutil/trace.go:172","msg":"trace[971975350] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"3.268126744s","start":"2026-03-18T13:12:07.366601Z","end":"2026-03-18T13:12:10.634728Z","steps":["trace[971975350] 'process raft request' (duration: 3.200811799s)","trace[971975350] 'compare' (duration: 65.62317ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:10.634932Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:07.366576Z","time spent":"3.268296536s","remote":"127.0.0.1:44374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2676,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T13:12:10.635139Z","caller":"traceutil/trace.go:172","msg":"trace[2044049193] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"2.26560064s","start":"2026-03-18T13:12:08.369520Z","end":"2026-03-18T13:12:10.635121Z","steps":["trace[2044049193] 'process raft request' (duration: 2.265132726s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:10.635257Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:08.369491Z","time spent":"2.2656998s","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:12:10.635366Z","caller":"traceutil/trace.go:172","msg":"trace[105220072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"2.685696019s","start":"2026-03-18T13:12:07.948905Z","end":"2026-03-18T13:12:10.634601Z","steps":["trace[105220072] 'agreement among raft nodes before linearized reading' (duration: 2.618450834s)","trace[105220072] 'range keys from in-memory index tree' (duration: 67.037483ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:12:10.635400Z","caller":"traceutil/trace.go:172","msg":"trace[1507872049] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"2.148671194s","start":"2026-03-18T13:12:08.486714Z","end":"2026-03-18T13:12:10.635385Z","steps":["trace[1507872049] 'process raft request' (duration: 2.148269941s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:10.635421Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:07.948893Z","time spent":"2.686514656s","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:12:10.635465Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:08.486682Z","time spent":"2.148748816s","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":424,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:12:10.635606Z","caller":"traceutil/trace.go:172","msg":"trace[809859441] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.769042592s","start":"2026-03-18T13:12:08.866519Z","end":"2026-03-18T13:12:10.635562Z","steps":["trace[809859441] 'process raft request' (duration: 1.76880888s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:10.635694Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:08.866491Z","time spent":"1.769156822s","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:12:10.635745Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"620.943585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:10.635780Z","caller":"traceutil/trace.go:172","msg":"trace[51746019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"621.352989ms","start":"2026-03-18T13:12:10.014417Z","end":"2026-03-18T13:12:10.635770Z","steps":["trace[51746019] 'agreement among raft nodes before linearized reading' (duration: 620.915215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:10.635839Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:10.014401Z","time spent":"621.430179ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T13:12:10.636084Z","caller":"traceutil/trace.go:172","msg":"trace[726599850] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.318375347s","start":"2026-03-18T13:12:09.317048Z","end":"2026-03-18T13:12:10.635423Z","steps":["trace[726599850] 'process raft request' (duration: 1.318335947s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:10.636171Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:09.317014Z","time spent":"1.319104264s","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:12:11.152851Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855697764259535341,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T13:12:11.653845Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855697764259535341,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T13:12:11.712766Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.049658797s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-18T13:12:12.649755Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.001381168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-18T13:12:12.649843Z","caller":"traceutil/trace.go:172","msg":"trace[117158934] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001490609s","start":"2026-03-18T13:12:10.648332Z","end":"2026-03-18T13:12:12.649822Z","steps":["trace[117158934] 'agreement among raft nodes before linearized reading' (duration: 2.001375708s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:12.649901Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:10.648324Z","time spent":"2.001567769s","remote":"127.0.0.1:43954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/18 13:12:12 WARNING: [core] [Server #6]grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-18T13:12:13.612550Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.960265924s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-18T13:12:13.613068Z","caller":"traceutil/trace.go:172","msg":"trace[600520419] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"2.960764038s","start":"2026-03-18T13:12:10.652188Z","end":"2026-03-18T13:12:13.612952Z","steps":["trace[600520419] 'read index received' (duration: 1.060759144s)","trace[600520419] 'applied index is now lower than readState.Index' (duration: 1.900003684s)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:13.612953Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.203650157s","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-03-18T13:12:13.613674Z","caller":"traceutil/trace.go:172","msg":"trace[1549612478] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:661; }","duration":"2.204379913s","start":"2026-03-18T13:12:11.409270Z","end":"2026-03-18T13:12:13.613650Z","steps":["trace[1549612478] 'range keys from in-memory index tree' (duration: 2.203606417s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:13.614128Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.9711892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" limit:1 ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-18T13:12:13.614210Z","caller":"traceutil/trace.go:172","msg":"trace[1374074616] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:661; }","duration":"2.971279191s","start":"2026-03-18T13:12:10.642916Z","end":"2026-03-18T13:12:13.614196Z","steps":["trace[1374074616] 'agreement among raft nodes before linearized reading' (duration: 2.971029159s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:13.614279Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:10.642902Z","time spent":"2.971364372s","remote":"127.0.0.1:44324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" limit:1 "} {"level":"info","ts":"2026-03-18T13:12:13.614309Z","caller":"traceutil/trace.go:172","msg":"trace[1616866258] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"2.971323271s","start":"2026-03-18T13:12:10.642959Z","end":"2026-03-18T13:12:13.614282Z","steps":["trace[1616866258] 'process raft request' (duration: 2.969698277s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:13.614422Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:10.642938Z","time spent":"2.971429012s","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:12:13.657938Z","caller":"traceutil/trace.go:172","msg":"trace[1807856562] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"3.014868781s","start":"2026-03-18T13:12:10.643025Z","end":"2026-03-18T13:12:13.657894Z","steps":["trace[1807856562] 'process raft request' (duration: 2.97124324s)","trace[1807856562] 'compare' (duration: 43.49982ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:13.658074Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"242.291243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:13.658129Z","caller":"traceutil/trace.go:172","msg":"trace[1039620755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"242.353554ms","start":"2026-03-18T13:12:13.415762Z","end":"2026-03-18T13:12:13.658115Z","steps":["trace[1039620755] 'agreement among raft nodes before linearized reading' (duration: 242.262053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:13.658065Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:10.643001Z","time spent":"3.015005703s","remote":"127.0.0.1:44070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":769,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T13:12:13.658305Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.008369628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:13.658375Z","caller":"traceutil/trace.go:172","msg":"trace[529170131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"1.008447269s","start":"2026-03-18T13:12:12.649913Z","end":"2026-03-18T13:12:13.658360Z","steps":["trace[529170131] 'agreement among raft nodes before linearized reading' (duration: 1.008338578s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:13.658414Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:12.649898Z","time spent":"1.008509379s","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T13:12:13.658816Z","caller":"traceutil/trace.go:172","msg":"trace[567023709] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.018286805s","start":"2026-03-18T13:12:12.640513Z","end":"2026-03-18T13:12:13.658800Z","steps":["trace[567023709] 'process raft request' (duration: 1.017397327s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:13.658927Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:12.640487Z","time spent":"1.018375325s","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:12:13.659170Z","caller":"traceutil/trace.go:172","msg":"trace[1806302709] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.017133855s","start":"2026-03-18T13:12:12.642018Z","end":"2026-03-18T13:12:13.659152Z","steps":["trace[1806302709] 'process raft request' (duration: 1.015951615s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:13.659272Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:12.642006Z","time spent":"1.017208505s","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:12:13.659439Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.017497208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-18T13:12:13.659509Z","caller":"traceutil/trace.go:172","msg":"trace[1168867673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:664; }","duration":"1.018464257s","start":"2026-03-18T13:12:12.641029Z","end":"2026-03-18T13:12:13.659493Z","steps":["trace[1168867673] 'agreement among raft nodes before linearized reading' (duration: 1.017183086s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:13.659561Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:12.641019Z","time spent":"1.018528627s","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-03-18T13:12:16.028858Z","caller":"traceutil/trace.go:172","msg":"trace[615396393] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:715; }","duration":"360.680306ms","start":"2026-03-18T13:12:15.668156Z","end":"2026-03-18T13:12:16.028836Z","steps":["trace[615396393] 'read index received' (duration: 360.671035ms)","trace[615396393] 'applied index is now lower than readState.Index' (duration: 5.571µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:12:16.029124Z","caller":"traceutil/trace.go:172","msg":"trace[1523746251] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"405.121999ms","start":"2026-03-18T13:12:15.623980Z","end":"2026-03-18T13:12:16.029102Z","steps":["trace[1523746251] 'process raft request' (duration: 404.977778ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:16.029265Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:15.623969Z","time spent":"405.228821ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:12:16.029257Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"360.896597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-18T13:12:16.029314Z","caller":"traceutil/trace.go:172","msg":"trace[43549424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:685; }","duration":"361.15209ms","start":"2026-03-18T13:12:15.668151Z","end":"2026-03-18T13:12:16.029303Z","steps":["trace[43549424] 'agreement among raft nodes before linearized reading' (duration: 360.804417ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:16.029658Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:15.668143Z","time spent":"361.350021ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-03-18T13:12:16.031098Z","caller":"traceutil/trace.go:172","msg":"trace[290379883] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"365.612008ms","start":"2026-03-18T13:12:15.665469Z","end":"2026-03-18T13:12:16.031081Z","steps":["trace[290379883] 'process raft request' (duration: 365.553347ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:16.031227Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:15.665437Z","time spent":"365.717508ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:12:16.031367Z","caller":"traceutil/trace.go:172","msg":"trace[1339271092] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"367.312742ms","start":"2026-03-18T13:12:15.664035Z","end":"2026-03-18T13:12:16.031348Z","steps":["trace[1339271092] 'process raft request' (duration: 366.881668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:16.031455Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:15.664003Z","time spent":"367.404534ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:12:18.623706Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"214.491561ms","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-03-18T13:12:18.623792Z","caller":"traceutil/trace.go:172","msg":"trace[1609920202] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:709; }","duration":"214.647202ms","start":"2026-03-18T13:12:18.409127Z","end":"2026-03-18T13:12:18.623774Z","steps":["trace[1609920202] 'range keys from in-memory index tree' (duration: 214.474251ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:20.247370Z","caller":"traceutil/trace.go:172","msg":"trace[278567317] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:743; }","duration":"231.151522ms","start":"2026-03-18T13:12:20.016180Z","end":"2026-03-18T13:12:20.247331Z","steps":["trace[278567317] 'read index received' (duration: 231.138211ms)","trace[278567317] 'applied index is now lower than readState.Index' (duration: 11.101µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:20.247575Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"231.379697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:20.247632Z","caller":"traceutil/trace.go:172","msg":"trace[1017035653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:713; }","duration":"231.447802ms","start":"2026-03-18T13:12:20.016166Z","end":"2026-03-18T13:12:20.247614Z","steps":["trace[1017035653] 'agreement among raft nodes before linearized reading' (duration: 231.326803ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:20.247770Z","caller":"traceutil/trace.go:172","msg":"trace[914013306] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"267.10139ms","start":"2026-03-18T13:12:19.980643Z","end":"2026-03-18T13:12:20.247745Z","steps":["trace[914013306] 'process raft request' (duration: 266.94262ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:20.508232Z","caller":"traceutil/trace.go:172","msg":"trace[2001045933] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:744; }","duration":"260.742816ms","start":"2026-03-18T13:12:20.247452Z","end":"2026-03-18T13:12:20.508195Z","steps":["trace[2001045933] 'read index received' (duration: 260.736095ms)","trace[2001045933] 'applied index is now lower than readState.Index' (duration: 5.811µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:20.517792Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"465.635095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-18T13:12:20.517858Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"464.855243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-18T13:12:20.517873Z","caller":"traceutil/trace.go:172","msg":"trace[210112434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:714; }","duration":"465.750543ms","start":"2026-03-18T13:12:20.052103Z","end":"2026-03-18T13:12:20.517853Z","steps":["trace[210112434] 'agreement among raft nodes before linearized reading' (duration: 456.22081ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:20.517916Z","caller":"traceutil/trace.go:172","msg":"trace[2119165264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"464.937178ms","start":"2026-03-18T13:12:20.052964Z","end":"2026-03-18T13:12:20.517901Z","steps":["trace[2119165264] 'agreement among raft nodes before linearized reading' (duration: 455.278587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:20.517925Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.052080Z","time spent":"465.832818ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:12:20.517956Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.052956Z","time spent":"464.991532ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:12:20.518196Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"109.948497ms","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-03-18T13:12:20.518244Z","caller":"traceutil/trace.go:172","msg":"trace[1562162414] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:714; }","duration":"110.003561ms","start":"2026-03-18T13:12:20.408228Z","end":"2026-03-18T13:12:20.518231Z","steps":["trace[1562162414] 'range keys from in-memory index tree' (duration: 109.883733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:20.519326Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"328.059784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z4wdv\" limit:1 ","response":"range_response_count:1 size:19900"} {"level":"info","ts":"2026-03-18T13:12:20.519455Z","caller":"traceutil/trace.go:172","msg":"trace[1318196923] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z4wdv; range_end:; response_count:1; response_revision:714; }","duration":"328.198464ms","start":"2026-03-18T13:12:20.191202Z","end":"2026-03-18T13:12:20.519400Z","steps":["trace[1318196923] 'agreement among raft nodes before linearized reading' (duration: 317.008381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:20.519509Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.191183Z","time spent":"328.308611ms","remote":"127.0.0.1:44300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":19922,"request content":"key:\"/registry/pods/kube-system/cilium-z4wdv\" limit:1 "} {"level":"info","ts":"2026-03-18T13:12:20.519554Z","caller":"traceutil/trace.go:172","msg":"trace[178780612] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"472.04547ms","start":"2026-03-18T13:12:20.047486Z","end":"2026-03-18T13:12:20.519531Z","steps":["trace[178780612] 'process raft request' (duration: 471.804824ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:20.519706Z","caller":"traceutil/trace.go:172","msg":"trace[956204948] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"474.025562ms","start":"2026-03-18T13:12:20.045453Z","end":"2026-03-18T13:12:20.519478Z","steps":["trace[956204948] 'process raft request' (duration: 462.822918ms)","trace[956204948] 'compare' (duration: 10.899144ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:20.520399Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.045432Z","time spent":"474.920332ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:12:20.519783Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.047471Z","time spent":"472.177359ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:12:20.924718Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"405.364405ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-18T13:12:20.924916Z","caller":"traceutil/trace.go:172","msg":"trace[124057002] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:744; }","duration":"416.607161ms","start":"2026-03-18T13:12:20.508296Z","end":"2026-03-18T13:12:20.924903Z","steps":["trace[124057002] 'read index received' (duration: 33.212µs)","trace[124057002] 'applied index is now lower than readState.Index' (duration: 416.572959ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:20.925149Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"665.990753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:20.925172Z","caller":"traceutil/trace.go:172","msg":"trace[1959548951] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:716; }","duration":"666.021665ms","start":"2026-03-18T13:12:20.259142Z","end":"2026-03-18T13:12:20.925163Z","steps":["trace[1959548951] 'agreement among raft nodes before linearized reading' (duration: 665.969181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:20.925134Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"662.396505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T13:12:20.925220Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"667.955913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:20.925237Z","caller":"traceutil/trace.go:172","msg":"trace[813030279] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:716; }","duration":"667.972374ms","start":"2026-03-18T13:12:20.257261Z","end":"2026-03-18T13:12:20.925233Z","steps":["trace[813030279] 'agreement among raft nodes before linearized reading' (duration: 667.944482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:20.925252Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.257255Z","time spent":"667.993535ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" limit:1 "} {"level":"info","ts":"2026-03-18T13:12:20.925241Z","caller":"traceutil/trace.go:172","msg":"trace[861193249] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:716; }","duration":"662.524133ms","start":"2026-03-18T13:12:20.262689Z","end":"2026-03-18T13:12:20.925213Z","steps":["trace[861193249] 'agreement among raft nodes before linearized reading' (duration: 662.32646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:20.925304Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.262676Z","time spent":"662.611519ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:12:20.925193Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.259116Z","time spent":"666.072228ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:12:20.925241Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"469.78569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:20.925757Z","caller":"traceutil/trace.go:172","msg":"trace[1020748743] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:716; }","duration":"470.308215ms","start":"2026-03-18T13:12:20.455430Z","end":"2026-03-18T13:12:20.925738Z","steps":["trace[1020748743] 'agreement among raft nodes before linearized reading' (duration: 469.735237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:20.925861Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.455414Z","time spent":"470.431733ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" limit:1 "} {"level":"info","ts":"2026-03-18T13:12:20.925355Z","caller":"traceutil/trace.go:172","msg":"trace[1035688930] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"400.435708ms","start":"2026-03-18T13:12:20.524898Z","end":"2026-03-18T13:12:20.925333Z","steps":["trace[1035688930] 'process raft request' (duration: 399.909473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:20.926695Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.524831Z","time spent":"401.730614ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:12:21.058251Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"802.043457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:21.080094Z","caller":"traceutil/trace.go:172","msg":"trace[29585859] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:717; }","duration":"823.906311ms","start":"2026-03-18T13:12:20.256172Z","end":"2026-03-18T13:12:21.080079Z","steps":["trace[29585859] 'agreement among raft nodes before linearized reading' (duration: 669.213386ms)","trace[29585859] 'range keys from in-memory index tree' (duration: 132.802349ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:21.080131Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.256162Z","time spent":"823.959024ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:12:21.080149Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"831.045635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:21.080209Z","caller":"traceutil/trace.go:172","msg":"trace[259754669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"831.12949ms","start":"2026-03-18T13:12:20.249065Z","end":"2026-03-18T13:12:21.080195Z","steps":["trace[259754669] 'agreement among raft nodes before linearized reading' (duration: 676.359091ms)","trace[259754669] 'range keys from in-memory index tree' (duration: 154.658752ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:21.080240Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.249050Z","time spent":"831.184554ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T13:12:21.080296Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"824.137916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T13:12:21.080359Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"824.186169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:21.080362Z","caller":"traceutil/trace.go:172","msg":"trace[101225643] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:717; }","duration":"824.218371ms","start":"2026-03-18T13:12:20.256129Z","end":"2026-03-18T13:12:21.080347Z","steps":["trace[101225643] 'agreement among raft nodes before linearized reading' (duration: 669.278151ms)","trace[101225643] 'range keys from in-memory index tree' (duration: 154.839504ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:12:21.080382Z","caller":"traceutil/trace.go:172","msg":"trace[1334267060] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:717; }","duration":"824.209621ms","start":"2026-03-18T13:12:20.256165Z","end":"2026-03-18T13:12:21.080375Z","steps":["trace[1334267060] 'agreement among raft nodes before linearized reading' (duration: 669.229598ms)","trace[1334267060] 'range keys from in-memory index tree' (duration: 154.947251ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:21.080398Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.256161Z","time spent":"824.232512ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:12:21.080406Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"824.287746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T13:12:21.080421Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"824.272585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T13:12:21.080395Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.256124Z","time spent":"824.263284ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" limit:1 "} {"level":"info","ts":"2026-03-18T13:12:21.080446Z","caller":"traceutil/trace.go:172","msg":"trace[679316843] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:717; }","duration":"824.294746ms","start":"2026-03-18T13:12:20.256144Z","end":"2026-03-18T13:12:21.080438Z","steps":["trace[679316843] 'agreement among raft nodes before linearized reading' (duration: 669.256069ms)","trace[679316843] 'range keys from in-memory index tree' (duration: 155.009965ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:21.080468Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.256121Z","time spent":"824.34212ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" limit:1 "} {"level":"info","ts":"2026-03-18T13:12:21.080424Z","caller":"traceutil/trace.go:172","msg":"trace[776446107] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:717; }","duration":"824.306667ms","start":"2026-03-18T13:12:20.256112Z","end":"2026-03-18T13:12:21.080418Z","steps":["trace[776446107] 'agreement among raft nodes before linearized reading' (duration: 669.299542ms)","trace[776446107] 'range keys from in-memory index tree' (duration: 154.979232ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:21.080522Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.256105Z","time spent":"824.411564ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:12:21.080556Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"828.668267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-03-18T13:12:21.080570Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"812.812475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T13:12:21.080570Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"562.288196ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:21.080587Z","caller":"traceutil/trace.go:172","msg":"trace[1318055100] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:717; }","duration":"812.826145ms","start":"2026-03-18T13:12:20.267753Z","end":"2026-03-18T13:12:21.080579Z","steps":["trace[1318055100] 'agreement among raft nodes before linearized reading' (duration: 657.522921ms)","trace[1318055100] 'range keys from in-memory index tree' (duration: 155.275062ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:12:21.080591Z","caller":"traceutil/trace.go:172","msg":"trace[251915370] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:717; }","duration":"562.309348ms","start":"2026-03-18T13:12:20.518275Z","end":"2026-03-18T13:12:21.080585Z","steps":["trace[251915370] 'agreement among raft nodes before linearized reading' (duration: 440.899203ms)","trace[251915370] 'range keys from in-memory index tree' (duration: 121.379513ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:12:21.080601Z","caller":"traceutil/trace.go:172","msg":"trace[42863803] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:717; }","duration":"828.692518ms","start":"2026-03-18T13:12:20.251883Z","end":"2026-03-18T13:12:21.080575Z","steps":["trace[42863803] 'agreement among raft nodes before linearized reading' (duration: 673.535834ms)","trace[42863803] 'range keys from in-memory index tree' (duration: 155.011765ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:21.080617Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"792.521318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T13:12:21.080619Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.251872Z","time spent":"828.741822ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 "} {"level":"info","ts":"2026-03-18T13:12:21.080631Z","caller":"traceutil/trace.go:172","msg":"trace[1311644055] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:717; }","duration":"792.532778ms","start":"2026-03-18T13:12:20.288092Z","end":"2026-03-18T13:12:21.080625Z","steps":["trace[1311644055] 'agreement among raft nodes before linearized reading' (duration: 637.16649ms)","trace[1311644055] 'range keys from in-memory index tree' (duration: 155.341157ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:21.080642Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.288074Z","time spent":"792.56462ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:12:21.080603Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.267744Z","time spent":"812.852487ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:12:21.081742Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"122.441402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T13:12:21.081945Z","caller":"traceutil/trace.go:172","msg":"trace[1082821180] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"555.908332ms","start":"2026-03-18T13:12:20.526024Z","end":"2026-03-18T13:12:21.081933Z","steps":["trace[1082821180] 'process raft request' (duration: 433.194812ms)","trace[1082821180] 'compare' (duration: 121.628149ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:21.082015Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.525950Z","time spent":"556.0243ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:12:21.082701Z","caller":"traceutil/trace.go:172","msg":"trace[1112291591] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"345.632326ms","start":"2026-03-18T13:12:20.737055Z","end":"2026-03-18T13:12:21.082687Z","steps":["trace[1112291591] 'process raft request' (duration: 345.599223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:21.082760Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.737030Z","time spent":"345.70251ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:12:21.082866Z","caller":"traceutil/trace.go:172","msg":"trace[1683059745] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"531.568036ms","start":"2026-03-18T13:12:20.551288Z","end":"2026-03-18T13:12:21.082857Z","steps":["trace[1683059745] 'process raft request' (duration: 531.305498ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:21.082904Z","caller":"traceutil/trace.go:172","msg":"trace[554688757] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"549.654336ms","start":"2026-03-18T13:12:20.533227Z","end":"2026-03-18T13:12:21.082881Z","steps":["trace[554688757] 'process raft request' (duration: 549.174595ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:21.082955Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.551261Z","time spent":"531.639691ms","remote":"127.0.0.1:44070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":784,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T13:12:21.083075Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:20.533216Z","time spent":"549.735552ms","remote":"127.0.0.1:44300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20071,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:12:21.113763Z","caller":"traceutil/trace.go:172","msg":"trace[138359639] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"177.260684ms","start":"2026-03-18T13:12:20.936480Z","end":"2026-03-18T13:12:21.113741Z","steps":["trace[138359639] 'process raft request' (duration: 177.212651ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:21.113790Z","caller":"traceutil/trace.go:172","msg":"trace[823912413] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"177.590296ms","start":"2026-03-18T13:12:20.936172Z","end":"2026-03-18T13:12:21.113762Z","steps":["trace[823912413] 'process raft request' (duration: 177.418515ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:21.328752Z","caller":"traceutil/trace.go:172","msg":"trace[1050934813] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"247.602791ms","start":"2026-03-18T13:12:21.081123Z","end":"2026-03-18T13:12:21.328726Z","steps":["trace[1050934813] 'process raft request' (duration: 247.205145ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:21.328737Z","caller":"traceutil/trace.go:172","msg":"trace[403567029] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"241.893537ms","start":"2026-03-18T13:12:21.086793Z","end":"2026-03-18T13:12:21.328687Z","steps":["trace[403567029] 'process raft request' (duration: 241.79911ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:21.529119Z","caller":"traceutil/trace.go:172","msg":"trace[367400128] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"409.822065ms","start":"2026-03-18T13:12:21.119274Z","end":"2026-03-18T13:12:21.529096Z","steps":["trace[367400128] 'process raft request' (duration: 409.165022ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:21.529246Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:21.119253Z","time spent":"409.937613ms","remote":"127.0.0.1:45048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T13:12:21.529688Z","caller":"traceutil/trace.go:172","msg":"trace[251217848] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"225.803292ms","start":"2026-03-18T13:12:21.303873Z","end":"2026-03-18T13:12:21.529676Z","steps":["trace[251217848] 'process raft request' (duration: 225.695135ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:21.529755Z","caller":"traceutil/trace.go:172","msg":"trace[844429357] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"406.967268ms","start":"2026-03-18T13:12:21.122764Z","end":"2026-03-18T13:12:21.529731Z","steps":["trace[844429357] 'process raft request' (duration: 406.156435ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:21.529976Z","caller":"traceutil/trace.go:172","msg":"trace[1968082105] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"407.067315ms","start":"2026-03-18T13:12:21.122883Z","end":"2026-03-18T13:12:21.529950Z","steps":["trace[1968082105] 'process raft request' (duration: 406.568112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:21.530026Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:21.122870Z","time spent":"407.131129ms","remote":"127.0.0.1:45048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T13:12:21.530036Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:21.122747Z","time spent":"407.166272ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:12:21.804035Z","caller":"traceutil/trace.go:172","msg":"trace[578199358] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"263.844866ms","start":"2026-03-18T13:12:21.540164Z","end":"2026-03-18T13:12:21.804009Z","steps":["trace[578199358] 'process raft request' (duration: 263.772761ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:21.804184Z","caller":"traceutil/trace.go:172","msg":"trace[2009704858] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"264.252093ms","start":"2026-03-18T13:12:21.539850Z","end":"2026-03-18T13:12:21.804102Z","steps":["trace[2009704858] 'process raft request' (duration: 172.561032ms)","trace[2009704858] 'compare' (duration: 91.348018ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:12:21.809799Z","caller":"traceutil/trace.go:172","msg":"trace[186487348] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"151.881146ms","start":"2026-03-18T13:12:21.657900Z","end":"2026-03-18T13:12:21.809781Z","steps":["trace[186487348] 'process raft request' (duration: 151.801431ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:25.171019Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:59156","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:25.185858Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:59182","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:25.312105Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:35058","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:25.458487Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"116.98006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-18T13:12:25.458573Z","caller":"traceutil/trace.go:172","msg":"trace[1069677952] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"117.074336ms","start":"2026-03-18T13:12:25.341479Z","end":"2026-03-18T13:12:25.458553Z","steps":["trace[1069677952] 'agreement among raft nodes before linearized reading' (duration: 93.551488ms)","trace[1069677952] 'range keys from in-memory index tree' (duration: 23.302334ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:12:25.458744Z","caller":"traceutil/trace.go:172","msg":"trace[1387975227] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"129.178652ms","start":"2026-03-18T13:12:25.329418Z","end":"2026-03-18T13:12:25.458597Z","steps":["trace[1387975227] 'process raft request' (duration: 105.674995ms)","trace[1387975227] 'compare' (duration: 23.208608ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:12:25.538161Z","caller":"traceutil/trace.go:172","msg":"trace[1154010646] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:825; }","duration":"103.139506ms","start":"2026-03-18T13:12:25.434977Z","end":"2026-03-18T13:12:25.538116Z","steps":["trace[1154010646] 'read index received' (duration: 103.120135ms)","trace[1154010646] 'applied index is now lower than readState.Index' (duration: 17.081µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:25.538368Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"181.138324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:25.538459Z","caller":"traceutil/trace.go:172","msg":"trace[787684860] range","detail":"{range_begin:/registry/statefulsets; range_end:; response_count:0; response_revision:793; }","duration":"181.24476ms","start":"2026-03-18T13:12:25.357195Z","end":"2026-03-18T13:12:25.538440Z","steps":["trace[787684860] 'agreement among raft nodes before linearized reading' (duration: 181.069419ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:25.538695Z","caller":"traceutil/trace.go:172","msg":"trace[18436306] transaction","detail":"{read_only:false; number_of_response:0; response_revision:794; }","duration":"206.937044ms","start":"2026-03-18T13:12:25.331718Z","end":"2026-03-18T13:12:25.538655Z","steps":["trace[18436306] 'process raft request' (duration: 206.739331ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:25.538754Z","caller":"traceutil/trace.go:172","msg":"trace[1152401714] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"203.756986ms","start":"2026-03-18T13:12:25.334965Z","end":"2026-03-18T13:12:25.538722Z","steps":["trace[1152401714] 'process raft request' (duration: 203.614427ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:25.538832Z","caller":"traceutil/trace.go:172","msg":"trace[1882844526] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"204.837443ms","start":"2026-03-18T13:12:25.333981Z","end":"2026-03-18T13:12:25.538818Z","steps":["trace[1882844526] 'process raft request' (duration: 204.518993ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:25.538528Z","caller":"traceutil/trace.go:172","msg":"trace[448438383] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"208.095737ms","start":"2026-03-18T13:12:25.330417Z","end":"2026-03-18T13:12:25.538512Z","steps":["trace[448438383] 'process raft request' (duration: 207.899625ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:25.680042Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"221.640762ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:25.680107Z","caller":"traceutil/trace.go:172","msg":"trace[1418775849] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:796; }","duration":"221.726537ms","start":"2026-03-18T13:12:25.458366Z","end":"2026-03-18T13:12:25.680093Z","steps":["trace[1418775849] 'agreement among raft nodes before linearized reading' (duration: 141.365082ms)","trace[1418775849] 'range keys from in-memory index tree' (duration: 80.247327ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:25.680162Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"199.739074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:25.680239Z","caller":"traceutil/trace.go:172","msg":"trace[1561921863] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities; range_end:; response_count:0; response_revision:796; }","duration":"199.831171ms","start":"2026-03-18T13:12:25.480388Z","end":"2026-03-18T13:12:25.680219Z","steps":["trace[1561921863] 'agreement among raft nodes before linearized reading' (duration: 119.316186ms)","trace[1561921863] 'range keys from in-memory index tree' (duration: 80.385636ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:25.680279Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"199.315298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:25.680352Z","caller":"traceutil/trace.go:172","msg":"trace[585040684] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:796; }","duration":"199.383803ms","start":"2026-03-18T13:12:25.480946Z","end":"2026-03-18T13:12:25.680329Z","steps":["trace[585040684] 'agreement among raft nodes before linearized reading' (duration: 118.773892ms)","trace[585040684] 'range keys from in-memory index tree' (duration: 80.516465ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:25.680452Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"137.892945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/controller\" limit:1 ","response":"range_response_count:1 size:1501"} {"level":"info","ts":"2026-03-18T13:12:25.680474Z","caller":"traceutil/trace.go:172","msg":"trace[192742613] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/controller; range_end:; response_count:1; response_revision:798; }","duration":"137.916356ms","start":"2026-03-18T13:12:25.542553Z","end":"2026-03-18T13:12:25.680469Z","steps":["trace[192742613] 'agreement among raft nodes before linearized reading' (duration: 137.831171ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:25.680619Z","caller":"traceutil/trace.go:172","msg":"trace[1955956729] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"217.695634ms","start":"2026-03-18T13:12:25.462823Z","end":"2026-03-18T13:12:25.680518Z","steps":["trace[1955956729] 'process raft request' (duration: 136.896072ms)","trace[1955956729] 'compare' (duration: 80.288861ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:25.680333Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"199.712873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:25.680979Z","caller":"traceutil/trace.go:172","msg":"trace[401689567] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"217.020873ms","start":"2026-03-18T13:12:25.463911Z","end":"2026-03-18T13:12:25.680932Z","steps":["trace[401689567] 'process raft request' (duration: 216.408195ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:25.681056Z","caller":"traceutil/trace.go:172","msg":"trace[30391450] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:796; }","duration":"200.338082ms","start":"2026-03-18T13:12:25.480539Z","end":"2026-03-18T13:12:25.680877Z","steps":["trace[30391450] 'agreement among raft nodes before linearized reading' (duration: 119.139945ms)","trace[30391450] 'range keys from in-memory index tree' (duration: 80.503473ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:12:26.567189Z","caller":"traceutil/trace.go:172","msg":"trace[413632275] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:831; }","duration":"158.258925ms","start":"2026-03-18T13:12:26.408905Z","end":"2026-03-18T13:12:26.567164Z","steps":["trace[413632275] 'read index received' (duration: 158.246275ms)","trace[413632275] 'applied index is now lower than readState.Index' (duration: 11.27µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:26.567352Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"158.437866ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:26.567383Z","caller":"traceutil/trace.go:172","msg":"trace[912912445] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:798; }","duration":"158.485149ms","start":"2026-03-18T13:12:26.408888Z","end":"2026-03-18T13:12:26.567373Z","steps":["trace[912912445] 'agreement among raft nodes before linearized reading' (duration: 158.410635ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:26.567749Z","caller":"traceutil/trace.go:172","msg":"trace[141831052] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"196.498613ms","start":"2026-03-18T13:12:26.371239Z","end":"2026-03-18T13:12:26.567738Z","steps":["trace[141831052] 'process raft request' (duration: 196.392736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:26.621140Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:35080","server-name":"","error":"EOF"} {"level":"info","ts":"2026-03-18T13:12:26.807321Z","caller":"traceutil/trace.go:172","msg":"trace[1747481121] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:835; }","duration":"189.726196ms","start":"2026-03-18T13:12:26.617563Z","end":"2026-03-18T13:12:26.807289Z","steps":["trace[1747481121] 'read index received' (duration: 189.712765ms)","trace[1747481121] 'applied index is now lower than readState.Index' (duration: 11.511µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:12:26.901671Z","caller":"traceutil/trace.go:172","msg":"trace[880817377] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"289.571902ms","start":"2026-03-18T13:12:26.612075Z","end":"2026-03-18T13:12:26.901647Z","steps":["trace[880817377] 'process raft request' (duration: 289.513049ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:26.901748Z","caller":"traceutil/trace.go:172","msg":"trace[808511162] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"326.945336ms","start":"2026-03-18T13:12:26.574759Z","end":"2026-03-18T13:12:26.901705Z","steps":["trace[808511162] 'process raft request' (duration: 232.557897ms)","trace[808511162] 'compare' (duration: 94.097221ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:12:26.901887Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"272.66445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:26.901946Z","caller":"traceutil/trace.go:172","msg":"trace[209450621] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:803; }","duration":"272.739065ms","start":"2026-03-18T13:12:26.629194Z","end":"2026-03-18T13:12:26.901933Z","steps":["trace[209450621] 'agreement among raft nodes before linearized reading' (duration: 272.632258ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:26.901954Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"272.896454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T13:12:26.901986Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"273.686933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:26.902096Z","caller":"traceutil/trace.go:172","msg":"trace[1013231282] range","detail":"{range_begin:/registry/ingress; range_end:; response_count:0; response_revision:803; }","duration":"273.823762ms","start":"2026-03-18T13:12:26.628248Z","end":"2026-03-18T13:12:26.902072Z","steps":["trace[1013231282] 'agreement among raft nodes before linearized reading' (duration: 273.585437ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:26.902122Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"272.892514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:26.902146Z","caller":"traceutil/trace.go:172","msg":"trace[1142416326] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups; range_end:; response_count:0; response_revision:803; }","duration":"272.916676ms","start":"2026-03-18T13:12:26.629224Z","end":"2026-03-18T13:12:26.902140Z","steps":["trace[1142416326] 'agreement among raft nodes before linearized reading' (duration: 272.877733ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:26.902029Z","caller":"traceutil/trace.go:172","msg":"trace[49778570] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:803; }","duration":"272.999291ms","start":"2026-03-18T13:12:26.629011Z","end":"2026-03-18T13:12:26.902010Z","steps":["trace[49778570] 'agreement among raft nodes before linearized reading' (duration: 272.838071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:26.902767Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"285.185631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/controller\" limit:1 ","response":"range_response_count:1 size:1722"} {"level":"warn","ts":"2026-03-18T13:12:26.902776Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:26.574732Z","time spent":"327.107906ms","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2070,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T13:12:26.902823Z","caller":"traceutil/trace.go:172","msg":"trace[240205277] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/controller; range_end:; response_count:1; response_revision:801; }","duration":"285.245965ms","start":"2026-03-18T13:12:26.617560Z","end":"2026-03-18T13:12:26.902806Z","steps":["trace[240205277] 'agreement among raft nodes before linearized reading' (duration: 189.849344ms)","trace[240205277] 'range keys from in-memory index tree' (duration: 94.187797ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:12:27.056036Z","caller":"traceutil/trace.go:172","msg":"trace[102007696] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"101.54623ms","start":"2026-03-18T13:12:26.954470Z","end":"2026-03-18T13:12:27.056016Z","steps":["trace[102007696] 'process raft request' (duration: 101.423503ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:31.927037Z","caller":"traceutil/trace.go:172","msg":"trace[488352111] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"213.29328ms","start":"2026-03-18T13:12:31.713710Z","end":"2026-03-18T13:12:31.927003Z","steps":["trace[488352111] 'process raft request' (duration: 212.875126ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:33.842169Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:35102","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:33.854581Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:35118","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:33.866299Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:35130","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-18T13:12:35.219270Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855697764259535739,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T13:12:35.720094Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855697764259535739,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T13:12:36.220718Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855697764259535739,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T13:12:36.252468Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.656024447s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T13:12:36.252791Z","caller":"traceutil/trace.go:172","msg":"trace[1873952167] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:893; }","duration":"1.534558734s","start":"2026-03-18T13:12:34.718200Z","end":"2026-03-18T13:12:36.252758Z","steps":["trace[1873952167] 'read index received' (duration: 1.534547524s)","trace[1873952167] 'applied index is now lower than readState.Index' (duration: 9.62µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:12:36.253235Z","caller":"traceutil/trace.go:172","msg":"trace[1020589123] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"1.656867103s","start":"2026-03-18T13:12:34.596350Z","end":"2026-03-18T13:12:36.253217Z","steps":["trace[1020589123] 'process raft request' (duration: 1.656509914s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:36.253441Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:34.596327Z","time spent":"1.657030282s","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:12:36.255493Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.537279864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.117\" limit:1 ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-18T13:12:36.256696Z","caller":"traceutil/trace.go:172","msg":"trace[226082399] range","detail":"{range_begin:/registry/masterleases/199.19.213.117; range_end:; response_count:1; response_revision:859; }","duration":"1.53811597s","start":"2026-03-18T13:12:34.718194Z","end":"2026-03-18T13:12:36.256310Z","steps":["trace[226082399] 'agreement among raft nodes before linearized reading' (duration: 1.534769777s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:36.257409Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:34.718172Z","time spent":"1.53921828s","remote":"127.0.0.1:43974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.117\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:12:36.265044Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.46086014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T13:12:36.265085Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.385363152s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:36.265103Z","caller":"traceutil/trace.go:172","msg":"trace[1161014255] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs; range_end:; response_count:0; response_revision:859; }","duration":"1.460918103s","start":"2026-03-18T13:12:34.804169Z","end":"2026-03-18T13:12:36.265087Z","steps":["trace[1161014255] 'agreement among raft nodes before linearized reading' (duration: 1.460828039s)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:36.265124Z","caller":"traceutil/trace.go:172","msg":"trace[1787350793] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads; range_end:; response_count:0; response_revision:859; }","duration":"1.385403994s","start":"2026-03-18T13:12:34.879711Z","end":"2026-03-18T13:12:36.265115Z","steps":["trace[1787350793] 'agreement among raft nodes before linearized reading' (duration: 1.385342411s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:36.265137Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:34.804144Z","time spent":"1.460984928s","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:12:36.265147Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:34.879689Z","time spent":"1.385449886s","remote":"127.0.0.1:35138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads\" limit:1 "} {"level":"info","ts":"2026-03-18T13:12:36.265196Z","caller":"traceutil/trace.go:172","msg":"trace[77231110] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"1.008124381s","start":"2026-03-18T13:12:35.257057Z","end":"2026-03-18T13:12:36.265181Z","steps":["trace[77231110] 'process raft request' (duration: 1.008089059s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:36.265207Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.400822668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T13:12:36.265291Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"220.83059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:36.265307Z","caller":"traceutil/trace.go:172","msg":"trace[1129647231] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools; range_end:; response_count:0; response_revision:861; }","duration":"1.400933684s","start":"2026-03-18T13:12:34.864352Z","end":"2026-03-18T13:12:36.265286Z","steps":["trace[1129647231] 'agreement among raft nodes before linearized reading' (duration: 1.400755895s)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:36.265349Z","caller":"traceutil/trace.go:172","msg":"trace[1231168692] range","detail":"{range_begin:/registry/serviceaccounts; range_end:; response_count:0; response_revision:862; }","duration":"220.876323ms","start":"2026-03-18T13:12:36.044453Z","end":"2026-03-18T13:12:36.265330Z","steps":["trace[1231168692] 'agreement among raft nodes before linearized reading' (duration: 220.784198ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:36.265372Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:34.864333Z","time spent":"1.401019109s","remote":"127.0.0.1:35120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools\" limit:1 "} {"level":"info","ts":"2026-03-18T13:12:36.265463Z","caller":"traceutil/trace.go:172","msg":"trace[749222102] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"1.666931801s","start":"2026-03-18T13:12:34.598525Z","end":"2026-03-18T13:12:36.265457Z","steps":["trace[749222102] 'process raft request' (duration: 1.666504367s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:36.265472Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.249864921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:36.265503Z","caller":"traceutil/trace.go:172","msg":"trace[498965440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:862; }","duration":"1.249897203s","start":"2026-03-18T13:12:35.015598Z","end":"2026-03-18T13:12:36.265495Z","steps":["trace[498965440] 'agreement among raft nodes before linearized reading' (duration: 1.24984183s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:36.265504Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:34.598499Z","time spent":"1.666986944s","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:12:36.265521Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:35.015490Z","time spent":"1.25002748s","remote":"127.0.0.1:43954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T13:12:36.265622Z","caller":"traceutil/trace.go:172","msg":"trace[1641665532] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"1.272356071s","start":"2026-03-18T13:12:34.993261Z","end":"2026-03-18T13:12:36.265617Z","steps":["trace[1641665532] 'process raft request' (duration: 1.271857164s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:36.265652Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:34.993234Z","time spent":"1.272402355s","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":675,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:12:36.265747Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"320.539032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-18T13:12:36.265780Z","caller":"traceutil/trace.go:172","msg":"trace[2099025997] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:862; }","duration":"320.574504ms","start":"2026-03-18T13:12:35.945196Z","end":"2026-03-18T13:12:36.265771Z","steps":["trace[2099025997] 'agreement among raft nodes before linearized reading' (duration: 320.414475ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:36.265808Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.036905693s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-18T13:12:36.265848Z","caller":"traceutil/trace.go:172","msg":"trace[1793647380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:862; }","duration":"1.036946326s","start":"2026-03-18T13:12:35.228892Z","end":"2026-03-18T13:12:36.265839Z","steps":["trace[1793647380] 'agreement among raft nodes before linearized reading' (duration: 1.036814808s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:36.265852Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.412153502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T13:12:36.265874Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:35.228861Z","time spent":"1.037006768s","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"info","ts":"2026-03-18T13:12:36.265889Z","caller":"traceutil/trace.go:172","msg":"trace[1627005623] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies; range_end:; response_count:0; response_revision:862; }","duration":"1.412192804s","start":"2026-03-18T13:12:34.853687Z","end":"2026-03-18T13:12:36.265880Z","steps":["trace[1627005623] 'agreement among raft nodes before linearized reading' (duration: 1.41212811s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:36.265918Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:34.853644Z","time spent":"1.412266247s","remote":"127.0.0.1:35116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:12:36.265336Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.176451598s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-18T13:12:36.265993Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"483.687973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:36.266020Z","caller":"traceutil/trace.go:172","msg":"trace[2127043718] range","detail":"{range_begin:/registry/volumeattachments; range_end:; response_count:0; response_revision:862; }","duration":"483.713564ms","start":"2026-03-18T13:12:35.782298Z","end":"2026-03-18T13:12:36.266012Z","steps":["trace[2127043718] 'agreement among raft nodes before linearized reading' (duration: 483.673162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:36.266041Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:35.782262Z","time spent":"483.773097ms","remote":"127.0.0.1:44626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":31,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:12:36.266229Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"857.519974ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:12:36.266262Z","caller":"traceutil/trace.go:172","msg":"trace[844962726] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:862; }","duration":"857.550695ms","start":"2026-03-18T13:12:35.408701Z","end":"2026-03-18T13:12:36.266251Z","steps":["trace[844962726] 'agreement among raft nodes before linearized reading' (duration: 857.506243ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:12:36.266395Z","caller":"traceutil/trace.go:172","msg":"trace[70458630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:862; }","duration":"1.177129865s","start":"2026-03-18T13:12:35.088878Z","end":"2026-03-18T13:12:36.266008Z","steps":["trace[70458630] 'agreement among raft nodes before linearized reading' (duration: 1.176398045s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:36.265301Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:35.257028Z","time spent":"1.008220435s","remote":"127.0.0.1:44374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2879,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T13:12:36.265818Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:35.945181Z","time spent":"320.628177ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 "} {"level":"warn","ts":"2026-03-18T13:12:36.265194Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.077930224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T13:12:36.266915Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:35.088826Z","time spent":"1.17759355s","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-03-18T13:12:36.266977Z","caller":"traceutil/trace.go:172","msg":"trace[919305216] range","detail":"{range_begin:/registry/flowschemas; range_end:; response_count:0; response_revision:859; }","duration":"1.079720082s","start":"2026-03-18T13:12:35.187234Z","end":"2026-03-18T13:12:36.266954Z","steps":["trace[919305216] 'agreement among raft nodes before linearized reading' (duration: 1.077796856s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:12:36.267096Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-18T13:12:35.187218Z","time spent":"1.079861199s","remote":"127.0.0.1:44740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":25,"response count":0,"response size":27,"request content":"key:\"/registry/flowschemas\" limit:1 "} {"level":"info","ts":"2026-03-18T13:12:40.026347Z","caller":"traceutil/trace.go:172","msg":"trace[1485194830] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"110.835401ms","start":"2026-03-18T13:12:39.915487Z","end":"2026-03-18T13:12:40.026322Z","steps":["trace[1485194830] 'process raft request' (duration: 107.586521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T13:13:14.662926Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"121.831461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-18T13:13:14.662997Z","caller":"traceutil/trace.go:172","msg":"trace[236274935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1041; }","duration":"121.908354ms","start":"2026-03-18T13:13:14.541074Z","end":"2026-03-18T13:13:14.662982Z","steps":["trace[236274935] 'agreement among raft nodes before linearized reading' (duration: 22.045612ms)","trace[236274935] 'range keys from in-memory index tree' (duration: 99.678436ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:13:14.663663Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"100.158753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T13:13:14.663898Z","caller":"traceutil/trace.go:172","msg":"trace[861488337] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"160.01313ms","start":"2026-03-18T13:13:14.503736Z","end":"2026-03-18T13:13:14.663750Z","steps":["trace[861488337] 'process raft request' (duration: 59.422371ms)","trace[861488337] 'compare' (duration: 99.814111ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:13:14.769874Z","caller":"traceutil/trace.go:172","msg":"trace[348505126] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"102.955765ms","start":"2026-03-18T13:13:14.666896Z","end":"2026-03-18T13:13:14.769852Z","steps":["trace[348505126] 'process raft request' (duration: 102.55683ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:13:16.949846Z","caller":"traceutil/trace.go:172","msg":"trace[878093713] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1093; }","duration":"201.874383ms","start":"2026-03-18T13:13:16.747940Z","end":"2026-03-18T13:13:16.949814Z","steps":["trace[878093713] 'read index received' (duration: 201.866243ms)","trace[878093713] 'applied index is now lower than readState.Index' (duration: 6.81µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:13:16.982852Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"234.89308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:13:16.982922Z","caller":"traceutil/trace.go:172","msg":"trace[676353135] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies; range_end:; response_count:0; response_revision:1049; }","duration":"234.982173ms","start":"2026-03-18T13:13:16.747922Z","end":"2026-03-18T13:13:16.982904Z","steps":["trace[676353135] 'agreement among raft nodes before linearized reading' (duration: 202.013407ms)","trace[676353135] 'range keys from in-memory index tree' (duration: 32.846501ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:13:16.982937Z","caller":"traceutil/trace.go:172","msg":"trace[785501267] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"248.521786ms","start":"2026-03-18T13:13:16.734403Z","end":"2026-03-18T13:13:16.982925Z","steps":["trace[785501267] 'process raft request' (duration: 215.453906ms)","trace[785501267] 'compare' (duration: 32.990497ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:13:16.983205Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"208.746357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-18T13:13:16.984161Z","caller":"traceutil/trace.go:172","msg":"trace[1542397474] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1050; }","duration":"209.712062ms","start":"2026-03-18T13:13:16.774437Z","end":"2026-03-18T13:13:16.984149Z","steps":["trace[1542397474] 'agreement among raft nodes before linearized reading' (duration: 208.657824ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:13:18.977492Z","caller":"traceutil/trace.go:172","msg":"trace[75849333] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"276.630824ms","start":"2026-03-18T13:13:18.700844Z","end":"2026-03-18T13:13:18.977475Z","steps":["trace[75849333] 'process raft request' (duration: 276.52294ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:13:19.121141Z","caller":"traceutil/trace.go:172","msg":"trace[1349029290] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"129.144893ms","start":"2026-03-18T13:13:18.991972Z","end":"2026-03-18T13:13:19.121117Z","steps":["trace[1349029290] 'process raft request' (duration: 51.012264ms)","trace[1349029290] 'compare' (duration: 77.633672ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:13:41.197163Z","caller":"traceutil/trace.go:172","msg":"trace[49854163] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"109.933966ms","start":"2026-03-18T13:13:41.087208Z","end":"2026-03-18T13:13:41.197142Z","steps":["trace[49854163] 'process raft request' (duration: 109.805323ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:13:41.236911Z","caller":"traceutil/trace.go:172","msg":"trace[152560580] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"125.051338ms","start":"2026-03-18T13:13:41.111845Z","end":"2026-03-18T13:13:41.236896Z","steps":["trace[152560580] 'process raft request' (duration: 124.912484ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:13:41.417791Z","caller":"traceutil/trace.go:172","msg":"trace[1514113019] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1178; }","duration":"145.490964ms","start":"2026-03-18T13:13:41.272275Z","end":"2026-03-18T13:13:41.417766Z","steps":["trace[1514113019] 'read index received' (duration: 145.482893ms)","trace[1514113019] 'applied index is now lower than readState.Index' (duration: 6.811µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:13:41.474586Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"202.31715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-18T13:13:41.475577Z","caller":"traceutil/trace.go:172","msg":"trace[69484853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1129; }","duration":"203.318638ms","start":"2026-03-18T13:13:41.272237Z","end":"2026-03-18T13:13:41.475556Z","steps":["trace[69484853] 'agreement among raft nodes before linearized reading' (duration: 145.841604ms)","trace[69484853] 'range keys from in-memory index tree' (duration: 56.340993ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:13:41.475373Z","caller":"traceutil/trace.go:172","msg":"trace[518328811] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"234.595324ms","start":"2026-03-18T13:13:41.240758Z","end":"2026-03-18T13:13:41.475353Z","steps":["trace[518328811] 'process raft request' (duration: 177.241472ms)","trace[518328811] 'compare' (duration: 56.803836ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T13:13:44.849359Z","caller":"traceutil/trace.go:172","msg":"trace[324093706] linearizableReadLoop","detail":"{readStateIndex:1188; appliedIndex:1188; }","duration":"175.279999ms","start":"2026-03-18T13:13:44.674058Z","end":"2026-03-18T13:13:44.849338Z","steps":["trace[324093706] 'read index received' (duration: 175.275468ms)","trace[324093706] 'applied index is now lower than readState.Index' (duration: 3.811µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:13:44.903887Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"229.807472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T13:13:44.903981Z","caller":"traceutil/trace.go:172","msg":"trace[1015555096] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs; range_end:; response_count:0; response_revision:1139; }","duration":"229.912575ms","start":"2026-03-18T13:13:44.674048Z","end":"2026-03-18T13:13:44.903961Z","steps":["trace[1015555096] 'agreement among raft nodes before linearized reading' (duration: 175.411652ms)","trace[1015555096] 'range keys from in-memory index tree' (duration: 54.370589ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:13:44.903985Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"128.221652ms","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-03-18T13:13:44.904066Z","caller":"traceutil/trace.go:172","msg":"trace[605867826] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1139; }","duration":"128.324275ms","start":"2026-03-18T13:13:44.775728Z","end":"2026-03-18T13:13:44.904053Z","steps":["trace[605867826] 'range keys from in-memory index tree' (duration: 128.143761ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:13:44.904473Z","caller":"traceutil/trace.go:172","msg":"trace[203441778] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"270.90819ms","start":"2026-03-18T13:13:44.633544Z","end":"2026-03-18T13:13:44.904452Z","steps":["trace[203441778] 'process raft request' (duration: 215.78319ms)","trace[203441778] 'compare' (duration: 54.866483ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T13:13:44.904538Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"182.646893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.117\" limit:1 ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-18T13:13:44.904588Z","caller":"traceutil/trace.go:172","msg":"trace[1429837282] range","detail":"{range_begin:/registry/masterleases/199.19.213.117; range_end:; response_count:1; response_revision:1140; }","duration":"182.697975ms","start":"2026-03-18T13:13:44.721876Z","end":"2026-03-18T13:13:44.904574Z","steps":["trace[1429837282] 'agreement among raft nodes before linearized reading' (duration: 182.546581ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:13:45.335882Z","caller":"traceutil/trace.go:172","msg":"trace[1416835052] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"118.131364ms","start":"2026-03-18T13:13:45.217722Z","end":"2026-03-18T13:13:45.335853Z","steps":["trace[1416835052] 'process raft request' (duration: 117.946819ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T13:13:45.364138Z","caller":"traceutil/trace.go:172","msg":"trace[846351656] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"111.555282ms","start":"2026-03-18T13:13:45.252564Z","end":"2026-03-18T13:13:45.364119Z","steps":["trace[846351656] 'process raft request' (duration: 111.439839ms)"],"step_count":1}