{"level":"warn","ts":"2026-04-17T00:53:52.569154Z","caller":"embed/config.go:1209","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"warn","ts":"2026-04-17T00:53:52.569785Z","caller":"etcdmain/config.go:270","msg":"--snapshot-count is deprecated in 3.6 and will be decommissioned in 3.7."} {"level":"info","ts":"2026-04-17T00:53:52.569819Z","caller":"etcdmain/etcd.go:64","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.204.45.89:2380","--initial-cluster=controller=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-04-17T00:53:52.569936Z","caller":"etcdmain/etcd.go:124","msg":"Initialize and start etcd server","data-dir":"/var/lib/etcd","dir-type":"empty"} {"level":"warn","ts":"2026-04-17T00:53:52.569976Z","caller":"embed/config.go:1209","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T00:53:52.569987Z","caller":"embed/etcd.go:138","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-17T00:53:52.570018Z","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-04-17T00:53:52.570551Z","caller":"embed/etcd.go:146","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-04-17T00:53:52.570653Z","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.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"experimental-local-address":"","cors":["*"],"host-whitelist":["*"],"initial-cluster":"controller=https://199.204.45.89: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-04-17T00:53:52.571353Z","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: 0xc00040e160}"} {"level":"info","ts":"2026-04-17T00:53:52.577854Z","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-04-17T00:53:52.577912Z","caller":"storage/backend.go:80","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.676619ms"} {"level":"info","ts":"2026-04-17T00:53:52.577941Z","caller":"etcdserver/bootstrap.go:220","msg":"restore consistentIndex","index":0} {"level":"info","ts":"2026-04-17T00:53:52.577964Z","caller":"etcdserver/bootstrap.go:94","msg":"bootstrapping cluster"} {"level":"info","ts":"2026-04-17T00:53:52.578006Z","caller":"etcdserver/bootstrap.go:101","msg":"bootstrapping storage"} {"level":"info","ts":"2026-04-17T00:53:52.585084Z","caller":"etcdserver/bootstrap.go:109","msg":"bootstrapping raft"} {"level":"info","ts":"2026-04-17T00:53:52.585175Z","caller":"etcdserver/bootstrap.go:499","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-04-17T00:53:52.585210Z","caller":"etcdserver/server.go:312","msg":"bootstrap successfully"} {"level":"info","ts":"2026-04-17T00:53:52.585300Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T00:53:52.585349Z","logger":"raft","caller":"v3@v3.6.0/raft.go:897","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-04-17T00:53:52.585360Z","logger":"raft","caller":"v3@v3.6.0/raft.go:493","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T00:53:52.585372Z","logger":"raft","caller":"v3@v3.6.0/raft.go:897","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-04-17T00:53:52.585423Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-04-17T00:53:52.601487Z","caller":"auth/store.go:1135","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T00:53:52.604980Z","caller":"mvcc/kvstore.go:408","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T00:53:52.607938Z","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-04-17T00:53:52.608370Z","caller":"etcdserver/server.go:598","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.6.4","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T00:53:52.608563Z","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-04-17T00:53:52.608600Z","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-04-17T00:53:52.608561Z","caller":"etcdserver/server.go:483","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T00:53:52.608607Z","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-04-17T00:53:52.608775Z","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-04-17T00:53:52.608878Z","caller":"embed/etcd.go:640","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-17T00:53:52.608915Z","caller":"embed/etcd.go:611","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-17T00:53:52.608975Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-04-17T00:53:52.609123Z","caller":"membership/cluster.go:424","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"],"added-peer-is-learner":false} {"level":"info","ts":"2026-04-17T00:53:52.609265Z","caller":"embed/etcd.go:292","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T00:53:52.609304Z","caller":"embed/etcd.go:890","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T00:53:53.286526Z","logger":"raft","caller":"v3@v3.6.0/raft.go:988","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T00:53:53.286589Z","logger":"raft","caller":"v3@v3.6.0/raft.go:930","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T00:53:53.286652Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1077","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-04-17T00:53:53.286671Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1693","msg":"c3b8446219deb8c1 has received 1 MsgPreVoteResp votes and 0 vote rejections"} {"level":"info","ts":"2026-04-17T00:53:53.286712Z","logger":"raft","caller":"v3@v3.6.0/raft.go:912","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-04-17T00:53:53.288922Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1077","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-17T00:53:53.288938Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1693","msg":"c3b8446219deb8c1 has received 1 MsgVoteResp votes and 0 vote rejections"} {"level":"info","ts":"2026-04-17T00:53:53.288956Z","logger":"raft","caller":"v3@v3.6.0/raft.go:970","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-04-17T00:53:53.288974Z","logger":"raft","caller":"v3@v3.6.0/node.go:370","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-17T00:53:53.289957Z","caller":"etcdserver/server.go:2404","msg":"setting up initial cluster version using v3 API","cluster-version":"3.6"} {"level":"info","ts":"2026-04-17T00:53:53.291943Z","caller":"etcdserver/server.go:1804","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:controller ClientURLs:[https://199.204.45.89:2379]}","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T00:53:53.292024Z","caller":"embed/serve.go:138","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T00:53:53.292409Z","caller":"embed/serve.go:138","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T00:53:53.292749Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T00:53:53.292820Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T00:53:53.294459Z","caller":"v3rpc/health.go:63","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"warn","ts":"2026-04-17T00:53:53.294489Z","caller":"v3rpc/grpc.go:52","msg":"etcdserver: failed to register grpc metrics","error":"duplicate metrics collector registration attempted"} {"level":"info","ts":"2026-04-17T00:53:53.294683Z","caller":"v3rpc/health.go:63","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-04-17T00:53:53.295840Z","caller":"membership/cluster.go:682","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.6"} {"level":"info","ts":"2026-04-17T00:53:53.295961Z","caller":"api/capability.go:76","msg":"enabled capabilities for version","cluster-version":"3.6"} {"level":"info","ts":"2026-04-17T00:53:53.295996Z","caller":"etcdserver/server.go:2424","msg":"cluster version is updated","cluster-version":"3.6"} {"level":"info","ts":"2026-04-17T00:53:53.296080Z","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-04-17T00:53:53.296117Z","caller":"schema/migration.go:65","msg":"updated storage version","new-storage-version":"3.6.0"} {"level":"info","ts":"2026-04-17T00:53:53.298008Z","caller":"embed/serve.go:283","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-17T00:53:53.298408Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47276","server-name":"","error":"EOF"} {"level":"info","ts":"2026-04-17T00:53:53.298659Z","caller":"embed/serve.go:283","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"warn","ts":"2026-04-17T00:53:53.302432Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47294","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.302614Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47310","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.337958Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47328","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.349092Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47382","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.369399Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47384","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.377748Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47402","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.385113Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47408","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.393043Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47430","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.399311Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47436","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.410070Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47462","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.418002Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47478","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.427248Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47490","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.442417Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47516","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.453800Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47520","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.460482Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47540","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.466299Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47558","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.473844Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47560","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.490208Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47602","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.497098Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47606","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.507909Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47622","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.535877Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47644","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.547243Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47660","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.562997Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47682","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.571275Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47702","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.580047Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47704","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.588451Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47718","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.597981Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47736","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.606193Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47752","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.617051Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47778","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.623996Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47810","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.633942Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47816","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.644158Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47826","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.652200Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47844","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.662300Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47866","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.677603Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47872","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.698587Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47886","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.706877Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47906","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.717803Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47922","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.728847Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47950","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.738741Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47972","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.742324Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47996","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.749249Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48012","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.756266Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48022","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.764789Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48044","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.773447Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48062","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.802520Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48080","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.812542Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48090","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.824397Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48128","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.833618Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48154","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.841363Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48168","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.849558Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48178","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.859472Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48200","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.868886Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48230","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.878763Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48246","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.886648Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48268","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.896217Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48290","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.926667Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48326","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.932806Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48328","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.942273Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48344","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:53.952021Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48368","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:53:54.007671Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48416","server-name":"","error":"EOF"} {"level":"info","ts":"2026-04-17T00:53:56.240474Z","caller":"traceutil/trace.go:172","msg":"trace[1288926697] transaction","detail":"{read_only:false; response_revision:188; number_of_response:1; }","duration":"110.35225ms","start":"2026-04-17T00:53:56.130102Z","end":"2026-04-17T00:53:56.240455Z","steps":["trace[1288926697] 'process raft request' (duration: 102.930396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:53:58.848189Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"205.901922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" limit:1 ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-17T00:53:58.848275Z","caller":"traceutil/trace.go:172","msg":"trace[835377127] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:265; }","duration":"206.003403ms","start":"2026-04-17T00:53:58.642258Z","end":"2026-04-17T00:53:58.848261Z","steps":["trace[835377127] 'agreement among raft nodes before linearized reading' (duration: 105.747374ms)","trace[835377127] 'range keys from in-memory index tree' (duration: 100.043777ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:53:58.848560Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"100.089748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:53:58.848639Z","caller":"traceutil/trace.go:172","msg":"trace[1713045261] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"206.015044ms","start":"2026-04-17T00:53:58.642612Z","end":"2026-04-17T00:53:58.848627Z","steps":["trace[1713045261] 'process raft request' (duration: 105.434689ms)","trace[1713045261] 'compare' (duration: 99.880535ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:53:58.851846Z","caller":"traceutil/trace.go:172","msg":"trace[1871473520] linearizableReadLoop","detail":"{readStateIndex:279; appliedIndex:279; }","duration":"103.866748ms","start":"2026-04-17T00:53:58.747968Z","end":"2026-04-17T00:53:58.851835Z","steps":["trace[1871473520] 'read index received' (duration: 103.862298ms)","trace[1871473520] 'applied index is now lower than readState.Index' (duration: 3.8µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:53:58.851998Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"131.409886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-controller\" limit:1 ","response":"range_response_count:1 size:5690"} {"level":"warn","ts":"2026-04-17T00:53:58.852048Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"131.507348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-controller\" limit:1 ","response":"range_response_count:1 size:6077"} {"level":"info","ts":"2026-04-17T00:53:58.852035Z","caller":"traceutil/trace.go:172","msg":"trace[188296658] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-controller; range_end:; response_count:1; response_revision:266; }","duration":"131.448807ms","start":"2026-04-17T00:53:58.720577Z","end":"2026-04-17T00:53:58.852025Z","steps":["trace[188296658] 'agreement among raft nodes before linearized reading' (duration: 131.325415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:53:58.852063Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"131.418647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-controller\" limit:1 ","response":"range_response_count:1 size:4336"} {"level":"info","ts":"2026-04-17T00:53:58.852132Z","caller":"traceutil/trace.go:172","msg":"trace[1978641704] range","detail":"{range_begin:/registry/pods/kube-system/etcd-controller; range_end:; response_count:1; response_revision:266; }","duration":"131.503118ms","start":"2026-04-17T00:53:58.720614Z","end":"2026-04-17T00:53:58.852117Z","steps":["trace[1978641704] 'agreement among raft nodes before linearized reading' (duration: 131.277195ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:53:58.852069Z","caller":"traceutil/trace.go:172","msg":"trace[998143695] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-controller; range_end:; response_count:1; response_revision:266; }","duration":"131.524458ms","start":"2026-04-17T00:53:58.720537Z","end":"2026-04-17T00:53:58.852061Z","steps":["trace[998143695] 'agreement among raft nodes before linearized reading' (duration: 131.483268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:53:58.851992Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"131.383166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-controller\" limit:1 ","response":"range_response_count:1 size:3323"} {"level":"info","ts":"2026-04-17T00:53:58.852246Z","caller":"traceutil/trace.go:172","msg":"trace[748940070] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-controller; range_end:; response_count:1; response_revision:266; }","duration":"131.64559ms","start":"2026-04-17T00:53:58.720596Z","end":"2026-04-17T00:53:58.852242Z","steps":["trace[748940070] 'agreement among raft nodes before linearized reading' (duration: 131.317415ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:53:58.852381Z","caller":"traceutil/trace.go:172","msg":"trace[1578584658] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"120.084294ms","start":"2026-04-17T00:53:58.732290Z","end":"2026-04-17T00:53:58.852374Z","steps":["trace[1578584658] 'process raft request' (duration: 119.911832ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:53:58.971168Z","caller":"traceutil/trace.go:172","msg":"trace[870685929] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"107.788971ms","start":"2026-04-17T00:53:58.863355Z","end":"2026-04-17T00:53:58.971144Z","steps":["trace[870685929] 'process raft request' (duration: 101.847892ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:00.488962Z","caller":"traceutil/trace.go:172","msg":"trace[1593579227] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"185.010516ms","start":"2026-04-17T00:54:00.303930Z","end":"2026-04-17T00:54:00.488940Z","steps":["trace[1593579227] 'process raft request' (duration: 184.820474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:00.685548Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"118.57548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-17T00:54:00.685592Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"132.6138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" limit:1 ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-17T00:54:00.685631Z","caller":"traceutil/trace.go:172","msg":"trace[879283388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:291; }","duration":"118.675881ms","start":"2026-04-17T00:54:00.566937Z","end":"2026-04-17T00:54:00.685613Z","steps":["trace[879283388] 'range keys from in-memory index tree' (duration: 118.417648ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:00.685673Z","caller":"traceutil/trace.go:172","msg":"trace[1486949941] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:291; }","duration":"132.711813ms","start":"2026-04-17T00:54:00.552942Z","end":"2026-04-17T00:54:00.685654Z","steps":["trace[1486949941] 'range keys from in-memory index tree' (duration: 132.411038ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:02.019967Z","caller":"traceutil/trace.go:172","msg":"trace[1696756340] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"116.447154ms","start":"2026-04-17T00:54:01.903494Z","end":"2026-04-17T00:54:02.019941Z","steps":["trace[1696756340] 'process raft request' (duration: 90.61654ms)","trace[1696756340] 'compare' (duration: 25.692822ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:54:04.395612Z","caller":"traceutil/trace.go:172","msg":"trace[764376079] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:338; }","duration":"292.474565ms","start":"2026-04-17T00:54:04.103105Z","end":"2026-04-17T00:54:04.395580Z","steps":["trace[764376079] 'read index received' (duration: 292.463595ms)","trace[764376079] 'applied index is now lower than readState.Index' (duration: 9.3µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:04.396000Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"242.827298ms","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-04-17T00:54:04.396092Z","caller":"traceutil/trace.go:172","msg":"trace[1180288760] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:326; }","duration":"242.933079ms","start":"2026-04-17T00:54:04.153141Z","end":"2026-04-17T00:54:04.396074Z","steps":["trace[1180288760] 'agreement among raft nodes before linearized reading' (duration: 242.728317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:04.396358Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"293.233915ms","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":"warn","ts":"2026-04-17T00:54:04.396362Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"142.74091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" limit:1 ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-17T00:54:04.396401Z","caller":"traceutil/trace.go:172","msg":"trace[1500538429] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:325; }","duration":"293.293296ms","start":"2026-04-17T00:54:04.103098Z","end":"2026-04-17T00:54:04.396391Z","steps":["trace[1500538429] 'agreement among raft nodes before linearized reading' (duration: 292.674378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:04.396403Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"192.816194ms","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-04-17T00:54:04.396416Z","caller":"traceutil/trace.go:172","msg":"trace[1224858525] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:326; }","duration":"142.790851ms","start":"2026-04-17T00:54:04.253612Z","end":"2026-04-17T00:54:04.396403Z","steps":["trace[1224858525] 'agreement among raft nodes before linearized reading' (duration: 142.6935ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:04.396431Z","caller":"traceutil/trace.go:172","msg":"trace[458966676] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:326; }","duration":"192.848525ms","start":"2026-04-17T00:54:04.203573Z","end":"2026-04-17T00:54:04.396421Z","steps":["trace[458966676] 'agreement among raft nodes before linearized reading' (duration: 192.760004ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:04.396428Z","caller":"traceutil/trace.go:172","msg":"trace[1093878799] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"337.960694ms","start":"2026-04-17T00:54:04.058427Z","end":"2026-04-17T00:54:04.396388Z","steps":["trace[1093878799] 'process raft request' (duration: 337.228664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:04.396533Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:04.058410Z","time spent":"338.069075ms","remote":"127.0.0.1:48176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3924,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:54:04.587441Z","caller":"traceutil/trace.go:172","msg":"trace[731884411] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:339; }","duration":"184.760977ms","start":"2026-04-17T00:54:04.402649Z","end":"2026-04-17T00:54:04.587410Z","steps":["trace[731884411] 'read index received' (duration: 184.750046ms)","trace[731884411] 'applied index is now lower than readState.Index' (duration: 9.301µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:04.654584Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"251.914166ms","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-04-17T00:54:04.654668Z","caller":"traceutil/trace.go:172","msg":"trace[1367984342] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:326; }","duration":"252.002277ms","start":"2026-04-17T00:54:04.402645Z","end":"2026-04-17T00:54:04.654647Z","steps":["trace[1367984342] 'agreement among raft nodes before linearized reading' (duration: 184.884068ms)","trace[1367984342] 'range keys from in-memory index tree' (duration: 66.912147ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:54:04.654986Z","caller":"traceutil/trace.go:172","msg":"trace[1127367871] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"252.701296ms","start":"2026-04-17T00:54:04.402269Z","end":"2026-04-17T00:54:04.654970Z","steps":["trace[1127367871] 'process raft request' (duration: 185.388244ms)","trace[1127367871] 'compare' (duration: 67.050778ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:04.656345Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"103.471937ms","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-04-17T00:54:04.656427Z","caller":"traceutil/trace.go:172","msg":"trace[1885388486] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:327; }","duration":"103.564538ms","start":"2026-04-17T00:54:04.552847Z","end":"2026-04-17T00:54:04.656411Z","steps":["trace[1885388486] 'agreement among raft nodes before linearized reading' (duration: 103.354975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:04.656475Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"202.832095ms","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-04-17T00:54:04.656520Z","caller":"traceutil/trace.go:172","msg":"trace[378626563] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-cidrs-controller; range_end:; response_count:1; response_revision:330; }","duration":"202.879975ms","start":"2026-04-17T00:54:04.453629Z","end":"2026-04-17T00:54:04.656509Z","steps":["trace[378626563] 'agreement among raft nodes before linearized reading' (duration: 202.748534ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:04.656569Z","caller":"traceutil/trace.go:172","msg":"trace[723656507] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"254.099802ms","start":"2026-04-17T00:54:04.402442Z","end":"2026-04-17T00:54:04.656542Z","steps":["trace[723656507] 'process raft request' (duration: 253.750808ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:04.656794Z","caller":"traceutil/trace.go:172","msg":"trace[1694654535] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"254.038922ms","start":"2026-04-17T00:54:04.402744Z","end":"2026-04-17T00:54:04.656783Z","steps":["trace[1694654535] 'process raft request' (duration: 253.551927ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:04.656980Z","caller":"traceutil/trace.go:172","msg":"trace[663880389] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"252.229039ms","start":"2026-04-17T00:54:04.404740Z","end":"2026-04-17T00:54:04.656969Z","steps":["trace[663880389] 'process raft request' (duration: 251.621092ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:04.656991Z","caller":"traceutil/trace.go:172","msg":"trace[843158270] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"253.273102ms","start":"2026-04-17T00:54:04.403708Z","end":"2026-04-17T00:54:04.656981Z","steps":["trace[843158270] 'process raft request' (duration: 252.622965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:04.657313Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"154.618433ms","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-04-17T00:54:04.657349Z","caller":"traceutil/trace.go:172","msg":"trace[1121834596] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/legacy-service-account-token-cleaner; range_end:; response_count:1; response_revision:332; }","duration":"154.654573ms","start":"2026-04-17T00:54:04.502683Z","end":"2026-04-17T00:54:04.657338Z","steps":["trace[1121834596] 'agreement among raft nodes before linearized reading' (duration: 153.953556ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:04.657522Z","caller":"traceutil/trace.go:172","msg":"trace[1852992735] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"251.546981ms","start":"2026-04-17T00:54:04.405964Z","end":"2026-04-17T00:54:04.657511Z","steps":["trace[1852992735] 'process raft request' (duration: 251.334409ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:04.657524Z","caller":"traceutil/trace.go:172","msg":"trace[120309554] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"252.619374ms","start":"2026-04-17T00:54:04.404891Z","end":"2026-04-17T00:54:04.657510Z","steps":["trace[120309554] 'process raft request' (duration: 251.810645ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:04.657619Z","caller":"traceutil/trace.go:172","msg":"trace[613207990] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"251.39608ms","start":"2026-04-17T00:54:04.406211Z","end":"2026-04-17T00:54:04.657607Z","steps":["trace[613207990] 'process raft request' (duration: 251.116636ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:04.657708Z","caller":"traceutil/trace.go:172","msg":"trace[1961515137] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"252.590945ms","start":"2026-04-17T00:54:04.405107Z","end":"2026-04-17T00:54:04.657698Z","steps":["trace[1961515137] 'process raft request' (duration: 252.087678ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:04.657630Z","caller":"traceutil/trace.go:172","msg":"trace[188272836] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"251.779095ms","start":"2026-04-17T00:54:04.405842Z","end":"2026-04-17T00:54:04.657621Z","steps":["trace[188272836] 'process raft request' (duration: 251.401019ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:04.657863Z","caller":"traceutil/trace.go:172","msg":"trace[1385127029] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"156.807729ms","start":"2026-04-17T00:54:04.501045Z","end":"2026-04-17T00:54:04.657853Z","steps":["trace[1385127029] 'process raft request' (duration: 156.312213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:06.719460Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:06.379862Z","time spent":"339.594162ms","remote":"127.0.0.1:47342","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-17T00:54:07.138248Z","caller":"traceutil/trace.go:172","msg":"trace[255807403] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:388; }","duration":"362.424185ms","start":"2026-04-17T00:54:06.775790Z","end":"2026-04-17T00:54:07.138215Z","steps":["trace[255807403] 'read index received' (duration: 362.411244ms)","trace[255807403] 'applied index is now lower than readState.Index' (duration: 10.501µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:07.639157Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095253638728820,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:54:07.703285Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"564.780937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-04-17T00:54:07.703322Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"927.505395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T00:54:07.703407Z","caller":"traceutil/trace.go:172","msg":"trace[1229458178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:373; }","duration":"927.607726ms","start":"2026-04-17T00:54:06.775775Z","end":"2026-04-17T00:54:07.703382Z","steps":["trace[1229458178] 'agreement among raft nodes before linearized reading' (duration: 362.641757ms)","trace[1229458178] 'range keys from in-memory index tree' (duration: 564.722887ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:54:07.703372Z","caller":"traceutil/trace.go:172","msg":"trace[2121343026] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"982.730909ms","start":"2026-04-17T00:54:06.720628Z","end":"2026-04-17T00:54:07.703359Z","steps":["trace[2121343026] 'process raft request' (duration: 417.8175ms)","trace[2121343026] 'compare' (duration: 564.690236ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:07.703460Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:06.775752Z","time spent":"927.697647ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:54:07.703501Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:06.720602Z","time spent":"982.87734ms","remote":"127.0.0.1:47342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:54:07.820054Z","caller":"traceutil/trace.go:172","msg":"trace[412829211] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:389; }","duration":"681.70394ms","start":"2026-04-17T00:54:07.138325Z","end":"2026-04-17T00:54:07.820029Z","steps":["trace[412829211] 'read index received' (duration: 681.694699ms)","trace[412829211] 'applied index is now lower than readState.Index' (duration: 7.52µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:07.820575Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"940.641796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-04-17T00:54:07.820592Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"774.597317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:07.820633Z","caller":"traceutil/trace.go:172","msg":"trace[985589009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:374; }","duration":"940.713717ms","start":"2026-04-17T00:54:06.879903Z","end":"2026-04-17T00:54:07.820617Z","steps":["trace[985589009] 'agreement among raft nodes before linearized reading' (duration: 940.281131ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:07.820742Z","caller":"traceutil/trace.go:172","msg":"trace[1082443618] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"753.330683ms","start":"2026-04-17T00:54:07.067352Z","end":"2026-04-17T00:54:07.820683Z","steps":["trace[1082443618] 'process raft request' (duration: 752.849036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:07.820975Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:06.879892Z","time spent":"941.038041ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-04-17T00:54:07.820888Z","caller":"traceutil/trace.go:172","msg":"trace[1126818475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:374; }","duration":"774.86004ms","start":"2026-04-17T00:54:07.045953Z","end":"2026-04-17T00:54:07.820813Z","steps":["trace[1126818475] 'agreement among raft nodes before linearized reading' (duration: 774.204882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:07.821085Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:07.067320Z","time spent":"753.684516ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:54:07.821193Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:07.045936Z","time spent":"775.176413ms","remote":"127.0.0.1:47280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:54:07.824637Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"215.655047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-controller\" limit:1 ","response":"range_response_count:1 size:4975"} {"level":"info","ts":"2026-04-17T00:54:07.824684Z","caller":"traceutil/trace.go:172","msg":"trace[1059261926] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-controller; range_end:; response_count:1; response_revision:375; }","duration":"215.706907ms","start":"2026-04-17T00:54:07.608966Z","end":"2026-04-17T00:54:07.824673Z","steps":["trace[1059261926] 'agreement among raft nodes before linearized reading' (duration: 215.570026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:07.824966Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"119.803896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" limit:1 ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-17T00:54:07.824986Z","caller":"traceutil/trace.go:172","msg":"trace[1299340193] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"117.448999ms","start":"2026-04-17T00:54:07.707527Z","end":"2026-04-17T00:54:07.824976Z","steps":["trace[1299340193] 'process raft request' (duration: 117.330467ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:07.825008Z","caller":"traceutil/trace.go:172","msg":"trace[811242460] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:375; }","duration":"119.844857ms","start":"2026-04-17T00:54:07.705150Z","end":"2026-04-17T00:54:07.824995Z","steps":["trace[811242460] 'agreement among raft nodes before linearized reading' (duration: 119.736856ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:09.987355Z","caller":"traceutil/trace.go:172","msg":"trace[696855609] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:399; }","duration":"158.152661ms","start":"2026-04-17T00:54:09.829182Z","end":"2026-04-17T00:54:09.987335Z","steps":["trace[696855609] 'read index received' (duration: 158.145141ms)","trace[696855609] 'applied index is now lower than readState.Index' (duration: 6.67µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:10.037424Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"208.217313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-17T00:54:10.037522Z","caller":"traceutil/trace.go:172","msg":"trace[1007902860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:384; }","duration":"208.321724ms","start":"2026-04-17T00:54:09.829179Z","end":"2026-04-17T00:54:10.037501Z","steps":["trace[1007902860] 'agreement among raft nodes before linearized reading' (duration: 158.240762ms)","trace[1007902860] 'range keys from in-memory index tree' (duration: 49.875261ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:54:10.037555Z","caller":"traceutil/trace.go:172","msg":"trace[885385660] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"411.854361ms","start":"2026-04-17T00:54:09.625663Z","end":"2026-04-17T00:54:10.037517Z","steps":["trace[885385660] 'process raft request' (duration: 361.791348ms)","trace[885385660] 'compare' (duration: 49.495397ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:10.037702Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:09.625646Z","time spent":"411.985532ms","remote":"127.0.0.1:47574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5757,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:54:10.066609Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"236.686875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T00:54:10.066653Z","caller":"traceutil/trace.go:172","msg":"trace[424397881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:385; }","duration":"236.737315ms","start":"2026-04-17T00:54:09.829907Z","end":"2026-04-17T00:54:10.066644Z","steps":["trace[424397881] 'agreement among raft nodes before linearized reading' (duration: 236.604794ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:10.066863Z","caller":"traceutil/trace.go:172","msg":"trace[977358175] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"240.818131ms","start":"2026-04-17T00:54:09.826025Z","end":"2026-04-17T00:54:10.066843Z","steps":["trace[977358175] 'process raft request' (duration: 240.580539ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:10.191095Z","caller":"traceutil/trace.go:172","msg":"trace[1894583912] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:401; }","duration":"124.575286ms","start":"2026-04-17T00:54:10.066494Z","end":"2026-04-17T00:54:10.191070Z","steps":["trace[1894583912] 'read index received' (duration: 124.564306ms)","trace[1894583912] 'applied index is now lower than readState.Index' (duration: 9.18µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:10.564111Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"557.657006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/controller\" limit:1 ","response":"range_response_count:1 size:4019"} {"level":"warn","ts":"2026-04-17T00:54:10.564187Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"372.974561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:54:10.564376Z","caller":"traceutil/trace.go:172","msg":"trace[1738673732] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"522.522981ms","start":"2026-04-17T00:54:10.041837Z","end":"2026-04-17T00:54:10.564360Z","steps":["trace[1738673732] 'process raft request' (duration: 149.310288ms)","trace[1738673732] 'compare' (duration: 372.8519ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:54:10.564325Z","caller":"traceutil/trace.go:172","msg":"trace[1973008655] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"493.555584ms","start":"2026-04-17T00:54:10.070756Z","end":"2026-04-17T00:54:10.564312Z","steps":["trace[1973008655] 'process raft request' (duration: 493.505543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:10.564454Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:10.041817Z","time spent":"522.601242ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":425,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:54:10.564466Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:10.070732Z","time spent":"493.699965ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:54:10.564711Z","caller":"traceutil/trace.go:172","msg":"trace[2014425861] range","detail":"{range_begin:/registry/minions/controller; range_end:; response_count:1; response_revision:386; }","duration":"557.778548ms","start":"2026-04-17T00:54:10.006420Z","end":"2026-04-17T00:54:10.564198Z","steps":["trace[2014425861] 'agreement among raft nodes before linearized reading' (duration: 184.735236ms)","trace[2014425861] 'range keys from in-memory index tree' (duration: 372.702008ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:10.564753Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:10.006401Z","time spent":"558.341094ms","remote":"127.0.0.1:47546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":32,"response count":1,"response size":4043,"request content":"key:\"/registry/minions/controller\" limit:1 "} {"level":"info","ts":"2026-04-17T00:54:10.695445Z","caller":"traceutil/trace.go:172","msg":"trace[1297096472] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:403; }","duration":"105.68938ms","start":"2026-04-17T00:54:10.589725Z","end":"2026-04-17T00:54:10.695415Z","steps":["trace[1297096472] 'read index received' (duration: 105.6793ms)","trace[1297096472] 'applied index is now lower than readState.Index' (duration: 8.54µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:10.741946Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"152.20397ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:10.742052Z","caller":"traceutil/trace.go:172","msg":"trace[981620809] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:388; }","duration":"152.323661ms","start":"2026-04-17T00:54:10.589706Z","end":"2026-04-17T00:54:10.742030Z","steps":["trace[981620809] 'agreement among raft nodes before linearized reading' (duration: 105.837841ms)","trace[981620809] 'range keys from in-memory index tree' (duration: 46.337969ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:54:10.742392Z","caller":"traceutil/trace.go:172","msg":"trace[1232466151] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"162.125207ms","start":"2026-04-17T00:54:10.580221Z","end":"2026-04-17T00:54:10.742346Z","steps":["trace[1232466151] 'process raft request' (duration: 115.220833ms)","trace[1232466151] 'compare' (duration: 46.50461ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:54:11.018398Z","caller":"traceutil/trace.go:172","msg":"trace[1065837750] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:404; }","duration":"186.20393ms","start":"2026-04-17T00:54:10.832143Z","end":"2026-04-17T00:54:11.018347Z","steps":["trace[1065837750] 'read index received' (duration: 186.192229ms)","trace[1065837750] 'applied index is now lower than readState.Index' (duration: 9.951µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:11.018662Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"186.498253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:11.018845Z","caller":"traceutil/trace.go:172","msg":"trace[1597094372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:389; }","duration":"186.670235ms","start":"2026-04-17T00:54:10.832128Z","end":"2026-04-17T00:54:11.018798Z","steps":["trace[1597094372] 'agreement among raft nodes before linearized reading' (duration: 186.358402ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:11.018815Z","caller":"traceutil/trace.go:172","msg":"trace[1048042504] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"212.225395ms","start":"2026-04-17T00:54:10.806566Z","end":"2026-04-17T00:54:11.018792Z","steps":["trace[1048042504] 'process raft request' (duration: 211.971342ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:12.255071Z","caller":"traceutil/trace.go:172","msg":"trace[2106825515] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:408; }","duration":"164.991607ms","start":"2026-04-17T00:54:12.090051Z","end":"2026-04-17T00:54:12.255043Z","steps":["trace[2106825515] 'read index received' (duration: 164.972047ms)","trace[2106825515] 'applied index is now lower than readState.Index' (duration: 17.99µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:12.255328Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"165.25371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" limit:1 ","response":"range_response_count:1 size:4338"} {"level":"info","ts":"2026-04-17T00:54:12.255370Z","caller":"traceutil/trace.go:172","msg":"trace[239356924] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:392; }","duration":"165.31895ms","start":"2026-04-17T00:54:12.090041Z","end":"2026-04-17T00:54:12.255360Z","steps":["trace[239356924] 'agreement among raft nodes before linearized reading' (duration: 165.100978ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:12.255510Z","caller":"traceutil/trace.go:172","msg":"trace[1315121403] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"183.948508ms","start":"2026-04-17T00:54:12.071534Z","end":"2026-04-17T00:54:12.255482Z","steps":["trace[1315121403] 'process raft request' (duration: 183.552544ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:12.669709Z","caller":"traceutil/trace.go:172","msg":"trace[383940774] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"396.605794ms","start":"2026-04-17T00:54:12.273061Z","end":"2026-04-17T00:54:12.669667Z","steps":["trace[383940774] 'process raft request' (duration: 385.614737ms)","trace[383940774] 'compare' (duration: 10.485332ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:12.671083Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:12.273022Z","time spent":"396.783696ms","remote":"127.0.0.1:48102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:54:18.262287Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.159559041s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T00:54:18.446286Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.614220569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:18.446405Z","caller":"traceutil/trace.go:172","msg":"trace[738935252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:434; }","duration":"1.61435083s","start":"2026-04-17T00:54:16.832032Z","end":"2026-04-17T00:54:18.446383Z","steps":["trace[738935252] 'range keys from in-memory index tree' (duration: 1.614112358s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:18.446447Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:16.832008Z","time spent":"1.614429522s","remote":"127.0.0.1:47280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:54:18.446482Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"856.721828ms","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-04-17T00:54:18.446589Z","caller":"traceutil/trace.go:172","msg":"trace[612490779] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:434; }","duration":"856.856599ms","start":"2026-04-17T00:54:17.589714Z","end":"2026-04-17T00:54:18.446571Z","steps":["trace[612490779] 'range keys from in-memory index tree' (duration: 856.656698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:18.446906Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"184.195036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:54:18.447119Z","caller":"traceutil/trace.go:172","msg":"trace[2062548425] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"1.344447275s","start":"2026-04-17T00:54:17.102650Z","end":"2026-04-17T00:54:18.447097Z","steps":["trace[2062548425] 'process raft request' (duration: 1.159967446s)","trace[2062548425] 'compare' (duration: 183.560641ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:18.447254Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:17.102628Z","time spent":"1.344540095s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:54:18.764003Z","caller":"traceutil/trace.go:172","msg":"trace[395583827] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:453; }","duration":"317.341043ms","start":"2026-04-17T00:54:18.446641Z","end":"2026-04-17T00:54:18.763982Z","steps":["trace[395583827] 'read index received' (duration: 317.334523ms)","trace[395583827] 'applied index is now lower than readState.Index' (duration: 5.31µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:19.039793Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"593.126414ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:19.039885Z","caller":"traceutil/trace.go:172","msg":"trace[1374307552] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:435; }","duration":"593.225995ms","start":"2026-04-17T00:54:18.446631Z","end":"2026-04-17T00:54:19.039857Z","steps":["trace[1374307552] 'agreement among raft nodes before linearized reading' (duration: 317.453454ms)","trace[1374307552] 'range keys from in-memory index tree' (duration: 275.63996ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:19.040152Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"275.960323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:54:19.040410Z","caller":"traceutil/trace.go:172","msg":"trace[732637152] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"760.180562ms","start":"2026-04-17T00:54:18.280196Z","end":"2026-04-17T00:54:19.040376Z","steps":["trace[732637152] 'process raft request' (duration: 760.093161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:19.040508Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:18.280174Z","time spent":"760.287453ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:54:19.040594Z","caller":"traceutil/trace.go:172","msg":"trace[1857816977] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:453; }","duration":"276.452896ms","start":"2026-04-17T00:54:18.764052Z","end":"2026-04-17T00:54:19.040505Z","steps":["trace[1857816977] 'read index received' (duration: 232.869454ms)","trace[1857816977] 'applied index is now lower than readState.Index' (duration: 43.579622ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:54:19.040748Z","caller":"traceutil/trace.go:172","msg":"trace[639258173] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"1.087116675s","start":"2026-04-17T00:54:17.953616Z","end":"2026-04-17T00:54:19.040732Z","steps":["trace[639258173] 'process raft request' (duration: 810.495377ms)","trace[639258173] 'compare' (duration: 275.854252ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:19.040872Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:17.953594Z","time spent":"1.087182146s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:54:19.040898Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"593.172394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:19.040947Z","caller":"traceutil/trace.go:172","msg":"trace[1763126268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:437; }","duration":"593.221834ms","start":"2026-04-17T00:54:18.447712Z","end":"2026-04-17T00:54:19.040934Z","steps":["trace[1763126268] 'agreement among raft nodes before linearized reading' (duration: 593.141384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:19.040983Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:18.447689Z","time spent":"593.284965ms","remote":"127.0.0.1:47300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:54:19.041015Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"257.108157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-17T00:54:19.041072Z","caller":"traceutil/trace.go:172","msg":"trace[467850338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:437; }","duration":"257.168517ms","start":"2026-04-17T00:54:18.783890Z","end":"2026-04-17T00:54:19.041058Z","steps":["trace[467850338] 'agreement among raft nodes before linearized reading' (duration: 256.993556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:19.041262Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"257.35618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T00:54:19.041311Z","caller":"traceutil/trace.go:172","msg":"trace[1477763966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:437; }","duration":"257.409681ms","start":"2026-04-17T00:54:18.783891Z","end":"2026-04-17T00:54:19.041300Z","steps":["trace[1477763966] 'agreement among raft nodes before linearized reading' (duration: 257.241539ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:19.168838Z","caller":"traceutil/trace.go:172","msg":"trace[424351952] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:455; }","duration":"118.81006ms","start":"2026-04-17T00:54:19.049999Z","end":"2026-04-17T00:54:19.168809Z","steps":["trace[424351952] 'read index received' (duration: 118.80111ms)","trace[424351952] 'applied index is now lower than readState.Index' (duration: 7.76µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:19.178527Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"128.507875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:19.178593Z","caller":"traceutil/trace.go:172","msg":"trace[1394039035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:437; }","duration":"128.584015ms","start":"2026-04-17T00:54:19.049995Z","end":"2026-04-17T00:54:19.178579Z","steps":["trace[1394039035] 'agreement among raft nodes before linearized reading' (duration: 118.888031ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:19.178720Z","caller":"traceutil/trace.go:172","msg":"trace[175067892] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"132.882367ms","start":"2026-04-17T00:54:19.045821Z","end":"2026-04-17T00:54:19.178703Z","steps":["trace[175067892] 'process raft request' (duration: 122.961321ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:19.178912Z","caller":"traceutil/trace.go:172","msg":"trace[402559120] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"132.919338ms","start":"2026-04-17T00:54:19.045981Z","end":"2026-04-17T00:54:19.178900Z","steps":["trace[402559120] 'process raft request' (duration: 132.688766ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:21.287701Z","caller":"traceutil/trace.go:172","msg":"trace[678244008] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"101.482619ms","start":"2026-04-17T00:54:21.186190Z","end":"2026-04-17T00:54:21.287673Z","steps":["trace[678244008] 'process raft request' (duration: 41.462392ms)","trace[678244008] 'compare' (duration: 59.544753ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:54:21.287894Z","caller":"traceutil/trace.go:172","msg":"trace[849095099] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"101.186586ms","start":"2026-04-17T00:54:21.186687Z","end":"2026-04-17T00:54:21.287874Z","steps":["trace[849095099] 'process raft request' (duration: 101.075195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:25.090946Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095253638728987,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:54:25.213533Z","caller":"traceutil/trace.go:172","msg":"trace[357447087] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:466; }","duration":"623.222969ms","start":"2026-04-17T00:54:24.590284Z","end":"2026-04-17T00:54:25.213507Z","steps":["trace[357447087] 'read index received' (duration: 623.202269ms)","trace[357447087] 'applied index is now lower than readState.Index' (duration: 18.92µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:25.213625Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"623.3265ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:25.213832Z","caller":"traceutil/trace.go:172","msg":"trace[1187284026] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:447; }","duration":"623.543142ms","start":"2026-04-17T00:54:24.590277Z","end":"2026-04-17T00:54:25.213821Z","steps":["trace[1187284026] 'agreement among raft nodes before linearized reading' (duration: 623.29906ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:25.214045Z","caller":"traceutil/trace.go:172","msg":"trace[128157839] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"896.184017ms","start":"2026-04-17T00:54:24.317853Z","end":"2026-04-17T00:54:25.214037Z","steps":["trace[128157839] 'process raft request' (duration: 895.897434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:25.214232Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:24.317823Z","time spent":"896.284997ms","remote":"127.0.0.1:47434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317280,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:54:25.217775Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"385.234409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:25.217854Z","caller":"traceutil/trace.go:172","msg":"trace[1328436286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"385.32774ms","start":"2026-04-17T00:54:24.832510Z","end":"2026-04-17T00:54:25.217837Z","steps":["trace[1328436286] 'agreement among raft nodes before linearized reading' (duration: 385.182739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:25.217895Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:24.832487Z","time spent":"385.40122ms","remote":"127.0.0.1:47280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T00:54:25.218391Z","caller":"traceutil/trace.go:172","msg":"trace[1088624086] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"732.491914ms","start":"2026-04-17T00:54:24.485853Z","end":"2026-04-17T00:54:25.218345Z","steps":["trace[1088624086] 'process raft request' (duration: 732.068831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:25.218526Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:24.485808Z","time spent":"732.653136ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:54:25.219101Z","caller":"traceutil/trace.go:172","msg":"trace[1667805509] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"278.524626ms","start":"2026-04-17T00:54:24.940559Z","end":"2026-04-17T00:54:25.219083Z","steps":["trace[1667805509] 'process raft request' (duration: 277.571547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:27.813262Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095253638729055,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:54:28.313485Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095253638729055,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:54:28.512187Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.601443816s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T00:54:28.512454Z","caller":"traceutil/trace.go:172","msg":"trace[739207019] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:508; }","duration":"1.199946428s","start":"2026-04-17T00:54:27.312487Z","end":"2026-04-17T00:54:28.512434Z","steps":["trace[739207019] 'read index received' (duration: 1.199939008s)","trace[739207019] 'applied index is now lower than readState.Index' (duration: 6.21µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:28.679772Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.089715351s","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-04-17T00:54:28.679873Z","caller":"traceutil/trace.go:172","msg":"trace[1342939946] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:488; }","duration":"1.089840692s","start":"2026-04-17T00:54:27.590009Z","end":"2026-04-17T00:54:28.679849Z","steps":["trace[1342939946] 'range keys from in-memory index tree' (duration: 1.089638251s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:28.679899Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.367378548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T00:54:28.679981Z","caller":"traceutil/trace.go:172","msg":"trace[263112163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:488; }","duration":"1.367478129s","start":"2026-04-17T00:54:27.312482Z","end":"2026-04-17T00:54:28.679960Z","steps":["trace[263112163] 'agreement among raft nodes before linearized reading' (duration: 1.200020089s)","trace[263112163] 'range keys from in-memory index tree' (duration: 167.146678ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:28.680024Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:27.312468Z","time spent":"1.367546399s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:54:28.679992Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"167.37987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:54:28.680184Z","caller":"traceutil/trace.go:172","msg":"trace[30519050] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.775449584s","start":"2026-04-17T00:54:26.904720Z","end":"2026-04-17T00:54:28.680169Z","steps":["trace[30519050] 'process raft request' (duration: 1.775379473s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:28.680253Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:26.904714Z","time spent":"1.775515894s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:54:28.680278Z","caller":"traceutil/trace.go:172","msg":"trace[116489894] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.775601595s","start":"2026-04-17T00:54:26.904659Z","end":"2026-04-17T00:54:28.680261Z","steps":["trace[116489894] 'process raft request' (duration: 1.607891713s)","trace[116489894] 'compare' (duration: 167.184218ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:28.681171Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:26.904648Z","time spent":"1.776257802s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:54:28.717970Z","caller":"traceutil/trace.go:172","msg":"trace[1524845621] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:510; }","duration":"205.444522ms","start":"2026-04-17T00:54:28.512486Z","end":"2026-04-17T00:54:28.717930Z","steps":["trace[1524845621] 'read index received' (duration: 205.432102ms)","trace[1524845621] 'applied index is now lower than readState.Index' (duration: 10.49µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:28.718021Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"885.81543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:28.718091Z","caller":"traceutil/trace.go:172","msg":"trace[1296774513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"885.892571ms","start":"2026-04-17T00:54:27.832182Z","end":"2026-04-17T00:54:28.718074Z","steps":["trace[1296774513] 'agreement among raft nodes before linearized reading' (duration: 885.76489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:28.718117Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:27.832143Z","time spent":"885.969752ms","remote":"127.0.0.1:47280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:54:28.718274Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.40543139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-17T00:54:28.718464Z","caller":"traceutil/trace.go:172","msg":"trace[226798424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:490; }","duration":"1.405639002s","start":"2026-04-17T00:54:27.312803Z","end":"2026-04-17T00:54:28.718442Z","steps":["trace[226798424] 'agreement among raft nodes before linearized reading' (duration: 1.405257239s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:28.718632Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:27.312793Z","time spent":"1.405806323s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-04-17T00:54:28.718312Z","caller":"traceutil/trace.go:172","msg":"trace[30199480] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.803216884s","start":"2026-04-17T00:54:26.915075Z","end":"2026-04-17T00:54:28.718292Z","steps":["trace[30199480] 'process raft request' (duration: 1.802972772s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:28.718985Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:26.915059Z","time spent":"1.80386319s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:54:28.719094Z","caller":"traceutil/trace.go:172","msg":"trace[239328222] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.78026975s","start":"2026-04-17T00:54:26.938783Z","end":"2026-04-17T00:54:28.719053Z","steps":["trace[239328222] 'process raft request' (duration: 1.779525873s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:28.718346Z","caller":"traceutil/trace.go:172","msg":"trace[1773482337] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.787237751s","start":"2026-04-17T00:54:26.931100Z","end":"2026-04-17T00:54:28.718338Z","steps":["trace[1773482337] 'process raft request' (duration: 1.78713903s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:28.719243Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:26.938761Z","time spent":"1.780420191s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:54:28.719313Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:26.931070Z","time spent":"1.788191869s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:54:28.719166Z","caller":"traceutil/trace.go:172","msg":"trace[151581601] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.776447695s","start":"2026-04-17T00:54:26.942694Z","end":"2026-04-17T00:54:28.719142Z","steps":["trace[151581601] 'process raft request' (duration: 1.775959541s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:28.718398Z","caller":"traceutil/trace.go:172","msg":"trace[1670325349] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.779806315s","start":"2026-04-17T00:54:26.938586Z","end":"2026-04-17T00:54:28.718393Z","steps":["trace[1670325349] 'process raft request' (duration: 1.779684854s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:28.719500Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:26.942681Z","time spent":"1.776765578s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:54:28.719554Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:26.938568Z","time spent":"1.780951996s","remote":"127.0.0.1:48400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:54:28.719858Z","caller":"traceutil/trace.go:172","msg":"trace[394971846] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.77703441s","start":"2026-04-17T00:54:26.942812Z","end":"2026-04-17T00:54:28.719846Z","steps":["trace[394971846] 'process raft request' (duration: 1.776101783s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:28.719933Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:26.942803Z","time spent":"1.777086391s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:54:28.720063Z","caller":"traceutil/trace.go:172","msg":"trace[644897339] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.77477646s","start":"2026-04-17T00:54:26.945279Z","end":"2026-04-17T00:54:28.720055Z","steps":["trace[644897339] 'process raft request' (duration: 1.773872203s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:28.720114Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:26.945257Z","time spent":"1.7748243s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:54:28.720254Z","caller":"traceutil/trace.go:172","msg":"trace[1762758747] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.77477906s","start":"2026-04-17T00:54:26.945465Z","end":"2026-04-17T00:54:28.720244Z","steps":["trace[1762758747] 'process raft request' (duration: 1.773911502s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:28.720291Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:26.945460Z","time spent":"1.774814001s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:54:28.722744Z","caller":"traceutil/trace.go:172","msg":"trace[98953842] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.759085889s","start":"2026-04-17T00:54:26.963632Z","end":"2026-04-17T00:54:28.722718Z","steps":["trace[98953842] 'process raft request' (duration: 1.756385506s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:28.723083Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:26.963612Z","time spent":"1.759304622s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:54:28.724387Z","caller":"traceutil/trace.go:172","msg":"trace[1602672589] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.641495211s","start":"2026-04-17T00:54:27.082876Z","end":"2026-04-17T00:54:28.724371Z","steps":["trace[1602672589] 'process raft request' (duration: 1.640717703s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:28.724512Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:27.082843Z","time spent":"1.641611891s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:54:28.724777Z","caller":"traceutil/trace.go:172","msg":"trace[1542458988] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"1.49506296s","start":"2026-04-17T00:54:27.229704Z","end":"2026-04-17T00:54:28.724767Z","steps":["trace[1542458988] 'process raft request' (duration: 1.494042461s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:28.724826Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:27.229686Z","time spent":"1.49511534s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:54:28.725621Z","caller":"traceutil/trace.go:172","msg":"trace[728212519] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.745869182s","start":"2026-04-17T00:54:26.978593Z","end":"2026-04-17T00:54:28.724462Z","steps":["trace[728212519] 'process raft request' (duration: 1.741671146s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:28.725714Z","caller":"traceutil/trace.go:172","msg":"trace[739704042] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.49514835s","start":"2026-04-17T00:54:27.229953Z","end":"2026-04-17T00:54:28.725101Z","steps":["trace[739704042] 'process raft request' (duration: 1.493868859s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:28.725859Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:27.229944Z","time spent":"1.495836947s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:54:28.725858Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:26.978575Z","time spent":"1.747100262s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:54:33.385709Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58346","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:33.507902Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58364","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:33.575100Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58382","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:33.639948Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58396","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:33.661975Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58418","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:33.673620Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58432","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:33.834000Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58456","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:33.856404Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58468","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:33.876582Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58482","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:33.897260Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58494","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:33.915984Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58504","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:33.948084Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58526","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:33.969454Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58534","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:33.991705Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58548","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:34.013727Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58580","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:34.043746Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58594","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:34.100812Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58630","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:34.142457Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58638","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:34.168593Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58644","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:34.195297Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58662","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:34.207395Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58676","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:34.221584Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58702","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:34.235763Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:58714","server-name":"","error":"EOF"} {"level":"info","ts":"2026-04-17T00:54:34.556304Z","caller":"traceutil/trace.go:172","msg":"trace[1461991118] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:611; }","duration":"157.931468ms","start":"2026-04-17T00:54:34.398302Z","end":"2026-04-17T00:54:34.556234Z","steps":["trace[1461991118] 'read index received' (duration: 157.919068ms)","trace[1461991118] 'applied index is now lower than readState.Index' (duration: 10.62µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:34.556444Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"158.117939ms","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-04-17T00:54:34.556508Z","caller":"traceutil/trace.go:172","msg":"trace[702110094] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies; range_end:; response_count:0; response_revision:587; }","duration":"158.2017ms","start":"2026-04-17T00:54:34.398288Z","end":"2026-04-17T00:54:34.556489Z","steps":["trace[702110094] 'agreement among raft nodes before linearized reading' (duration: 158.057409ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:34.556976Z","caller":"traceutil/trace.go:172","msg":"trace[386888420] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"293.221167ms","start":"2026-04-17T00:54:34.263740Z","end":"2026-04-17T00:54:34.556961Z","steps":["trace[386888420] 'process raft request' (duration: 292.613533ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:34.889642Z","caller":"traceutil/trace.go:172","msg":"trace[1460611940] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:612; }","duration":"124.712885ms","start":"2026-04-17T00:54:34.764877Z","end":"2026-04-17T00:54:34.889590Z","steps":["trace[1460611940] 'read index received' (duration: 124.705155ms)","trace[1460611940] 'applied index is now lower than readState.Index' (duration: 6.54µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:34.999018Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"234.124123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:34.999104Z","caller":"traceutil/trace.go:172","msg":"trace[1982731634] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies; range_end:; response_count:0; response_revision:588; }","duration":"234.217583ms","start":"2026-04-17T00:54:34.764862Z","end":"2026-04-17T00:54:34.999080Z","steps":["trace[1982731634] 'agreement among raft nodes before linearized reading' (duration: 124.811855ms)","trace[1982731634] 'range keys from in-memory index tree' (duration: 109.280678ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:34.999606Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"109.914502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:54:34.999774Z","caller":"traceutil/trace.go:172","msg":"trace[2114853899] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"384.897171ms","start":"2026-04-17T00:54:34.614819Z","end":"2026-04-17T00:54:34.999716Z","steps":["trace[2114853899] 'process raft request' (duration: 274.806807ms)","trace[2114853899] 'compare' (duration: 109.736191ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:34.999935Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:34.614789Z","time spent":"385.069642ms","remote":"127.0.0.1:47912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:54:34.999949Z","caller":"traceutil/trace.go:172","msg":"trace[214381398] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"110.089194ms","start":"2026-04-17T00:54:34.889759Z","end":"2026-04-17T00:54:34.999848Z","steps":["trace[214381398] 'read index received' (duration: 108.772053ms)","trace[214381398] 'applied index is now lower than readState.Index' (duration: 1.313581ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:34.999966Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"167.003282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:35.000047Z","caller":"traceutil/trace.go:172","msg":"trace[558845834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"167.088103ms","start":"2026-04-17T00:54:34.832940Z","end":"2026-04-17T00:54:35.000028Z","steps":["trace[558845834] 'agreement among raft nodes before linearized reading' (duration: 166.940571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:35.000166Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"176.57063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-17T00:54:35.000208Z","caller":"traceutil/trace.go:172","msg":"trace[1910665825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"176.619541ms","start":"2026-04-17T00:54:34.823579Z","end":"2026-04-17T00:54:35.000198Z","steps":["trace[1910665825] 'agreement among raft nodes before linearized reading' (duration: 176.47452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:35.000229Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"154.877782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:35.000259Z","caller":"traceutil/trace.go:172","msg":"trace[982034896] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies; range_end:; response_count:0; response_revision:591; }","duration":"154.909682ms","start":"2026-04-17T00:54:34.845340Z","end":"2026-04-17T00:54:35.000250Z","steps":["trace[982034896] 'agreement among raft nodes before linearized reading' (duration: 154.859612ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:35.000218Z","caller":"traceutil/trace.go:172","msg":"trace[1507483168] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"184.485765ms","start":"2026-04-17T00:54:34.815715Z","end":"2026-04-17T00:54:35.000201Z","steps":["trace[1507483168] 'process raft request' (duration: 184.276783ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:35.000462Z","caller":"traceutil/trace.go:172","msg":"trace[179162075] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"184.904649ms","start":"2026-04-17T00:54:34.815545Z","end":"2026-04-17T00:54:35.000450Z","steps":["trace[179162075] 'process raft request' (duration: 184.160753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:35.000462Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"223.715158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T00:54:35.000512Z","caller":"traceutil/trace.go:172","msg":"trace[1674503610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"223.768258ms","start":"2026-04-17T00:54:34.776731Z","end":"2026-04-17T00:54:35.000499Z","steps":["trace[1674503610] 'agreement among raft nodes before linearized reading' (duration: 223.647247ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:35.136545Z","caller":"traceutil/trace.go:172","msg":"trace[987146880] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:616; }","duration":"120.360839ms","start":"2026-04-17T00:54:35.016133Z","end":"2026-04-17T00:54:35.136493Z","steps":["trace[987146880] 'read index received' (duration: 120.349139ms)","trace[987146880] 'applied index is now lower than readState.Index' (duration: 9.96µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:35.160908Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"148.063485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:35.160983Z","caller":"traceutil/trace.go:172","msg":"trace[1771203801] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes; range_end:; response_count:0; response_revision:592; }","duration":"148.142746ms","start":"2026-04-17T00:54:35.012820Z","end":"2026-04-17T00:54:35.160963Z","steps":["trace[1771203801] 'agreement among raft nodes before linearized reading' (duration: 123.784617ms)","trace[1771203801] 'range keys from in-memory index tree' (duration: 24.249428ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:35.162189Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"134.684887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:35.162257Z","caller":"traceutil/trace.go:172","msg":"trace[270141221] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends; range_end:; response_count:0; response_revision:593; }","duration":"134.754778ms","start":"2026-04-17T00:54:35.027492Z","end":"2026-04-17T00:54:35.162247Z","steps":["trace[270141221] 'agreement among raft nodes before linearized reading' (duration: 134.660037ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:35.162172Z","caller":"traceutil/trace.go:172","msg":"trace[1925581236] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"155.496706ms","start":"2026-04-17T00:54:35.006606Z","end":"2026-04-17T00:54:35.162103Z","steps":["trace[1925581236] 'process raft request' (duration: 129.910397ms)","trace[1925581236] 'compare' (duration: 24.256099ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:35.162635Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"106.253515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:35.162712Z","caller":"traceutil/trace.go:172","msg":"trace[7926199] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies; range_end:; response_count:0; response_revision:593; }","duration":"106.386306ms","start":"2026-04-17T00:54:35.056309Z","end":"2026-04-17T00:54:35.162696Z","steps":["trace[7926199] 'agreement among raft nodes before linearized reading' (duration: 106.206575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:35.549983Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"191.296827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:54:35.550087Z","caller":"traceutil/trace.go:172","msg":"trace[1969265567] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"281.046287ms","start":"2026-04-17T00:54:35.269024Z","end":"2026-04-17T00:54:35.550070Z","steps":["trace[1969265567] 'process raft request' (duration: 89.587919ms)","trace[1969265567] 'compare' (duration: 191.080525ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:54:35.585739Z","caller":"traceutil/trace.go:172","msg":"trace[125779489] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"176.412066ms","start":"2026-04-17T00:54:35.409305Z","end":"2026-04-17T00:54:35.585717Z","steps":["trace[125779489] 'process raft request' (duration: 175.935283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:35.772617Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"113.628485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:54:35.772748Z","caller":"traceutil/trace.go:172","msg":"trace[760991534] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"129.535004ms","start":"2026-04-17T00:54:35.643196Z","end":"2026-04-17T00:54:35.772731Z","steps":["trace[760991534] 'process raft request' (duration: 15.703458ms)","trace[760991534] 'compare' (duration: 113.407652ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:36.767090Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"176.595124ms","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-04-17T00:54:36.767178Z","caller":"traceutil/trace.go:172","msg":"trace[1755453011] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:607; }","duration":"176.724685ms","start":"2026-04-17T00:54:36.590437Z","end":"2026-04-17T00:54:36.767161Z","steps":["trace[1755453011] 'range keys from in-memory index tree' (duration: 176.574674ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:36.958219Z","caller":"traceutil/trace.go:172","msg":"trace[1079931228] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:632; }","duration":"127.017564ms","start":"2026-04-17T00:54:36.831176Z","end":"2026-04-17T00:54:36.958193Z","steps":["trace[1079931228] 'read index received' (duration: 127.008994ms)","trace[1079931228] 'applied index is now lower than readState.Index' (duration: 7.32µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:36.958341Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"127.161725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:36.958387Z","caller":"traceutil/trace.go:172","msg":"trace[510106317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"127.220095ms","start":"2026-04-17T00:54:36.831158Z","end":"2026-04-17T00:54:36.958378Z","steps":["trace[510106317] 'agreement among raft nodes before linearized reading' (duration: 127.126895ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:36.958431Z","caller":"traceutil/trace.go:172","msg":"trace[1941316724] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"174.373487ms","start":"2026-04-17T00:54:36.784039Z","end":"2026-04-17T00:54:36.958413Z","steps":["trace[1941316724] 'process raft request' (duration: 174.219664ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:37.148161Z","caller":"traceutil/trace.go:172","msg":"trace[331653603] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:633; }","duration":"127.597ms","start":"2026-04-17T00:54:37.020532Z","end":"2026-04-17T00:54:37.148129Z","steps":["trace[331653603] 'read index received' (duration: 127.58593ms)","trace[331653603] 'applied index is now lower than readState.Index' (duration: 9.33µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:37.260076Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"239.522145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-17T00:54:37.260156Z","caller":"traceutil/trace.go:172","msg":"trace[1259228513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"239.612695ms","start":"2026-04-17T00:54:37.020526Z","end":"2026-04-17T00:54:37.260138Z","steps":["trace[1259228513] 'agreement among raft nodes before linearized reading' (duration: 127.709271ms)","trace[1259228513] 'range keys from in-memory index tree' (duration: 111.694873ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:37.260467Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"112.175817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:54:37.260562Z","caller":"traceutil/trace.go:172","msg":"trace[1998044530] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"252.206586ms","start":"2026-04-17T00:54:37.008338Z","end":"2026-04-17T00:54:37.260544Z","steps":["trace[1998044530] 'process raft request' (duration: 139.888379ms)","trace[1998044530] 'compare' (duration: 112.058395ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:37.668007Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095253638729326,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:54:38.168965Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095253638729326,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:54:38.469185Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.321053308s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T00:54:38.469532Z","caller":"traceutil/trace.go:172","msg":"trace[1675651546] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:634; }","duration":"1.301932156s","start":"2026-04-17T00:54:37.167550Z","end":"2026-04-17T00:54:38.469482Z","steps":["trace[1675651546] 'read index received' (duration: 1.301907755s)","trace[1675651546] 'applied index is now lower than readState.Index' (duration: 19.42µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:38.494818Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.327253406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T00:54:38.494892Z","caller":"traceutil/trace.go:172","msg":"trace[1937914603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"1.327342136s","start":"2026-04-17T00:54:37.167533Z","end":"2026-04-17T00:54:38.494875Z","steps":["trace[1937914603] 'agreement among raft nodes before linearized reading' (duration: 1.302084537s)","trace[1937914603] 'range keys from in-memory index tree' (duration: 24.985398ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:38.494926Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:37.167510Z","time spent":"1.327408127s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:54:38.495107Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"904.97294ms","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-04-17T00:54:38.495173Z","caller":"traceutil/trace.go:172","msg":"trace[1884713359] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:609; }","duration":"905.07806ms","start":"2026-04-17T00:54:37.590078Z","end":"2026-04-17T00:54:38.495156Z","steps":["trace[1884713359] 'range keys from in-memory index tree' (duration: 904.916869ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:38.495298Z","caller":"traceutil/trace.go:172","msg":"trace[1754428591] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.486233776s","start":"2026-04-17T00:54:37.009038Z","end":"2026-04-17T00:54:38.495272Z","steps":["trace[1754428591] 'process raft request' (duration: 1.460543203s)","trace[1754428591] 'compare' (duration: 25.550462ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:38.495483Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:37.009020Z","time spent":"1.486382287s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:54:38.534593Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"702.939622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:38.534647Z","caller":"traceutil/trace.go:172","msg":"trace[612408117] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"1.270151956s","start":"2026-04-17T00:54:37.264476Z","end":"2026-04-17T00:54:38.534628Z","steps":["trace[612408117] 'process raft request' (duration: 1.270010585s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:38.534697Z","caller":"traceutil/trace.go:172","msg":"trace[550983468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"703.062023ms","start":"2026-04-17T00:54:37.831613Z","end":"2026-04-17T00:54:38.534675Z","steps":["trace[550983468] 'agreement among raft nodes before linearized reading' (duration: 702.868792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:38.534754Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:37.831587Z","time spent":"703.157814ms","remote":"127.0.0.1:47280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:54:38.534786Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:37.264452Z","time spent":"1.270254807s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":424,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:54:38.861107Z","caller":"traceutil/trace.go:172","msg":"trace[815043581] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:636; }","duration":"326.628298ms","start":"2026-04-17T00:54:38.534434Z","end":"2026-04-17T00:54:38.861063Z","steps":["trace[815043581] 'read index received' (duration: 326.614438ms)","trace[815043581] 'applied index is now lower than readState.Index' (duration: 11.18µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:39.263590Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"768.332191ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:39.263682Z","caller":"traceutil/trace.go:172","msg":"trace[1601599010] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:611; }","duration":"768.445912ms","start":"2026-04-17T00:54:38.495215Z","end":"2026-04-17T00:54:39.263661Z","steps":["trace[1601599010] 'agreement among raft nodes before linearized reading' (duration: 365.93998ms)","trace[1601599010] 'range keys from in-memory index tree' (duration: 402.36376ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:39.264751Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"402.666254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:54:39.264918Z","caller":"traceutil/trace.go:172","msg":"trace[2050998654] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"403.693861ms","start":"2026-04-17T00:54:38.861201Z","end":"2026-04-17T00:54:39.264895Z","steps":["trace[2050998654] 'read index received' (duration: 68.03µs)","trace[2050998654] 'applied index is now lower than readState.Index' (duration: 403.623801ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:39.265034Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"729.221292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:39.265070Z","caller":"traceutil/trace.go:172","msg":"trace[993894937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"729.256292ms","start":"2026-04-17T00:54:38.535803Z","end":"2026-04-17T00:54:39.265060Z","steps":["trace[993894937] 'agreement among raft nodes before linearized reading' (duration: 729.158882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:39.265109Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:38.535782Z","time spent":"729.319373ms","remote":"127.0.0.1:47300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T00:54:39.265060Z","caller":"traceutil/trace.go:172","msg":"trace[1093692732] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"765.974503ms","start":"2026-04-17T00:54:38.499032Z","end":"2026-04-17T00:54:39.265007Z","steps":["trace[1093692732] 'process raft request' (duration: 362.035519ms)","trace[1093692732] 'compare' (duration: 402.337251ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:39.265307Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:38.499008Z","time spent":"766.167164ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:54:39.774526Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095253638729333,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:54:40.274939Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095253638729333,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:54:40.775830Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095253638729333,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:54:40.955198Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.687031179s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T00:54:40.955525Z","caller":"traceutil/trace.go:172","msg":"trace[1547941350] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:637; }","duration":"1.681386284s","start":"2026-04-17T00:54:39.274099Z","end":"2026-04-17T00:54:40.955486Z","steps":["trace[1547941350] 'read index received' (duration: 1.681373214s)","trace[1547941350] 'applied index is now lower than readState.Index' (duration: 11.2µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:41.456508Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095253638729337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:54:41.957139Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095253638729337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:54:42.457327Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095253638729337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:54:42.524269Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.568844972s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T00:54:42.964627Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"3.690520244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:42.964705Z","caller":"traceutil/trace.go:172","msg":"trace[1848599958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"3.690601205s","start":"2026-04-17T00:54:39.274085Z","end":"2026-04-17T00:54:42.964686Z","steps":["trace[1848599958] 'agreement among raft nodes before linearized reading' (duration: 1.681532445s)","trace[1848599958] 'range keys from in-memory index tree' (duration: 2.008961109s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:42.964741Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:39.274060Z","time spent":"3.690673235s","remote":"127.0.0.1:47280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:54:42.965496Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.009783335s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:54:42.966036Z","caller":"traceutil/trace.go:172","msg":"trace[374568455] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"2.0103881s","start":"2026-04-17T00:54:40.955607Z","end":"2026-04-17T00:54:42.965995Z","steps":["trace[374568455] 'read index received' (duration: 1.568829583s)","trace[374568455] 'applied index is now lower than readState.Index' (duration: 441.556137ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:54:42.966144Z","caller":"traceutil/trace.go:172","msg":"trace[1914636916] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"3.698149664s","start":"2026-04-17T00:54:39.267983Z","end":"2026-04-17T00:54:42.966132Z","steps":["trace[1914636916] 'process raft request' (duration: 1.687553793s)","trace[1914636916] 'compare' (duration: 2.009001509s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:42.966233Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:39.267762Z","time spent":"3.698411735s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:54:42.966447Z","caller":"traceutil/trace.go:172","msg":"trace[538390075] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"3.603629481s","start":"2026-04-17T00:54:39.362792Z","end":"2026-04-17T00:54:42.966422Z","steps":["trace[538390075] 'process raft request' (duration: 3.602889365s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:42.966626Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.697691109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-17T00:54:42.966636Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:39.362753Z","time spent":"3.603799842s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:54:42.966691Z","caller":"traceutil/trace.go:172","msg":"trace[1626588392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"1.69775907s","start":"2026-04-17T00:54:41.268917Z","end":"2026-04-17T00:54:42.966676Z","steps":["trace[1626588392] 'agreement among raft nodes before linearized reading' (duration: 1.697521228s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:42.966743Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:41.268894Z","time spent":"1.697835771s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:54:42.966766Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"3.376930729s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:54:42.966814Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.692394009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:54:42.966832Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.428791722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T00:54:42.966847Z","caller":"traceutil/trace.go:172","msg":"trace[889705130] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:615; }","duration":"3.37702753s","start":"2026-04-17T00:54:39.589801Z","end":"2026-04-17T00:54:42.966828Z","steps":["trace[889705130] 'agreement among raft nodes before linearized reading' (duration: 3.376885669s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:42.966867Z","caller":"traceutil/trace.go:172","msg":"trace[1799714134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"2.428829402s","start":"2026-04-17T00:54:40.538028Z","end":"2026-04-17T00:54:42.966857Z","steps":["trace[1799714134] 'agreement among raft nodes before linearized reading' (duration: 2.428701241s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:42.966867Z","caller":"traceutil/trace.go:172","msg":"trace[722800788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"1.692445179s","start":"2026-04-17T00:54:41.274405Z","end":"2026-04-17T00:54:42.966850Z","steps":["trace[722800788] 'agreement among raft nodes before linearized reading' (duration: 1.692354918s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:42.966894Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:40.538013Z","time spent":"2.428875032s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:54:42.966911Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:41.274380Z","time spent":"1.692520599s","remote":"127.0.0.1:47300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T00:54:42.967018Z","caller":"traceutil/trace.go:172","msg":"trace[1669653442] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"2.464026436s","start":"2026-04-17T00:54:40.502954Z","end":"2026-04-17T00:54:42.966980Z","steps":["trace[1669653442] 'process raft request' (duration: 2.462909427s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:42.967175Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:40.502923Z","time spent":"2.464176737s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:54:45.651627Z","caller":"traceutil/trace.go:172","msg":"trace[972221633] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"459.190645ms","start":"2026-04-17T00:54:45.192419Z","end":"2026-04-17T00:54:45.651610Z","steps":["trace[972221633] 'process raft request' (duration: 459.092245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:45.651839Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:45.192387Z","time spent":"459.299256ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":868,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:54:47.030773Z","caller":"traceutil/trace.go:172","msg":"trace[61474782] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:668; }","duration":"199.668986ms","start":"2026-04-17T00:54:46.831075Z","end":"2026-04-17T00:54:47.030744Z","steps":["trace[61474782] 'read index received' (duration: 199.661856ms)","trace[61474782] 'applied index is now lower than readState.Index' (duration: 6.1µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:47.030877Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"199.794997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:47.030893Z","caller":"traceutil/trace.go:172","msg":"trace[297669486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"199.824148ms","start":"2026-04-17T00:54:46.831064Z","end":"2026-04-17T00:54:47.030888Z","steps":["trace[297669486] 'agreement among raft nodes before linearized reading' (duration: 199.757866ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:47.031086Z","caller":"traceutil/trace.go:172","msg":"trace[1254163227] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"258.073142ms","start":"2026-04-17T00:54:46.772988Z","end":"2026-04-17T00:54:47.031061Z","steps":["trace[1254163227] 'process raft request' (duration: 257.890451ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:47.279499Z","caller":"traceutil/trace.go:172","msg":"trace[1919471684] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:669; }","duration":"241.286575ms","start":"2026-04-17T00:54:47.038181Z","end":"2026-04-17T00:54:47.279467Z","steps":["trace[1919471684] 'read index received' (duration: 241.275794ms)","trace[1919471684] 'applied index is now lower than readState.Index' (duration: 8.731µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:47.447078Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"408.868795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T00:54:47.447153Z","caller":"traceutil/trace.go:172","msg":"trace[572009185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"408.955795ms","start":"2026-04-17T00:54:47.038178Z","end":"2026-04-17T00:54:47.447134Z","steps":["trace[572009185] 'agreement among raft nodes before linearized reading' (duration: 241.426886ms)","trace[572009185] 'range keys from in-memory index tree' (duration: 167.331938ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:47.447168Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"167.57731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-04-17T00:54:47.447191Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:47.038147Z","time spent":"409.035286ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"info","ts":"2026-04-17T00:54:47.447237Z","caller":"traceutil/trace.go:172","msg":"trace[581418124] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"667.897703ms","start":"2026-04-17T00:54:46.779325Z","end":"2026-04-17T00:54:47.447222Z","steps":["trace[581418124] 'process raft request' (duration: 500.203942ms)","trace[581418124] 'compare' (duration: 167.356199ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:47.447295Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:46.779315Z","time spent":"667.955153ms","remote":"127.0.0.1:47574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19387,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:54:47.547461Z","caller":"traceutil/trace.go:172","msg":"trace[1854094393] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:670; }","duration":"267.815051ms","start":"2026-04-17T00:54:47.279569Z","end":"2026-04-17T00:54:47.547384Z","steps":["trace[1854094393] 'read index received' (duration: 267.790981ms)","trace[1854094393] 'applied index is now lower than readState.Index' (duration: 22.1µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:47.557549Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"517.055305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T00:54:47.557680Z","caller":"traceutil/trace.go:172","msg":"trace[962127981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"517.200876ms","start":"2026-04-17T00:54:47.040461Z","end":"2026-04-17T00:54:47.557661Z","steps":["trace[962127981] 'agreement among raft nodes before linearized reading' (duration: 507.140942ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:47.557702Z","caller":"traceutil/trace.go:172","msg":"trace[1712932515] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"523.010519ms","start":"2026-04-17T00:54:47.034680Z","end":"2026-04-17T00:54:47.557691Z","steps":["trace[1712932515] 'process raft request' (duration: 522.814748ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:47.557753Z","caller":"traceutil/trace.go:172","msg":"trace[1997918032] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"522.466015ms","start":"2026-04-17T00:54:47.035277Z","end":"2026-04-17T00:54:47.557743Z","steps":["trace[1997918032] 'process raft request' (duration: 522.278343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:47.557824Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:47.040430Z","time spent":"517.327378ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:54:47.557871Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:47.035269Z","time spent":"522.528565ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:54:47.557584Z","caller":"traceutil/trace.go:172","msg":"trace[1483287589] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"522.930248ms","start":"2026-04-17T00:54:47.034634Z","end":"2026-04-17T00:54:47.557564Z","steps":["trace[1483287589] 'process raft request' (duration: 513.291437ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:47.557675Z","caller":"traceutil/trace.go:172","msg":"trace[1017789557] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"103.239864ms","start":"2026-04-17T00:54:47.454412Z","end":"2026-04-17T00:54:47.557652Z","steps":["trace[1017789557] 'process raft request' (duration: 103.183984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:47.558444Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:47.034618Z","time spent":"523.558322ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:54:47.558472Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:47.034656Z","time spent":"523.12681ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:54:49.036233Z","caller":"traceutil/trace.go:172","msg":"trace[1828176326] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:677; }","duration":"204.087508ms","start":"2026-04-17T00:54:48.832114Z","end":"2026-04-17T00:54:49.036202Z","steps":["trace[1828176326] 'read index received' (duration: 204.074208ms)","trace[1828176326] 'applied index is now lower than readState.Index' (duration: 11.89µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:49.036558Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"204.413441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:49.036620Z","caller":"traceutil/trace.go:172","msg":"trace[153151948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"204.504251ms","start":"2026-04-17T00:54:48.832102Z","end":"2026-04-17T00:54:49.036606Z","steps":["trace[153151948] 'agreement among raft nodes before linearized reading' (duration: 204.28934ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:49.036693Z","caller":"traceutil/trace.go:172","msg":"trace[2044369932] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"254.894811ms","start":"2026-04-17T00:54:48.781769Z","end":"2026-04-17T00:54:49.036664Z","steps":["trace[2044369932] 'process raft request' (duration: 254.554399ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:49.463652Z","caller":"traceutil/trace.go:172","msg":"trace[1133554037] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:678; }","duration":"425.648808ms","start":"2026-04-17T00:54:49.037982Z","end":"2026-04-17T00:54:49.463631Z","steps":["trace[1133554037] 'read index received' (duration: 425.641378ms)","trace[1133554037] 'applied index is now lower than readState.Index' (duration: 6.11µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:49.463758Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"425.760478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:54:49.463779Z","caller":"traceutil/trace.go:172","msg":"trace[1717160132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"425.794959ms","start":"2026-04-17T00:54:49.037978Z","end":"2026-04-17T00:54:49.463773Z","steps":["trace[1717160132] 'agreement among raft nodes before linearized reading' (duration: 425.729638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:49.463799Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:49.037954Z","time spent":"425.839889ms","remote":"127.0.0.1:47300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T00:54:49.463949Z","caller":"traceutil/trace.go:172","msg":"trace[1114761931] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"668.783452ms","start":"2026-04-17T00:54:48.795138Z","end":"2026-04-17T00:54:49.463921Z","steps":["trace[1114761931] 'process raft request' (duration: 668.61619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:49.464096Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:48.795113Z","time spent":"668.908702ms","remote":"127.0.0.1:47574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19646,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:54:53.034974Z","caller":"traceutil/trace.go:172","msg":"trace[886697908] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"196.300324ms","start":"2026-04-17T00:54:52.838635Z","end":"2026-04-17T00:54:53.034935Z","steps":["trace[886697908] 'process raft request' (duration: 196.090193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:53.483533Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"260.767167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:54:53.483629Z","caller":"traceutil/trace.go:172","msg":"trace[1120908576] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"249.93596ms","start":"2026-04-17T00:54:53.233679Z","end":"2026-04-17T00:54:53.483614Z","steps":["trace[1120908576] 'read index received' (duration: 69.121µs)","trace[1120908576] 'applied index is now lower than readState.Index' (duration: 249.865899ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:54:53.483723Z","caller":"traceutil/trace.go:172","msg":"trace[855573001] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"434.282982ms","start":"2026-04-17T00:54:53.049421Z","end":"2026-04-17T00:54:53.483704Z","steps":["trace[855573001] 'process raft request' (duration: 173.267814ms)","trace[855573001] 'compare' (duration: 260.608066ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:53.483793Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:54:53.049401Z","time spent":"434.363523ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:54:53.483961Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"250.252722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" limit:1 ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-17T00:54:53.484051Z","caller":"traceutil/trace.go:172","msg":"trace[1908786901] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:715; }","duration":"250.358443ms","start":"2026-04-17T00:54:53.233672Z","end":"2026-04-17T00:54:53.484030Z","steps":["trace[1908786901] 'agreement among raft nodes before linearized reading' (duration: 250.070391ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:53.487484Z","caller":"traceutil/trace.go:172","msg":"trace[785990665] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"126.47151ms","start":"2026-04-17T00:54:53.360986Z","end":"2026-04-17T00:54:53.487458Z","steps":["trace[785990665] 'process raft request' (duration: 126.314409ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:53.728359Z","caller":"traceutil/trace.go:172","msg":"trace[1836730624] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:744; }","duration":"133.633201ms","start":"2026-04-17T00:54:53.594696Z","end":"2026-04-17T00:54:53.728329Z","steps":["trace[1836730624] 'read index received' (duration: 133.617631ms)","trace[1836730624] 'applied index is now lower than readState.Index' (duration: 13.34µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:53.753442Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"163.362093ms","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-04-17T00:54:53.753526Z","caller":"traceutil/trace.go:172","msg":"trace[2114907413] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:716; }","duration":"163.463224ms","start":"2026-04-17T00:54:53.590044Z","end":"2026-04-17T00:54:53.753508Z","steps":["trace[2114907413] 'range keys from in-memory index tree' (duration: 163.314383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:53.753703Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"159.003962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-17T00:54:53.753737Z","caller":"traceutil/trace.go:172","msg":"trace[1605111549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:716; }","duration":"159.037202ms","start":"2026-04-17T00:54:53.594689Z","end":"2026-04-17T00:54:53.753726Z","steps":["trace[1605111549] 'agreement among raft nodes before linearized reading' (duration: 133.751072ms)","trace[1605111549] 'range keys from in-memory index tree' (duration: 25.156689ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:54:53.753935Z","caller":"traceutil/trace.go:172","msg":"trace[1507271951] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"253.097011ms","start":"2026-04-17T00:54:53.500812Z","end":"2026-04-17T00:54:53.753909Z","steps":["trace[1507271951] 'process raft request' (duration: 227.63122ms)","trace[1507271951] 'compare' (duration: 25.189009ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:54:53.829991Z","caller":"traceutil/trace.go:172","msg":"trace[744577269] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:745; }","duration":"101.522933ms","start":"2026-04-17T00:54:53.728439Z","end":"2026-04-17T00:54:53.829962Z","steps":["trace[744577269] 'read index received' (duration: 101.507673ms)","trace[744577269] 'applied index is now lower than readState.Index' (duration: 13.36µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:54:53.830250Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"230.713033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-17T00:54:53.830249Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"197.506406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" limit:1 ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-04-17T00:54:53.830289Z","caller":"traceutil/trace.go:172","msg":"trace[50516005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:717; }","duration":"230.774393ms","start":"2026-04-17T00:54:53.599505Z","end":"2026-04-17T00:54:53.830279Z","steps":["trace[50516005] 'agreement among raft nodes before linearized reading' (duration: 230.587592ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:53.830326Z","caller":"traceutil/trace.go:172","msg":"trace[1912765211] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:717; }","duration":"197.603717ms","start":"2026-04-17T00:54:53.632705Z","end":"2026-04-17T00:54:53.830308Z","steps":["trace[1912765211] 'agreement among raft nodes before linearized reading' (duration: 197.340045ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:53.830314Z","caller":"traceutil/trace.go:172","msg":"trace[1555501467] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"254.700173ms","start":"2026-04-17T00:54:53.575557Z","end":"2026-04-17T00:54:53.830258Z","steps":["trace[1555501467] 'process raft request' (duration: 254.472052ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:53.830422Z","caller":"traceutil/trace.go:172","msg":"trace[2108299954] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"166.004402ms","start":"2026-04-17T00:54:53.664389Z","end":"2026-04-17T00:54:53.830394Z","steps":["trace[2108299954] 'process raft request' (duration: 165.854461ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:53.830492Z","caller":"traceutil/trace.go:172","msg":"trace[720218171] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"248.745922ms","start":"2026-04-17T00:54:53.581725Z","end":"2026-04-17T00:54:53.830471Z","steps":["trace[720218171] 'process raft request' (duration: 248.44135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:54.110993Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"219.344757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:54:54.111349Z","caller":"traceutil/trace.go:172","msg":"trace[262015699] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"275.471417ms","start":"2026-04-17T00:54:53.835837Z","end":"2026-04-17T00:54:54.111308Z","steps":["trace[262015699] 'process raft request' (duration: 55.709387ms)","trace[262015699] 'compare' (duration: 219.239596ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:54:54.111751Z","caller":"traceutil/trace.go:172","msg":"trace[120292239] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"246.102748ms","start":"2026-04-17T00:54:53.865630Z","end":"2026-04-17T00:54:54.111733Z","steps":["trace[120292239] 'process raft request' (duration: 245.718406ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:54.287291Z","caller":"traceutil/trace.go:172","msg":"trace[1304239911] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"104.419959ms","start":"2026-04-17T00:54:54.182857Z","end":"2026-04-17T00:54:54.287277Z","steps":["trace[1304239911] 'process raft request' (duration: 104.240287ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:54:54.386673Z","caller":"traceutil/trace.go:172","msg":"trace[978713906] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"107.585532ms","start":"2026-04-17T00:54:54.279005Z","end":"2026-04-17T00:54:54.386590Z","steps":["trace[978713906] 'process raft request' (duration: 107.455501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:54:54.657621Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48854","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:55.420443Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48874","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:55.433335Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48880","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:55.532549Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48908","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:55.559744Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48914","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:55.723891Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:48928","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:54:57.430995Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39332","server-name":"","error":"EOF"} {"level":"info","ts":"2026-04-17T00:55:02.420316Z","caller":"traceutil/trace.go:172","msg":"trace[1949413593] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"218.226933ms","start":"2026-04-17T00:55:02.202065Z","end":"2026-04-17T00:55:02.420292Z","steps":["trace[1949413593] 'process raft request' (duration: 218.036961ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:04.286593Z","caller":"traceutil/trace.go:172","msg":"trace[2028178358] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"379.517618ms","start":"2026-04-17T00:55:03.907053Z","end":"2026-04-17T00:55:04.286570Z","steps":["trace[2028178358] 'process raft request' (duration: 379.362437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:04.286736Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:03.907032Z","time spent":"379.632548ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":425,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:55:05.294160Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39352","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:05.320215Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39376","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:05.332937Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39396","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:05.344310Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39400","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:06.611929Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095253638729765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:55:06.945849Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.062423884s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T00:55:06.946111Z","caller":"traceutil/trace.go:172","msg":"trace[726068202] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:835; }","duration":"834.294208ms","start":"2026-04-17T00:55:06.111792Z","end":"2026-04-17T00:55:06.946086Z","steps":["trace[726068202] 'read index received' (duration: 834.285958ms)","trace[726068202] 'applied index is now lower than readState.Index' (duration: 7.17µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:55:06.946496Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"834.630901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:55:06.946519Z","caller":"traceutil/trace.go:172","msg":"trace[1871877250] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"1.063219461s","start":"2026-04-17T00:55:05.883285Z","end":"2026-04-17T00:55:06.946505Z","steps":["trace[1871877250] 'process raft request' (duration: 1.063007839s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:06.946800Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:05.883257Z","time spent":"1.063423482s","remote":"127.0.0.1:47674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2879,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:55:06.946682Z","caller":"traceutil/trace.go:172","msg":"trace[474432528] range","detail":"{range_begin:/registry/cronjobs; range_end:; response_count:0; response_revision:803; }","duration":"834.780502ms","start":"2026-04-17T00:55:06.111777Z","end":"2026-04-17T00:55:06.946557Z","steps":["trace[474432528] 'agreement among raft nodes before linearized reading' (duration: 834.52054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:06.947502Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:06.111755Z","time spent":"835.689307ms","remote":"127.0.0.1:47684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":22,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:55:06.952133Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"680.641793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:55:06.952171Z","caller":"traceutil/trace.go:172","msg":"trace[1289680321] range","detail":"{range_begin:/registry/namespaces; range_end:; response_count:0; response_revision:804; }","duration":"680.683413ms","start":"2026-04-17T00:55:06.271479Z","end":"2026-04-17T00:55:06.952162Z","steps":["trace[1289680321] 'agreement among raft nodes before linearized reading' (duration: 680.613973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:06.952192Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:06.271461Z","time spent":"680.725903ms","remote":"127.0.0.1:47466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":24,"response count":0,"response size":29,"request content":"key:\"/registry/namespaces\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:55:06.952330Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"580.132072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingadmissionpolicybindings\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:55:06.952354Z","caller":"traceutil/trace.go:172","msg":"trace[1430188726] range","detail":"{range_begin:/registry/validatingadmissionpolicybindings; range_end:; response_count:0; response_revision:804; }","duration":"580.154612ms","start":"2026-04-17T00:55:06.372192Z","end":"2026-04-17T00:55:06.952347Z","steps":["trace[1430188726] 'agreement among raft nodes before linearized reading' (duration: 580.115822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:06.952371Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:06.372180Z","time spent":"580.185532ms","remote":"127.0.0.1:48276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/validatingadmissionpolicybindings\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:55:06.952362Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"569.465461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" limit:1 ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-17T00:55:06.952461Z","caller":"traceutil/trace.go:172","msg":"trace[2017535873] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:804; }","duration":"569.580742ms","start":"2026-04-17T00:55:06.382862Z","end":"2026-04-17T00:55:06.952443Z","steps":["trace[2017535873] 'agreement among raft nodes before linearized reading' (duration: 569.28901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:06.952494Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"595.866007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:55:06.952515Z","caller":"traceutil/trace.go:172","msg":"trace[948974101] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools; range_end:; response_count:0; response_revision:804; }","duration":"595.895717ms","start":"2026-04-17T00:55:06.356612Z","end":"2026-04-17T00:55:06.952508Z","steps":["trace[948974101] 'agreement among raft nodes before linearized reading' (duration: 595.848287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:06.952511Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:06.382847Z","time spent":"569.653902ms","remote":"127.0.0.1:47342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.89\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:55:06.952589Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"609.668209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:55:06.952613Z","caller":"traceutil/trace.go:172","msg":"trace[1822796718] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies; range_end:; response_count:0; response_revision:804; }","duration":"609.690289ms","start":"2026-04-17T00:55:06.342917Z","end":"2026-04-17T00:55:06.952607Z","steps":["trace[1822796718] 'agreement among raft nodes before linearized reading' (duration: 609.622898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:06.952631Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:06.342901Z","time spent":"609.726449ms","remote":"127.0.0.1:39384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:55:06.952531Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:06.356598Z","time spent":"595.928548ms","remote":"127.0.0.1:39410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:55:06.952755Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"662.981325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-04-17T00:55:06.952788Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"455.245449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T00:55:06.952811Z","caller":"traceutil/trace.go:172","msg":"trace[1679630873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"663.038655ms","start":"2026-04-17T00:55:06.289757Z","end":"2026-04-17T00:55:06.952795Z","steps":["trace[1679630873] 'agreement among raft nodes before linearized reading' (duration: 662.902544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:06.952827Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"362.909152ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:55:06.952856Z","caller":"traceutil/trace.go:172","msg":"trace[1800291987] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:804; }","duration":"362.937752ms","start":"2026-04-17T00:55:06.589912Z","end":"2026-04-17T00:55:06.952850Z","steps":["trace[1800291987] 'agreement among raft nodes before linearized reading' (duration: 362.900142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:06.952858Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"620.666842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:55:06.952864Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:06.289741Z","time spent":"663.110505ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-04-17T00:55:06.952880Z","caller":"traceutil/trace.go:172","msg":"trace[326661907] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads; range_end:; response_count:0; response_revision:804; }","duration":"620.688392ms","start":"2026-04-17T00:55:06.332187Z","end":"2026-04-17T00:55:06.952875Z","steps":["trace[326661907] 'agreement among raft nodes before linearized reading' (duration: 620.652932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:06.952912Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:06.332172Z","time spent":"620.734383ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:55:06.952960Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"644.686933ms","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-04-17T00:55:06.953005Z","caller":"traceutil/trace.go:172","msg":"trace[1340708547] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs; range_end:; response_count:0; response_revision:804; }","duration":"644.740263ms","start":"2026-04-17T00:55:06.308255Z","end":"2026-04-17T00:55:06.952996Z","steps":["trace[1340708547] 'agreement among raft nodes before linearized reading' (duration: 644.663423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:06.953037Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:06.308233Z","time spent":"644.797443ms","remote":"127.0.0.1:39366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs\" limit:1 "} {"level":"info","ts":"2026-04-17T00:55:06.952820Z","caller":"traceutil/trace.go:172","msg":"trace[1188692279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:804; }","duration":"455.280439ms","start":"2026-04-17T00:55:06.497531Z","end":"2026-04-17T00:55:06.952812Z","steps":["trace[1188692279] 'agreement among raft nodes before linearized reading' (duration: 455.145758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:06.953099Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:06.497516Z","time spent":"455.571512ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:55:06.953306Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"341.086837ms","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-04-17T00:55:06.953401Z","caller":"traceutil/trace.go:172","msg":"trace[1677497319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"341.181557ms","start":"2026-04-17T00:55:06.612166Z","end":"2026-04-17T00:55:06.953348Z","steps":["trace[1677497319] 'agreement among raft nodes before linearized reading' (duration: 340.361701ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:06.953460Z","caller":"traceutil/trace.go:172","msg":"trace[768460267] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"1.04682715s","start":"2026-04-17T00:55:05.906622Z","end":"2026-04-17T00:55:06.953449Z","steps":["trace[768460267] 'process raft request' (duration: 1.046065554s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:06.953538Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:05.906597Z","time spent":"1.0468838s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:55:06.953628Z","caller":"traceutil/trace.go:172","msg":"trace[1108327277] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"1.024257969s","start":"2026-04-17T00:55:05.929359Z","end":"2026-04-17T00:55:06.953617Z","steps":["trace[1108327277] 'process raft request' (duration: 1.024030658s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:06.953536Z","caller":"traceutil/trace.go:172","msg":"trace[805909671] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"698.1282ms","start":"2026-04-17T00:55:06.255396Z","end":"2026-04-17T00:55:06.953524Z","steps":["trace[805909671] 'process raft request' (duration: 698.08321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:06.953698Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:06.255372Z","time spent":"698.303501ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":674,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:55:06.953710Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:05.929337Z","time spent":"1.024324569s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:55:06.953807Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:06.612148Z","time spent":"341.276848ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 "} {"level":"info","ts":"2026-04-17T00:55:10.939454Z","caller":"traceutil/trace.go:172","msg":"trace[1551823089] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:882; }","duration":"366.919211ms","start":"2026-04-17T00:55:10.572498Z","end":"2026-04-17T00:55:10.939417Z","steps":["trace[1551823089] 'read index received' (duration: 366.910701ms)","trace[1551823089] 'applied index is now lower than readState.Index' (duration: 6.14µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:55:10.939707Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"367.194982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:55:10.939771Z","caller":"traceutil/trace.go:172","msg":"trace[451492817] range","detail":"{range_begin:/registry/ingressclasses; range_end:; response_count:0; response_revision:849; }","duration":"367.282123ms","start":"2026-04-17T00:55:10.572475Z","end":"2026-04-17T00:55:10.939757Z","steps":["trace[451492817] 'agreement among raft nodes before linearized reading' (duration: 367.054721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:10.939810Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:10.572449Z","time spent":"367.352193ms","remote":"127.0.0.1:47794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses\" limit:1 "} {"level":"info","ts":"2026-04-17T00:55:10.942552Z","caller":"traceutil/trace.go:172","msg":"trace[1924683951] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"372.098395ms","start":"2026-04-17T00:55:10.570432Z","end":"2026-04-17T00:55:10.942530Z","steps":["trace[1924683951] 'process raft request' (duration: 370.304274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:10.942565Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"352.412306ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:55:10.942647Z","caller":"traceutil/trace.go:172","msg":"trace[252426714] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:850; }","duration":"352.508527ms","start":"2026-04-17T00:55:10.590117Z","end":"2026-04-17T00:55:10.942626Z","steps":["trace[252426714] 'agreement among raft nodes before linearized reading' (duration: 352.195345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:10.942710Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:10.570398Z","time spent":"372.205746ms","remote":"127.0.0.1:47434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036290,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:55:11.128930Z","caller":"traceutil/trace.go:172","msg":"trace[49936122] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:883; }","duration":"152.623739ms","start":"2026-04-17T00:55:10.976276Z","end":"2026-04-17T00:55:11.128900Z","steps":["trace[49936122] 'read index received' (duration: 152.609669ms)","trace[49936122] 'applied index is now lower than readState.Index' (duration: 11.71µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:55:11.129345Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"153.023611ms","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-04-17T00:55:11.129651Z","caller":"traceutil/trace.go:172","msg":"trace[1673027212] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:850; }","duration":"153.360153ms","start":"2026-04-17T00:55:10.976269Z","end":"2026-04-17T00:55:11.129629Z","steps":["trace[1673027212] 'agreement among raft nodes before linearized reading' (duration: 152.82776ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:11.129631Z","caller":"traceutil/trace.go:172","msg":"trace[1732702728] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"166.775432ms","start":"2026-04-17T00:55:10.962814Z","end":"2026-04-17T00:55:11.129589Z","steps":["trace[1732702728] 'process raft request' (duration: 166.582991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:11.132362Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"154.575002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-17T00:55:11.132422Z","caller":"traceutil/trace.go:172","msg":"trace[1590487459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:851; }","duration":"154.646632ms","start":"2026-04-17T00:55:10.977761Z","end":"2026-04-17T00:55:11.132408Z","steps":["trace[1590487459] 'agreement among raft nodes before linearized reading' (duration: 154.423341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:11.132725Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"153.724466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T00:55:11.132802Z","caller":"traceutil/trace.go:172","msg":"trace[1610977833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:851; }","duration":"153.816737ms","start":"2026-04-17T00:55:10.978968Z","end":"2026-04-17T00:55:11.132785Z","steps":["trace[1610977833] 'agreement among raft nodes before linearized reading' (duration: 153.569195ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:11.132801Z","caller":"traceutil/trace.go:172","msg":"trace[1953025133] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"162.216233ms","start":"2026-04-17T00:55:10.970573Z","end":"2026-04-17T00:55:11.132789Z","steps":["trace[1953025133] 'process raft request' (duration: 162.142992ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:12.019944Z","caller":"traceutil/trace.go:172","msg":"trace[1669262712] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:895; }","duration":"161.273415ms","start":"2026-04-17T00:55:11.858642Z","end":"2026-04-17T00:55:12.019915Z","steps":["trace[1669262712] 'read index received' (duration: 161.260175ms)","trace[1669262712] 'applied index is now lower than readState.Index' (duration: 11.08µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:55:12.056738Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"198.063686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:55:12.056949Z","caller":"traceutil/trace.go:172","msg":"trace[1856965043] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"218.244598ms","start":"2026-04-17T00:55:11.838679Z","end":"2026-04-17T00:55:12.056923Z","steps":["trace[1856965043] 'process raft request' (duration: 181.517099ms)","trace[1856965043] 'compare' (duration: 36.440178ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:55:12.057009Z","caller":"traceutil/trace.go:172","msg":"trace[2093779069] range","detail":"{range_begin:/registry/serviceaccounts; range_end:; response_count:0; response_revision:861; }","duration":"198.287457ms","start":"2026-04-17T00:55:11.858637Z","end":"2026-04-17T00:55:12.056924Z","steps":["trace[2093779069] 'agreement among raft nodes before linearized reading' (duration: 161.401336ms)","trace[2093779069] 'range keys from in-memory index tree' (duration: 36.60609ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:55:12.168748Z","caller":"traceutil/trace.go:172","msg":"trace[1091071630] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:896; }","duration":"148.64078ms","start":"2026-04-17T00:55:12.020082Z","end":"2026-04-17T00:55:12.168723Z","steps":["trace[1091071630] 'read index received' (duration: 148.6296ms)","trace[1091071630] 'applied index is now lower than readState.Index' (duration: 8.99µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:55:12.255482Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"359.769299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-86c8f9f5d8-7d25h\" limit:1 ","response":"range_response_count:1 size:5968"} {"level":"info","ts":"2026-04-17T00:55:12.255553Z","caller":"traceutil/trace.go:172","msg":"trace[1207356593] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-86c8f9f5d8-7d25h; range_end:; response_count:1; response_revision:862; }","duration":"359.85787ms","start":"2026-04-17T00:55:11.895679Z","end":"2026-04-17T00:55:12.255537Z","steps":["trace[1207356593] 'agreement among raft nodes before linearized reading' (duration: 273.121164ms)","trace[1207356593] 'range keys from in-memory index tree' (duration: 86.475705ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:55:12.255584Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:11.895662Z","time spent":"359.91521ms","remote":"127.0.0.1:47574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":5992,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-86c8f9f5d8-7d25h\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:55:12.255574Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"274.638902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:55:12.255677Z","caller":"traceutil/trace.go:172","msg":"trace[127501350] range","detail":"{range_begin:/registry/replicasets; range_end:; response_count:0; response_revision:862; }","duration":"274.835664ms","start":"2026-04-17T00:55:11.980819Z","end":"2026-04-17T00:55:12.255654Z","steps":["trace[127501350] 'agreement among raft nodes before linearized reading' (duration: 187.976997ms)","trace[127501350] 'range keys from in-memory index tree' (duration: 86.614085ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:55:12.255985Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"194.473098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:55:12.256011Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"119.435639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/eg\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:55:12.256078Z","caller":"traceutil/trace.go:172","msg":"trace[1889912529] range","detail":"{range_begin:/registry/configmaps; range_end:; response_count:0; response_revision:863; }","duration":"194.911581ms","start":"2026-04-17T00:55:12.061149Z","end":"2026-04-17T00:55:12.256060Z","steps":["trace[1889912529] 'agreement among raft nodes before linearized reading' (duration: 194.430528ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:12.256040Z","caller":"traceutil/trace.go:172","msg":"trace[1635959437] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"417.181986ms","start":"2026-04-17T00:55:11.838676Z","end":"2026-04-17T00:55:12.255858Z","steps":["trace[1635959437] 'process raft request' (duration: 330.118798ms)","trace[1635959437] 'compare' (duration: 86.579365ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:55:12.256739Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:11.838650Z","time spent":"418.016061ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":902,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:55:12.256108Z","caller":"traceutil/trace.go:172","msg":"trace[2087863746] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/eg; range_end:; response_count:0; response_revision:863; }","duration":"119.5506ms","start":"2026-04-17T00:55:12.136534Z","end":"2026-04-17T00:55:12.256085Z","steps":["trace[2087863746] 'agreement among raft nodes before linearized reading' (duration: 119.384819ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:13.824714Z","caller":"traceutil/trace.go:172","msg":"trace[1402702109] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"159.255001ms","start":"2026-04-17T00:55:13.665436Z","end":"2026-04-17T00:55:13.824691Z","steps":["trace[1402702109] 'process raft request' (duration: 159.064486ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:21.499314Z","caller":"traceutil/trace.go:172","msg":"trace[1974663846] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:930; }","duration":"308.551943ms","start":"2026-04-17T00:55:21.190735Z","end":"2026-04-17T00:55:21.499287Z","steps":["trace[1974663846] 'read index received' (duration: 308.538923ms)","trace[1974663846] 'applied index is now lower than readState.Index' (duration: 11.73µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:55:21.499670Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"308.90977ms","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-04-17T00:55:21.499706Z","caller":"traceutil/trace.go:172","msg":"trace[1953454872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:895; }","duration":"308.969071ms","start":"2026-04-17T00:55:21.190729Z","end":"2026-04-17T00:55:21.499698Z","steps":["trace[1953454872] 'agreement among raft nodes before linearized reading' (duration: 308.714436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:21.499747Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:21.190706Z","time spent":"309.030893ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 "} {"level":"info","ts":"2026-04-17T00:55:21.499789Z","caller":"traceutil/trace.go:172","msg":"trace[926612654] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"324.833078ms","start":"2026-04-17T00:55:21.174931Z","end":"2026-04-17T00:55:21.499764Z","steps":["trace[926612654] 'process raft request' (duration: 324.677735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:21.499946Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:21.174906Z","time spent":"324.964651ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:55:21.505225Z","caller":"traceutil/trace.go:172","msg":"trace[46040991] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"330.075227ms","start":"2026-04-17T00:55:21.175132Z","end":"2026-04-17T00:55:21.505207Z","steps":["trace[46040991] 'process raft request' (duration: 329.962685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:21.505294Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"305.638922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-17T00:55:21.505357Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:21.175095Z","time spent":"330.209379ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:55:21.505392Z","caller":"traceutil/trace.go:172","msg":"trace[355107585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"305.753364ms","start":"2026-04-17T00:55:21.199618Z","end":"2026-04-17T00:55:21.505371Z","steps":["trace[355107585] 'agreement among raft nodes before linearized reading' (duration: 305.464188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:21.505452Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:21.199597Z","time spent":"305.841886ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:55:21.505237Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"309.815628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T00:55:21.505931Z","caller":"traceutil/trace.go:172","msg":"trace[882080658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"310.533173ms","start":"2026-04-17T00:55:21.195388Z","end":"2026-04-17T00:55:21.505921Z","steps":["trace[882080658] 'agreement among raft nodes before linearized reading' (duration: 309.681586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:21.505973Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:21.195379Z","time spent":"310.583554ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-04-17T00:55:22.397037Z","caller":"traceutil/trace.go:172","msg":"trace[1577169604] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"298.383263ms","start":"2026-04-17T00:55:22.098632Z","end":"2026-04-17T00:55:22.397016Z","steps":["trace[1577169604] 'process raft request' (duration: 298.2257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:22.544646Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"101.555093ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-17T00:55:29.688599Z","caller":"traceutil/trace.go:172","msg":"trace[857093258] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:964; }","duration":"136.16948ms","start":"2026-04-17T00:55:29.552408Z","end":"2026-04-17T00:55:29.688578Z","steps":["trace[857093258] 'read index received' (duration: 136.1614ms)","trace[857093258] 'applied index is now lower than readState.Index' (duration: 6.87µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:55:29.688777Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"136.365593ms","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-04-17T00:55:29.688807Z","caller":"traceutil/trace.go:172","msg":"trace[628626164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"136.408714ms","start":"2026-04-17T00:55:29.552388Z","end":"2026-04-17T00:55:29.688797Z","steps":["trace[628626164] 'agreement among raft nodes before linearized reading' (duration: 136.286802ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:29.688814Z","caller":"traceutil/trace.go:172","msg":"trace[388198446] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"157.360194ms","start":"2026-04-17T00:55:29.531439Z","end":"2026-04-17T00:55:29.688799Z","steps":["trace[388198446] 'process raft request' (duration: 157.240512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:29.694405Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"104.642157ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:55:29.694613Z","caller":"traceutil/trace.go:172","msg":"trace[464924350] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:928; }","duration":"104.860471ms","start":"2026-04-17T00:55:29.589733Z","end":"2026-04-17T00:55:29.694593Z","steps":["trace[464924350] 'agreement among raft nodes before linearized reading' (duration: 104.594626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:29.694731Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"130.712615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T00:55:29.694787Z","caller":"traceutil/trace.go:172","msg":"trace[1393350893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:928; }","duration":"130.769866ms","start":"2026-04-17T00:55:29.564003Z","end":"2026-04-17T00:55:29.694773Z","steps":["trace[1393350893] 'agreement among raft nodes before linearized reading' (duration: 130.309087ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:29.694610Z","caller":"traceutil/trace.go:172","msg":"trace[1417607671] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"163.119193ms","start":"2026-04-17T00:55:29.531471Z","end":"2026-04-17T00:55:29.694590Z","steps":["trace[1417607671] 'process raft request' (duration: 162.988691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:29.694478Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"129.970111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-17T00:55:29.695302Z","caller":"traceutil/trace.go:172","msg":"trace[721069556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:928; }","duration":"130.535141ms","start":"2026-04-17T00:55:29.564489Z","end":"2026-04-17T00:55:29.695024Z","steps":["trace[721069556] 'agreement among raft nodes before linearized reading' (duration: 129.90292ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:30.558632Z","caller":"traceutil/trace.go:172","msg":"trace[1161112650] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"115.412152ms","start":"2026-04-17T00:55:30.443197Z","end":"2026-04-17T00:55:30.558610Z","steps":["trace[1161112650] 'process raft request' (duration: 115.235078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:31.889995Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"221.718084ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-17T00:55:31.890169Z","caller":"traceutil/trace.go:172","msg":"trace[195852262] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"186.129077ms","start":"2026-04-17T00:55:31.704028Z","end":"2026-04-17T00:55:31.890157Z","steps":["trace[195852262] 'read index received' (duration: 96.674281ms)","trace[195852262] 'applied index is now lower than readState.Index' (duration: 89.454026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:55:31.890497Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"186.448082ms","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-04-17T00:55:31.890588Z","caller":"traceutil/trace.go:172","msg":"trace[1994880210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"186.556304ms","start":"2026-04-17T00:55:31.704015Z","end":"2026-04-17T00:55:31.890572Z","steps":["trace[1994880210] 'agreement among raft nodes before linearized reading' (duration: 186.218058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:31.890840Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"162.679708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T00:55:31.890898Z","caller":"traceutil/trace.go:172","msg":"trace[678776904] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"195.268038ms","start":"2026-04-17T00:55:31.695616Z","end":"2026-04-17T00:55:31.890884Z","steps":["trace[678776904] 'process raft request' (duration: 194.466943ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:31.890925Z","caller":"traceutil/trace.go:172","msg":"trace[1263385006] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"190.447838ms","start":"2026-04-17T00:55:31.700473Z","end":"2026-04-17T00:55:31.890920Z","steps":["trace[1263385006] 'process raft request' (duration: 190.206682ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:31.890899Z","caller":"traceutil/trace.go:172","msg":"trace[2413036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:936; }","duration":"162.747889ms","start":"2026-04-17T00:55:31.728141Z","end":"2026-04-17T00:55:31.890889Z","steps":["trace[2413036] 'agreement among raft nodes before linearized reading' (duration: 162.616326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:31.891294Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"163.052025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-17T00:55:31.891446Z","caller":"traceutil/trace.go:172","msg":"trace[16783350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:936; }","duration":"163.200967ms","start":"2026-04-17T00:55:31.728196Z","end":"2026-04-17T00:55:31.891397Z","steps":["trace[16783350] 'agreement among raft nodes before linearized reading' (duration: 162.839211ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:32.974131Z","caller":"traceutil/trace.go:172","msg":"trace[1207238314] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:977; }","duration":"383.866623ms","start":"2026-04-17T00:55:32.590202Z","end":"2026-04-17T00:55:32.974068Z","steps":["trace[1207238314] 'read index received' (duration: 383.852022ms)","trace[1207238314] 'applied index is now lower than readState.Index' (duration: 12.881µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:55:32.974256Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"384.010635ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:55:32.974303Z","caller":"traceutil/trace.go:172","msg":"trace[99964436] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"411.346581ms","start":"2026-04-17T00:55:32.562934Z","end":"2026-04-17T00:55:32.974280Z","steps":["trace[99964436] 'process raft request' (duration: 411.160828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:32.974403Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"144.147855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:55:32.974458Z","caller":"traceutil/trace.go:172","msg":"trace[2038290506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"144.244176ms","start":"2026-04-17T00:55:32.830198Z","end":"2026-04-17T00:55:32.974442Z","steps":["trace[2038290506] 'agreement among raft nodes before linearized reading' (duration: 144.085523ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:32.974334Z","caller":"traceutil/trace.go:172","msg":"trace[2001619228] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:939; }","duration":"384.123137ms","start":"2026-04-17T00:55:32.590195Z","end":"2026-04-17T00:55:32.974318Z","steps":["trace[2001619228] 'agreement among raft nodes before linearized reading' (duration: 383.975474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:32.974505Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:32.562907Z","time spent":"411.522885ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:55:55.317886Z","caller":"traceutil/trace.go:172","msg":"trace[1141830167] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"130.47301ms","start":"2026-04-17T00:55:55.187397Z","end":"2026-04-17T00:55:55.317870Z","steps":["trace[1141830167] 'process raft request' (duration: 130.390007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:58.128632Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"111.333448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:55:58.128820Z","caller":"traceutil/trace.go:172","msg":"trace[2016629276] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"113.715642ms","start":"2026-04-17T00:55:58.015089Z","end":"2026-04-17T00:55:58.128805Z","steps":["trace[2016629276] 'process raft request' (duration: 113.642841ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:55:58.129186Z","caller":"traceutil/trace.go:172","msg":"trace[425292790] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"136.360931ms","start":"2026-04-17T00:55:57.992801Z","end":"2026-04-17T00:55:58.129162Z","steps":["trace[425292790] 'process raft request' (duration: 24.227502ms)","trace[425292790] 'compare' (duration: 111.200456ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:55:58.529491Z","caller":"traceutil/trace.go:172","msg":"trace[118652677] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"394.094667ms","start":"2026-04-17T00:55:58.135380Z","end":"2026-04-17T00:55:58.529475Z","steps":["trace[118652677] 'process raft request' (duration: 394.051156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:58.529605Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:58.135354Z","time spent":"394.195259ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:55:58.529620Z","caller":"traceutil/trace.go:172","msg":"trace[1549179602] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"394.25428ms","start":"2026-04-17T00:55:58.135299Z","end":"2026-04-17T00:55:58.529554Z","steps":["trace[1549179602] 'process raft request' (duration: 394.014747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:58.529928Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:58.135285Z","time spent":"394.548095ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":424,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:55:58.725903Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"137.473907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:55:58.725969Z","caller":"traceutil/trace.go:172","msg":"trace[1040398389] range","detail":"{range_begin:/registry/controllers; range_end:; response_count:0; response_revision:1030; }","duration":"137.558809ms","start":"2026-04-17T00:55:58.588397Z","end":"2026-04-17T00:55:58.725956Z","steps":["trace[1040398389] 'range keys from in-memory index tree' (duration: 137.394386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:58.726012Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"136.109237ms","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-04-17T00:55:58.726122Z","caller":"traceutil/trace.go:172","msg":"trace[1094747929] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1030; }","duration":"136.233699ms","start":"2026-04-17T00:55:58.589868Z","end":"2026-04-17T00:55:58.726102Z","steps":["trace[1094747929] 'range keys from in-memory index tree' (duration: 136.044676ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:06.683424Z","caller":"traceutil/trace.go:172","msg":"trace[2047798771] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"109.85366ms","start":"2026-04-17T00:56:06.573545Z","end":"2026-04-17T00:56:06.683399Z","steps":["trace[2047798771] 'process raft request' (duration: 109.658978ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:06.691016Z","caller":"traceutil/trace.go:172","msg":"trace[567472576] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"116.453073ms","start":"2026-04-17T00:56:06.574542Z","end":"2026-04-17T00:56:06.690995Z","steps":["trace[567472576] 'process raft request' (duration: 116.321562ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:14.967927Z","caller":"traceutil/trace.go:172","msg":"trace[564598757] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1126; }","duration":"199.761842ms","start":"2026-04-17T00:56:14.768134Z","end":"2026-04-17T00:56:14.967896Z","steps":["trace[564598757] 'read index received' (duration: 199.749652ms)","trace[564598757] 'applied index is now lower than readState.Index' (duration: 10.71µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:14.968229Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"200.074766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/eg\" limit:1 ","response":"range_response_count:1 size:1086"} {"level":"info","ts":"2026-04-17T00:56:14.968294Z","caller":"traceutil/trace.go:172","msg":"trace[1630779318] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/eg; range_end:; response_count:1; response_revision:1080; }","duration":"200.152197ms","start":"2026-04-17T00:56:14.768123Z","end":"2026-04-17T00:56:14.968275Z","steps":["trace[1630779318] 'agreement among raft nodes before linearized reading' (duration: 199.929574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:14.968549Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"136.264189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:14.968613Z","caller":"traceutil/trace.go:172","msg":"trace[558386547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1081; }","duration":"136.329491ms","start":"2026-04-17T00:56:14.832269Z","end":"2026-04-17T00:56:14.968599Z","steps":["trace[558386547] 'agreement among raft nodes before linearized reading' (duration: 136.234229ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:14.968580Z","caller":"traceutil/trace.go:172","msg":"trace[26514118] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"222.227979ms","start":"2026-04-17T00:56:14.746295Z","end":"2026-04-17T00:56:14.968523Z","steps":["trace[26514118] 'process raft request' (duration: 221.638521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:35.712285Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"135.299192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:914"} {"level":"warn","ts":"2026-04-17T00:56:35.712467Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"115.901205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:56:35.712536Z","caller":"traceutil/trace.go:172","msg":"trace[448399902] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1198; }","duration":"104.319879ms","start":"2026-04-17T00:56:35.608204Z","end":"2026-04-17T00:56:35.712524Z","steps":["trace[448399902] 'read index received' (duration: 43.72µs)","trace[448399902] 'applied index is now lower than readState.Index' (duration: 104.275379ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:56:35.712496Z","caller":"traceutil/trace.go:172","msg":"trace[86462268] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:1; response_revision:1148; }","duration":"135.514494ms","start":"2026-04-17T00:56:35.576956Z","end":"2026-04-17T00:56:35.712471Z","steps":["trace[86462268] 'agreement among raft nodes before linearized reading' (duration: 19.496388ms)","trace[86462268] 'range keys from in-memory index tree' (duration: 115.656623ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:35.712709Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"104.506421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler\" limit:1 ","response":"range_response_count:1 size:808"} {"level":"info","ts":"2026-04-17T00:56:35.712741Z","caller":"traceutil/trace.go:172","msg":"trace[504499497] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler; range_end:; response_count:1; response_revision:1149; }","duration":"104.539261ms","start":"2026-04-17T00:56:35.608193Z","end":"2026-04-17T00:56:35.712732Z","steps":["trace[504499497] 'agreement among raft nodes before linearized reading' (duration: 104.4355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:35.712316Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"121.738713ms","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-04-17T00:56:35.712613Z","caller":"traceutil/trace.go:172","msg":"trace[319806535] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"157.935316ms","start":"2026-04-17T00:56:35.554666Z","end":"2026-04-17T00:56:35.712601Z","steps":["trace[319806535] 'process raft request' (duration: 41.842319ms)","trace[319806535] 'compare' (duration: 115.721763ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:56:35.712892Z","caller":"traceutil/trace.go:172","msg":"trace[1099896679] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1148; }","duration":"122.28691ms","start":"2026-04-17T00:56:35.590561Z","end":"2026-04-17T00:56:35.712847Z","steps":["trace[1099896679] 'range keys from in-memory index tree' (duration: 121.702072ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:36.462250Z","caller":"traceutil/trace.go:172","msg":"trace[1390349184] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1199; }","duration":"103.702155ms","start":"2026-04-17T00:56:36.358520Z","end":"2026-04-17T00:56:36.462222Z","steps":["trace[1390349184] 'read index received' (duration: 103.692525ms)","trace[1390349184] 'applied index is now lower than readState.Index' (duration: 7.55µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:56:36.462432Z","caller":"traceutil/trace.go:172","msg":"trace[398298189] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"106.955534ms","start":"2026-04-17T00:56:36.355452Z","end":"2026-04-17T00:56:36.462408Z","steps":["trace[398298189] 'process raft request' (duration: 106.817953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:36.462533Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"103.994429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:kubeadm-certs\" limit:1 ","response":"range_response_count:1 size:339"} {"level":"info","ts":"2026-04-17T00:56:36.462570Z","caller":"traceutil/trace.go:172","msg":"trace[1368043053] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:kubeadm-certs; range_end:; response_count:1; response_revision:1150; }","duration":"104.04439ms","start":"2026-04-17T00:56:36.358515Z","end":"2026-04-17T00:56:36.462559Z","steps":["trace[1368043053] 'agreement among raft nodes before linearized reading' (duration: 103.834608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:36.634674Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"100.192134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-04-17T00:56:36.634725Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"165.656245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:kubelet-config\" limit:1 ","response":"range_response_count:1 size:345"} {"level":"info","ts":"2026-04-17T00:56:36.634807Z","caller":"traceutil/trace.go:172","msg":"trace[469226708] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:kubelet-config; range_end:; response_count:1; response_revision:1151; }","duration":"165.748166ms","start":"2026-04-17T00:56:36.469040Z","end":"2026-04-17T00:56:36.634788Z","steps":["trace[469226708] 'agreement among raft nodes before linearized reading' (duration: 65.264347ms)","trace[469226708] 'range keys from in-memory index tree' (duration: 100.109754ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:56:36.634836Z","caller":"traceutil/trace.go:172","msg":"trace[696358960] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"135.625466ms","start":"2026-04-17T00:56:36.499196Z","end":"2026-04-17T00:56:36.634821Z","steps":["trace[696358960] 'process raft request' (duration: 135.568105ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:36.634864Z","caller":"traceutil/trace.go:172","msg":"trace[42247255] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"165.298721ms","start":"2026-04-17T00:56:36.469543Z","end":"2026-04-17T00:56:36.634841Z","steps":["trace[42247255] 'process raft request' (duration: 64.861824ms)","trace[42247255] 'compare' (duration: 100.088753ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:36.901144Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"103.789786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:827"} {"level":"info","ts":"2026-04-17T00:56:36.901266Z","caller":"traceutil/trace.go:172","msg":"trace[2071050580] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:1; response_revision:1153; }","duration":"103.931378ms","start":"2026-04-17T00:56:36.797311Z","end":"2026-04-17T00:56:36.901243Z","steps":["trace[2071050580] 'range keys from in-memory index tree' (duration: 102.517292ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:37.044435Z","caller":"traceutil/trace.go:172","msg":"trace[1049931883] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1204; }","duration":"137.897222ms","start":"2026-04-17T00:56:36.906499Z","end":"2026-04-17T00:56:37.044397Z","steps":["trace[1049931883] 'read index received' (duration: 137.880832ms)","trace[1049931883] 'applied index is now lower than readState.Index' (duration: 14.76µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:37.044664Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"138.154134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:827"} {"level":"info","ts":"2026-04-17T00:56:37.044746Z","caller":"traceutil/trace.go:172","msg":"trace[454425569] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:1; response_revision:1153; }","duration":"138.252445ms","start":"2026-04-17T00:56:36.906471Z","end":"2026-04-17T00:56:37.044724Z","steps":["trace[454425569] 'agreement among raft nodes before linearized reading' (duration: 138.014263ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:37.046129Z","caller":"traceutil/trace.go:172","msg":"trace[759684526] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"140.797665ms","start":"2026-04-17T00:56:36.905292Z","end":"2026-04-17T00:56:37.046090Z","steps":["trace[759684526] 'process raft request' (duration: 140.079966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:38.014864Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095253638731544,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:56:38.024822Z","caller":"traceutil/trace.go:172","msg":"trace[1291071691] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1206; }","duration":"510.604003ms","start":"2026-04-17T00:56:37.514168Z","end":"2026-04-17T00:56:38.024772Z","steps":["trace[1291071691] 'read index received' (duration: 510.574223ms)","trace[1291071691] 'applied index is now lower than readState.Index' (duration: 27.25µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:56:38.024930Z","caller":"traceutil/trace.go:172","msg":"trace[44465859] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"521.761682ms","start":"2026-04-17T00:56:37.503149Z","end":"2026-04-17T00:56:38.024911Z","steps":["trace[44465859] 'process raft request' (duration: 521.61076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:38.025076Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:37.503128Z","time spent":"521.865783ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:56:38.025115Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"510.922557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-iblhvo\" limit:1 ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-04-17T00:56:38.025193Z","caller":"traceutil/trace.go:172","msg":"trace[593566905] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-iblhvo; range_end:; response_count:1; response_revision:1156; }","duration":"511.015268ms","start":"2026-04-17T00:56:37.514157Z","end":"2026-04-17T00:56:38.025172Z","steps":["trace[593566905] 'agreement among raft nodes before linearized reading' (duration: 510.723985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:38.025236Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:37.514135Z","time spent":"511.091858ms","remote":"127.0.0.1:47434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":564,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-iblhvo\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:38.025274Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"148.178912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:56:38.025283Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"192.791209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:38.025355Z","caller":"traceutil/trace.go:172","msg":"trace[595581795] range","detail":"{range_begin:/registry/replicasets; range_end:; response_count:0; response_revision:1156; }","duration":"148.276473ms","start":"2026-04-17T00:56:37.877060Z","end":"2026-04-17T00:56:38.025336Z","steps":["trace[595581795] 'agreement among raft nodes before linearized reading' (duration: 148.109042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:38.025359Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"490.382168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/kubeadm-certs\" limit:1 ","response":"range_response_count:1 size:11447"} {"level":"info","ts":"2026-04-17T00:56:38.025369Z","caller":"traceutil/trace.go:172","msg":"trace[499524681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1156; }","duration":"192.88458ms","start":"2026-04-17T00:56:37.832468Z","end":"2026-04-17T00:56:38.025352Z","steps":["trace[499524681] 'agreement among raft nodes before linearized reading' (duration: 192.745798ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:38.025407Z","caller":"traceutil/trace.go:172","msg":"trace[1250991184] range","detail":"{range_begin:/registry/secrets/kube-system/kubeadm-certs; range_end:; response_count:1; response_revision:1156; }","duration":"490.452459ms","start":"2026-04-17T00:56:37.534942Z","end":"2026-04-17T00:56:38.025394Z","steps":["trace[1250991184] 'agreement among raft nodes before linearized reading' (duration: 490.176476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:38.025445Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:37.534921Z","time spent":"490.515631ms","remote":"127.0.0.1:47434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11471,"request content":"key:\"/registry/secrets/kube-system/kubeadm-certs\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:38.100263Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"509.428674ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:38.100339Z","caller":"traceutil/trace.go:172","msg":"trace[2056037693] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1156; }","duration":"509.511665ms","start":"2026-04-17T00:56:37.590808Z","end":"2026-04-17T00:56:38.100320Z","steps":["trace[2056037693] 'agreement among raft nodes before linearized reading' (duration: 434.635814ms)","trace[2056037693] 'range keys from in-memory index tree' (duration: 74.7801ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:56:38.658033Z","caller":"traceutil/trace.go:172","msg":"trace[406161176] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1207; }","duration":"150.352999ms","start":"2026-04-17T00:56:38.507652Z","end":"2026-04-17T00:56:38.658005Z","steps":["trace[406161176] 'read index received' (duration: 150.343969ms)","trace[406161176] 'applied index is now lower than readState.Index' (duration: 7.13µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:56:38.658288Z","caller":"traceutil/trace.go:172","msg":"trace[239086795] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"191.947868ms","start":"2026-04-17T00:56:38.466316Z","end":"2026-04-17T00:56:38.658264Z","steps":["trace[239086795] 'process raft request' (duration: 191.765506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:38.658303Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"150.628642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" limit:1 ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-17T00:56:38.658521Z","caller":"traceutil/trace.go:172","msg":"trace[1637221730] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:1156; }","duration":"150.850124ms","start":"2026-04-17T00:56:38.507642Z","end":"2026-04-17T00:56:38.658492Z","steps":["trace[1637221730] 'agreement among raft nodes before linearized reading' (duration: 150.48148ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:38.811945Z","caller":"traceutil/trace.go:172","msg":"trace[2102969733] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1208; }","duration":"153.818879ms","start":"2026-04-17T00:56:38.658101Z","end":"2026-04-17T00:56:38.811920Z","steps":["trace[2102969733] 'read index received' (duration: 153.806869ms)","trace[2102969733] 'applied index is now lower than readState.Index' (duration: 9.99µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:38.907851Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"317.712413ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:56:38.907853Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"268.767561ms","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-04-17T00:56:38.907920Z","caller":"traceutil/trace.go:172","msg":"trace[1431704630] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1157; }","duration":"317.799955ms","start":"2026-04-17T00:56:38.590103Z","end":"2026-04-17T00:56:38.907903Z","steps":["trace[1431704630] 'agreement among raft nodes before linearized reading' (duration: 221.856042ms)","trace[1431704630] 'range keys from in-memory index tree' (duration: 95.834881ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:56:38.907938Z","caller":"traceutil/trace.go:172","msg":"trace[1237358658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1157; }","duration":"268.874323ms","start":"2026-04-17T00:56:38.639042Z","end":"2026-04-17T00:56:38.907917Z","steps":["trace[1237358658] 'agreement among raft nodes before linearized reading' (duration: 172.892619ms)","trace[1237358658] 'range keys from in-memory index tree' (duration: 95.74739ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:38.908046Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"386.973101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" limit:1 ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-04-17T00:56:38.908141Z","caller":"traceutil/trace.go:172","msg":"trace[1287050611] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:1157; }","duration":"387.096402ms","start":"2026-04-17T00:56:38.521023Z","end":"2026-04-17T00:56:38.908120Z","steps":["trace[1287050611] 'agreement among raft nodes before linearized reading' (duration: 290.987077ms)","trace[1287050611] 'range keys from in-memory index tree' (duration: 95.843492ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:56:38.908121Z","caller":"traceutil/trace.go:172","msg":"trace[1974728155] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"438.074458ms","start":"2026-04-17T00:56:38.470014Z","end":"2026-04-17T00:56:38.908088Z","steps":["trace[1974728155] 'process raft request' (duration: 341.971333ms)","trace[1974728155] 'compare' (duration: 95.801711ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:38.908244Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"246.386024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" limit:1 ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-17T00:56:38.908330Z","caller":"traceutil/trace.go:172","msg":"trace[1241992396] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:1158; }","duration":"246.474644ms","start":"2026-04-17T00:56:38.661834Z","end":"2026-04-17T00:56:38.908309Z","steps":["trace[1241992396] 'agreement among raft nodes before linearized reading' (duration: 246.283332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:38.908189Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:38.521011Z","time spent":"387.167973ms","remote":"127.0.0.1:47614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":544,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:38.908411Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:38.469991Z","time spent":"438.28709ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:56:42.861427Z","caller":"traceutil/trace.go:172","msg":"trace[952705189] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"193.248948ms","start":"2026-04-17T00:56:42.668153Z","end":"2026-04-17T00:56:42.861402Z","steps":["trace[952705189] 'process raft request' (duration: 193.065596ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:43.090688Z","caller":"traceutil/trace.go:172","msg":"trace[1521731092] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1222; }","duration":"144.956299ms","start":"2026-04-17T00:56:42.945701Z","end":"2026-04-17T00:56:43.090657Z","steps":["trace[1521731092] 'read index received' (duration: 144.946709ms)","trace[1521731092] 'applied index is now lower than readState.Index' (duration: 7.79µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:43.143386Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"255.700924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:56:43.143529Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"181.270325ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:56:43.143491Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"197.792351ms","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-04-17T00:56:43.143631Z","caller":"traceutil/trace.go:172","msg":"trace[1631370977] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1170; }","duration":"181.392127ms","start":"2026-04-17T00:56:42.962215Z","end":"2026-04-17T00:56:43.143607Z","steps":["trace[1631370977] 'range keys from in-memory index tree' (duration: 181.181464ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:43.143680Z","caller":"traceutil/trace.go:172","msg":"trace[481840326] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1170; }","duration":"197.979453ms","start":"2026-04-17T00:56:42.945679Z","end":"2026-04-17T00:56:43.143659Z","steps":["trace[481840326] 'agreement among raft nodes before linearized reading' (duration: 145.118431ms)","trace[481840326] 'range keys from in-memory index tree' (duration: 52.567299ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:56:43.143591Z","caller":"traceutil/trace.go:172","msg":"trace[364615302] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways; range_end:; response_count:0; response_revision:1170; }","duration":"255.913586ms","start":"2026-04-17T00:56:42.887653Z","end":"2026-04-17T00:56:43.143566Z","steps":["trace[364615302] 'range keys from in-memory index tree' (duration: 255.603252ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:43.143966Z","caller":"traceutil/trace.go:172","msg":"trace[1987551619] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"222.301647ms","start":"2026-04-17T00:56:42.921648Z","end":"2026-04-17T00:56:43.143949Z","steps":["trace[1987551619] 'process raft request' (duration: 169.058421ms)","trace[1987551619] 'compare' (duration: 52.585849ms)"],"step_count":2}