{"level":"warn","ts":"2026-04-01T10:30:28.184695Z","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-01T10:30:28.184802Z","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-01T10:30:28.184818Z","caller":"etcdmain/etcd.go:64","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=controller-2=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205:2380","--name=controller-2","--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-01T10:30:28.184887Z","caller":"etcdmain/etcd.go:107","msg":"server has already been initialized","data-dir":"/var/lib/etcd","dir-type":"member"} {"level":"warn","ts":"2026-04-01T10:30:28.184905Z","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-01T10:30:28.184915Z","caller":"embed/etcd.go:138","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-01T10:30:28.184936Z","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-01T10:30:28.186818Z","caller":"embed/etcd.go:146","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-04-01T10:30:28.187287Z","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":true,"name":"controller-2","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"experimental-local-address":"","cors":["*"],"host-whitelist":["*"],"initial-cluster":"","initial-cluster-state":"new","initial-cluster-token":"","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-01T10:30:28.188227Z","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: 0xc00007c518}"} {"level":"info","ts":"2026-04-01T10:30:28.198000Z","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-01T10:30:28.198225Z","caller":"storage/backend.go:80","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.130684ms"} {"level":"info","ts":"2026-04-01T10:30:28.198615Z","caller":"etcdserver/bootstrap.go:220","msg":"restore consistentIndex","index":5403} {"level":"info","ts":"2026-04-01T10:30:28.276931Z","caller":"etcdserver/bootstrap.go:441","msg":"No snapshot found. Recovering WAL from scratch!"} {"level":"info","ts":"2026-04-01T10:30:28.277011Z","caller":"etcdserver/bootstrap.go:232","msg":"recovered v3 backend","backend-size-bytes":24936448,"backend-size":"25 MB","backend-size-in-use-bytes":8089600,"backend-size-in-use":"8.1 MB"} {"level":"info","ts":"2026-04-01T10:30:28.277174Z","caller":"etcdserver/bootstrap.go:90","msg":"Bootstrapping WAL from snapshot"} {"level":"info","ts":"2026-04-01T10:30:28.343817Z","caller":"etcdserver/bootstrap.go:599","msg":"restarting local member","cluster-id":"ba0a336ca61ee41d","local-member-id":"11b599b5a29506e4","commit-index":5404} {"level":"info","ts":"2026-04-01T10:30:28.343882Z","caller":"etcdserver/bootstrap.go:94","msg":"bootstrapping cluster"} {"level":"info","ts":"2026-04-01T10:30:28.344003Z","caller":"etcdserver/bootstrap.go:101","msg":"bootstrapping storage"} {"level":"info","ts":"2026-04-01T10:30:28.344155Z","caller":"membership/cluster.go:605","msg":"Detected member only in v3store but missing in v2store","member":"{ID:11b599b5a29506e4 RaftAttributes:{PeerURLs:[https://199.204.45.205:2380] IsLearner:false} Attributes:{Name:controller-2 ClientURLs:[https://199.204.45.205:2379]}}"} {"level":"info","ts":"2026-04-01T10:30:28.344178Z","caller":"membership/cluster.go:605","msg":"Detected member only in v3store but missing in v2store","member":"{ID:1cac722af8b2b8a6 RaftAttributes:{PeerURLs:[https://199.204.45.210:2380] IsLearner:false} Attributes:{Name:controller-1 ClientURLs:[https://199.204.45.210:2379]}}"} {"level":"info","ts":"2026-04-01T10:30:28.344193Z","caller":"membership/cluster.go:605","msg":"Detected member only in v3store but missing in v2store","member":"{ID:4951500f65e17c3 RaftAttributes:{PeerURLs:[https://199.204.45.23:2380] IsLearner:false} Attributes:{Name:controller-3 ClientURLs:[https://199.204.45.23:2379]}}"} {"level":"info","ts":"2026-04-01T10:30:28.344234Z","caller":"api/capability.go:76","msg":"enabled capabilities for version","cluster-version":"3.6"} {"level":"info","ts":"2026-04-01T10:30:28.344250Z","caller":"membership/cluster.go:297","msg":"recovered/added member from store","cluster-id":"ba0a336ca61ee41d","local-member-id":"11b599b5a29506e4","recovered-remote-peer-id":"11b599b5a29506e4","recovered-remote-peer-urls":["https://199.204.45.205:2380"],"recovered-remote-peer-is-learner":false} {"level":"info","ts":"2026-04-01T10:30:28.344259Z","caller":"membership/cluster.go:297","msg":"recovered/added member from store","cluster-id":"ba0a336ca61ee41d","local-member-id":"11b599b5a29506e4","recovered-remote-peer-id":"1cac722af8b2b8a6","recovered-remote-peer-urls":["https://199.204.45.210:2380"],"recovered-remote-peer-is-learner":false} {"level":"info","ts":"2026-04-01T10:30:28.344264Z","caller":"membership/cluster.go:297","msg":"recovered/added member from store","cluster-id":"ba0a336ca61ee41d","local-member-id":"11b599b5a29506e4","recovered-remote-peer-id":"4951500f65e17c3","recovered-remote-peer-urls":["https://199.204.45.23:2380"],"recovered-remote-peer-is-learner":false} {"level":"info","ts":"2026-04-01T10:30:28.344269Z","caller":"membership/cluster.go:307","msg":"set cluster version from store","cluster-version":"3.6"} {"level":"info","ts":"2026-04-01T10:30:28.344278Z","caller":"etcdserver/bootstrap.go:109","msg":"bootstrapping raft"} {"level":"info","ts":"2026-04-01T10:30:28.344323Z","caller":"etcdserver/server.go:312","msg":"bootstrap successfully"} {"level":"info","ts":"2026-04-01T10:30:28.344400Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"11b599b5a29506e4 switched to configuration voters=()"} {"level":"info","ts":"2026-04-01T10:30:28.344435Z","logger":"raft","caller":"v3@v3.6.0/raft.go:897","msg":"11b599b5a29506e4 became follower at term 8"} {"level":"info","ts":"2026-04-01T10:30:28.344445Z","logger":"raft","caller":"v3@v3.6.0/raft.go:493","msg":"newRaft 11b599b5a29506e4 [peers: [], term: 8, commit: 5404, applied: 0, lastindex: 5405, lastterm: 8]"} {"level":"warn","ts":"2026-04-01T10:30:28.352628Z","caller":"auth/store.go:1135","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-01T10:30:28.354474Z","caller":"mvcc/kvstore.go:334","msg":"restored last compact revision","meta-bucket-name-key":"finishedCompactRev","restored-compact-revision":2350} {"level":"info","ts":"2026-04-01T10:30:28.361552Z","caller":"mvcc/kvstore.go:408","msg":"kvstore restored","current-rev":4670} {"level":"info","ts":"2026-04-01T10:30:28.383148Z","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-01T10:30:28.384410Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:28.384832Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:28.385284Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:28.385379Z","caller":"rafthttp/stream.go:394","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:28.385665Z","caller":"rafthttp/stream.go:394","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:28.385297Z","caller":"rafthttp/stream.go:168","msg":"started stream writer with remote peer","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:28.385475Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3","remote-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-04-01T10:30:28.386034Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"1cac722af8b2b8a6"} {"level":"info","ts":"2026-04-01T10:30:28.386108Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"11b599b5a29506e4","remote-peer-id":"1cac722af8b2b8a6"} {"level":"info","ts":"2026-04-01T10:30:28.386158Z","caller":"rafthttp/stream.go:168","msg":"started stream writer with remote peer","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:28.386942Z","caller":"rafthttp/stream.go:168","msg":"started stream writer with remote peer","local-member-id":"11b599b5a29506e4","remote-peer-id":"1cac722af8b2b8a6"} {"level":"info","ts":"2026-04-01T10:30:28.387002Z","caller":"rafthttp/stream.go:168","msg":"started stream writer with remote peer","local-member-id":"11b599b5a29506e4","remote-peer-id":"1cac722af8b2b8a6"} {"level":"info","ts":"2026-04-01T10:30:28.387128Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"1cac722af8b2b8a6"} {"level":"info","ts":"2026-04-01T10:30:28.387175Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"11b599b5a29506e4","remote-peer-id":"1cac722af8b2b8a6","remote-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-04-01T10:30:28.387201Z","caller":"etcdserver/corrupt.go:91","msg":"starting initial corruption check","local-member-id":"11b599b5a29506e4","timeout":"7s"} {"level":"info","ts":"2026-04-01T10:30:28.387206Z","caller":"rafthttp/stream.go:394","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"11b599b5a29506e4","remote-peer-id":"1cac722af8b2b8a6"} {"level":"info","ts":"2026-04-01T10:30:28.387221Z","caller":"rafthttp/stream.go:394","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"11b599b5a29506e4","remote-peer-id":"1cac722af8b2b8a6"} {"level":"info","ts":"2026-04-01T10:30:28.397454Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"1cac722af8b2b8a6"} {"level":"info","ts":"2026-04-01T10:30:28.397504Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"11b599b5a29506e4","remote-peer-id":"1cac722af8b2b8a6"} {"level":"info","ts":"2026-04-01T10:30:28.400451Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:28.400498Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:28.401170Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:28.401815Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"11b599b5a29506e4","remote-peer-id":"1cac722af8b2b8a6"} {"level":"info","ts":"2026-04-01T10:30:28.411492Z","logger":"raft","caller":"v3@v3.6.0/node.go:370","msg":"raft.node: 11b599b5a29506e4 elected leader 4951500f65e17c3 at term 8"} {"level":"info","ts":"2026-04-01T10:30:28.414776Z","caller":"etcdserver/corrupt.go:172","msg":"initial corruption checking passed; no corruption","local-member-id":"11b599b5a29506e4"} {"level":"info","ts":"2026-04-01T10:30:28.414870Z","caller":"etcdserver/server.go:589","msg":"starting etcd server","local-member-id":"11b599b5a29506e4","local-server-version":"3.6.4","cluster-id":"ba0a336ca61ee41d","cluster-version":"3.6"} {"level":"info","ts":"2026-04-01T10:30:28.415210Z","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-01T10:30:28.415680Z","caller":"embed/etcd.go:292","msg":"now serving peer/client/metrics","local-member-id":"11b599b5a29506e4","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-01T10:30:28.415718Z","caller":"embed/etcd.go:890","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-01T10:30:28.415792Z","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-01T10:30:28.415839Z","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-01T10:30:28.415851Z","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-01T10:30:28.416182Z","caller":"embed/etcd.go:640","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-01T10:30:28.416199Z","caller":"embed/etcd.go:611","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-01T10:30:28.416464Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"11b599b5a29506e4","to":"4951500f65e17c3","stream-type":"stream Message"} {"level":"info","ts":"2026-04-01T10:30:28.416484Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:28.417152Z","caller":"etcdserver/server.go:499","msg":"starting initial election tick advance","election-ticks":10} {"level":"info","ts":"2026-04-01T10:30:28.417782Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"11b599b5a29506e4 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-04-01T10:30:28.417860Z","caller":"membership/cluster.go:433","msg":"ignore already added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"11b599b5a29506e4","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"],"added-peer-is-learner":false} {"level":"info","ts":"2026-04-01T10:30:28.418009Z","caller":"membership/cluster.go:674","msg":"updated cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"11b599b5a29506e4","from":"3.6","to":"3.5"} {"level":"info","ts":"2026-04-01T10:30:28.418124Z","caller":"api/capability.go:76","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T10:30:28.418179Z","caller":"apply/apply.go:422","msg":"Cluster version downgrade detected, forcing snapshot","prev-cluster-version":"3.6.0","new-cluster-version":"3.5.0"} {"level":"info","ts":"2026-04-01T10:30:28.418277Z","caller":"version/monitor.go:116","msg":"cluster version differs from storage version.","cluster-version":"3.5.0","storage-version":"3.6.0"} {"level":"info","ts":"2026-04-01T10:30:28.418366Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"11b599b5a29506e4","to":"1cac722af8b2b8a6","stream-type":"stream Message"} {"level":"info","ts":"2026-04-01T10:30:28.418403Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"11b599b5a29506e4","remote-peer-id":"1cac722af8b2b8a6"} {"level":"info","ts":"2026-04-01T10:30:28.418759Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"11b599b5a29506e4","to":"4951500f65e17c3","stream-type":"stream MsgApp v2"} {"level":"info","ts":"2026-04-01T10:30:28.418785Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:28.419050Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"11b599b5a29506e4","to":"1cac722af8b2b8a6","stream-type":"stream MsgApp v2"} {"level":"info","ts":"2026-04-01T10:30:28.419092Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"11b599b5a29506e4","remote-peer-id":"1cac722af8b2b8a6"} {"level":"info","ts":"2026-04-01T10:30:28.420742Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"11b599b5a29506e4 switched to configuration voters=(2066151857942870182) learners=(1276095074825209572)"} {"level":"info","ts":"2026-04-01T10:30:28.420828Z","caller":"membership/cluster.go:433","msg":"ignore already added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"11b599b5a29506e4","added-peer-id":"11b599b5a29506e4","added-peer-peer-urls":["https://199.204.45.205:2380"],"added-peer-is-learner":true} {"level":"info","ts":"2026-04-01T10:30:28.421048Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"11b599b5a29506e4 switched to configuration voters=(1276095074825209572 2066151857942870182)"} {"level":"info","ts":"2026-04-01T10:30:28.421129Z","caller":"membership/cluster.go:574","msg":"ignore already promoted member in v3store","cluster-id":"ba0a336ca61ee41d","local-member-id":"11b599b5a29506e4","promoted-member-id":"11b599b5a29506e4"} {"level":"info","ts":"2026-04-01T10:30:28.421596Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"11b599b5a29506e4 switched to configuration voters=(1276095074825209572 2066151857942870182) learners=(330193241559144387)"} {"level":"info","ts":"2026-04-01T10:30:28.421649Z","caller":"membership/cluster.go:433","msg":"ignore already added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"11b599b5a29506e4","added-peer-id":"4951500f65e17c3","added-peer-peer-urls":["https://199.204.45.23:2380"],"added-peer-is-learner":true} {"level":"info","ts":"2026-04-01T10:30:28.421777Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"11b599b5a29506e4 switched to configuration voters=(330193241559144387 1276095074825209572 2066151857942870182)"} {"level":"info","ts":"2026-04-01T10:30:28.421825Z","caller":"membership/cluster.go:574","msg":"ignore already promoted member in v3store","cluster-id":"ba0a336ca61ee41d","local-member-id":"11b599b5a29506e4","promoted-member-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:28.422586Z","caller":"etcdserver/server.go:2185","msg":"triggering snapshot","local-member-id":"11b599b5a29506e4","local-member-applied-index":874,"local-member-snapshot-index":0,"local-member-snapshot-count":10000,"snapshot-forced":true} {"level":"info","ts":"2026-04-01T10:30:28.482756Z","caller":"etcdserver/server.go:522","msg":"initialized peer connections; fast-forwarding election ticks","local-member-id":"11b599b5a29506e4","forward-ticks":8,"forward-duration":"800ms","election-ticks":10,"election-timeout":"1s","active-remote-members":2} {"level":"info","ts":"2026-04-01T10:30:28.715489Z","caller":"schema/migration.go:65","msg":"updated storage version","new-storage-version":"3.5.0"} {"level":"info","ts":"2026-04-01T10:30:29.053939Z","caller":"etcdserver/server.go:2230","msg":"saved snapshot to disk","snapshot-index":874} {"level":"info","ts":"2026-04-01T10:30:29.075457Z","caller":"membership/cluster.go:674","msg":"updated cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"11b599b5a29506e4","from":"3.5","to":"3.6"} {"level":"info","ts":"2026-04-01T10:30:29.075557Z","caller":"api/capability.go:76","msg":"enabled capabilities for version","cluster-version":"3.6"} {"level":"info","ts":"2026-04-01T10:30:29.075842Z","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-01T10:30:29.076035Z","caller":"schema/migration.go:65","msg":"updated storage version","new-storage-version":"3.6.0"} {"level":"info","ts":"2026-04-01T10:30:29.079776Z","caller":"etcdserver/server.go:1804","msg":"published local member to cluster through raft","local-member-id":"11b599b5a29506e4","local-member-attributes":"{Name:controller-2 ClientURLs:[https://199.204.45.205:2379]}","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-01T10:30:29.079794Z","caller":"embed/serve.go:138","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T10:30:29.079819Z","caller":"embed/serve.go:138","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T10:30:29.080182Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-01T10:30:29.080479Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-01T10:30:29.082851Z","caller":"v3rpc/health.go:63","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"warn","ts":"2026-04-01T10:30:29.083662Z","caller":"v3rpc/grpc.go:52","msg":"etcdserver: failed to register grpc metrics","error":"duplicate metrics collector registration attempted"} {"level":"info","ts":"2026-04-01T10:30:29.084043Z","caller":"v3rpc/health.go:63","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-04-01T10:30:29.087632Z","caller":"embed/serve.go:283","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-04-01T10:30:29.091404Z","caller":"embed/serve.go:283","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-01T10:30:29.303270Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53334","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.303284Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53348","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.329267Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53368","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.343410Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53388","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.360102Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53424","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.379395Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53428","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.390544Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53456","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.403997Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53478","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.414400Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53488","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.421597Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53504","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.432151Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53526","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.443540Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53544","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.450581Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53558","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.458457Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53578","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.467762Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53614","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.475923Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53620","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.482478Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53630","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.490753Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53646","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.498899Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53664","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.508501Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53668","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.516483Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53674","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.524738Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53694","server-name":"","error":"EOF"} 2026/04/01 10:30:29 WARNING: [core] [Server #6]grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" 2026/04/01 10:30:29 WARNING: [core] [Server #6]grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-01T10:30:29.568114Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53726","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.586582Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53750","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.597671Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53764","server-name":"","error":"EOF"} {"level":"info","ts":"2026-04-01T10:30:29.608467Z","caller":"traceutil/trace.go:172","msg":"trace[1869700713] linearizableReadLoop","detail":"{readStateIndex:5479; appliedIndex:5480; }","duration":"235.957639ms","start":"2026-04-01T10:30:29.372483Z","end":"2026-04-01T10:30:29.608440Z","steps":["trace[1869700713] 'read index received' (duration: 235.704854ms)","trace[1869700713] 'applied index is now lower than readState.Index' (duration: 3.17µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:30:29.608562Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"236.055513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.609063Z","caller":"traceutil/trace.go:172","msg":"trace[733601431] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions; range_end:; response_count:0; response_revision:4732; }","duration":"236.570824ms","start":"2026-04-01T10:30:29.372475Z","end":"2026-04-01T10:30:29.609046Z","steps":["trace[733601431] 'agreement among raft nodes before linearized reading' (duration: 235.98602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.618312Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53778","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.622148Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53810","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.627257Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"145.279861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.627306Z","caller":"traceutil/trace.go:172","msg":"trace[1844691992] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:4732; }","duration":"145.337733ms","start":"2026-04-01T10:30:29.481953Z","end":"2026-04-01T10:30:29.627291Z","steps":["trace[1844691992] 'agreement among raft nodes before linearized reading' (duration: 145.23868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.627316Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"145.425885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.627378Z","caller":"traceutil/trace.go:172","msg":"trace[256082645] range","detail":"{range_begin:/registry/persistentvolumeclaims; range_end:; response_count:0; response_revision:4732; }","duration":"145.503286ms","start":"2026-04-01T10:30:29.481859Z","end":"2026-04-01T10:30:29.627362Z","steps":["trace[256082645] 'agreement among raft nodes before linearized reading' (duration: 145.404054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.627513Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"150.975413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.627551Z","caller":"traceutil/trace.go:172","msg":"trace[469434963] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:4732; }","duration":"151.014114ms","start":"2026-04-01T10:30:29.476528Z","end":"2026-04-01T10:30:29.627542Z","steps":["trace[469434963] 'agreement among raft nodes before linearized reading' (duration: 150.955963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.627732Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"151.262729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.627773Z","caller":"traceutil/trace.go:172","msg":"trace[1030780075] range","detail":"{range_begin:/registry/persistentvolumes; range_end:; response_count:0; response_revision:4732; }","duration":"151.30257ms","start":"2026-04-01T10:30:29.476458Z","end":"2026-04-01T10:30:29.627761Z","steps":["trace[1030780075] 'agreement among raft nodes before linearized reading' (duration: 151.237859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.627929Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"160.188006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.627967Z","caller":"traceutil/trace.go:172","msg":"trace[1085722344] range","detail":"{range_begin:/registry/limitranges; range_end:; response_count:0; response_revision:4732; }","duration":"160.227257ms","start":"2026-04-01T10:30:29.467729Z","end":"2026-04-01T10:30:29.627956Z","steps":["trace[1085722344] 'agreement among raft nodes before linearized reading' (duration: 160.165025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.628154Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"161.016706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.628196Z","caller":"traceutil/trace.go:172","msg":"trace[674751815] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:4732; }","duration":"161.060067ms","start":"2026-04-01T10:30:29.467125Z","end":"2026-04-01T10:30:29.628185Z","steps":["trace[674751815] 'agreement among raft nodes before linearized reading' (duration: 160.994536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.628364Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"169.208936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.628394Z","caller":"traceutil/trace.go:172","msg":"trace[1264959567] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:4732; }","duration":"169.242447ms","start":"2026-04-01T10:30:29.459142Z","end":"2026-04-01T10:30:29.628385Z","steps":["trace[1264959567] 'agreement among raft nodes before linearized reading' (duration: 169.179345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.628414Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"215.747822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.628454Z","caller":"traceutil/trace.go:172","msg":"trace[1120985726] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:4732; }","duration":"215.791563ms","start":"2026-04-01T10:30:29.412652Z","end":"2026-04-01T10:30:29.628444Z","steps":["trace[1120985726] 'agreement among raft nodes before linearized reading' (duration: 215.715861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.628706Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"170.209509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.628734Z","caller":"traceutil/trace.go:172","msg":"trace[1827017394] range","detail":"{range_begin:/registry/podtemplates; range_end:; response_count:0; response_revision:4732; }","duration":"170.238739ms","start":"2026-04-01T10:30:29.458487Z","end":"2026-04-01T10:30:29.628726Z","steps":["trace[1827017394] 'agreement among raft nodes before linearized reading' (duration: 170.185558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.628944Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"186.124037ms","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-01T10:30:29.629038Z","caller":"traceutil/trace.go:172","msg":"trace[67137698] range","detail":"{range_begin:/registry/namespaces; range_end:; response_count:0; response_revision:4732; }","duration":"186.216969ms","start":"2026-04-01T10:30:29.442811Z","end":"2026-04-01T10:30:29.629028Z","steps":["trace[67137698] 'agreement among raft nodes before linearized reading' (duration: 186.101867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.629208Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"121.361678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.629462Z","caller":"traceutil/trace.go:172","msg":"trace[1775667270] range","detail":"{range_begin:/registry/pods; range_end:; response_count:0; response_revision:4732; }","duration":"121.616774ms","start":"2026-04-01T10:30:29.507834Z","end":"2026-04-01T10:30:29.629450Z","steps":["trace[1775667270] 'agreement among raft nodes before linearized reading' (duration: 121.335207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.629699Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53816","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.630011Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"187.209321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:5 size:1788"} {"level":"info","ts":"2026-04-01T10:30:29.630048Z","caller":"traceutil/trace.go:172","msg":"trace[695581051] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:5; response_revision:4732; }","duration":"187.246062ms","start":"2026-04-01T10:30:29.442790Z","end":"2026-04-01T10:30:29.630036Z","steps":["trace[695581051] 'agreement among raft nodes before linearized reading' (duration: 186.865743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.630056Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"140.003309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 ","response":"range_response_count:3 size:2542"} {"level":"info","ts":"2026-04-01T10:30:29.630097Z","caller":"traceutil/trace.go:172","msg":"trace[996040766] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:3; response_revision:4732; }","duration":"140.04877ms","start":"2026-04-01T10:30:29.490037Z","end":"2026-04-01T10:30:29.630085Z","steps":["trace[996040766] 'agreement among raft nodes before linearized reading' (duration: 139.910657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.630559Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"122.737299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" limit:10000 ","response":"range_response_count:25 size:191617"} {"level":"info","ts":"2026-04-01T10:30:29.630604Z","caller":"traceutil/trace.go:172","msg":"trace[194220365] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:25; response_revision:4732; }","duration":"122.78553ms","start":"2026-04-01T10:30:29.507810Z","end":"2026-04-01T10:30:29.630596Z","steps":["trace[194220365] 'agreement among raft nodes before linearized reading' (duration: 122.502343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.630966Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"132.950746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.631008Z","caller":"traceutil/trace.go:172","msg":"trace[2006717845] range","detail":"{range_begin:/registry/minions; range_end:; response_count:0; response_revision:4732; }","duration":"132.994997ms","start":"2026-04-01T10:30:29.498004Z","end":"2026-04-01T10:30:29.630999Z","steps":["trace[2006717845] 'agreement among raft nodes before linearized reading' (duration: 132.929376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.631386Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"115.014121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" limit:10000 ","response":"range_response_count:3 size:3809"} {"level":"warn","ts":"2026-04-01T10:30:29.631137Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"198.579795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.631463Z","caller":"traceutil/trace.go:172","msg":"trace[41692813] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:3; response_revision:4732; }","duration":"115.105123ms","start":"2026-04-01T10:30:29.516341Z","end":"2026-04-01T10:30:29.631446Z","steps":["trace[41692813] 'agreement among raft nodes before linearized reading' (duration: 114.838247ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:29.631481Z","caller":"traceutil/trace.go:172","msg":"trace[1674316193] range","detail":"{range_begin:/registry/configmaps; range_end:; response_count:0; response_revision:4732; }","duration":"198.924803ms","start":"2026-04-01T10:30:29.432546Z","end":"2026-04-01T10:30:29.631471Z","steps":["trace[1674316193] 'agreement among raft nodes before linearized reading' (duration: 198.552684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.631954Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"199.564428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:14 size:27820"} {"level":"info","ts":"2026-04-01T10:30:29.631982Z","caller":"traceutil/trace.go:172","msg":"trace[112188306] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:14; response_revision:4732; }","duration":"199.596778ms","start":"2026-04-01T10:30:29.432379Z","end":"2026-04-01T10:30:29.631976Z","steps":["trace[112188306] 'agreement among raft nodes before linearized reading' (duration: 199.427454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.632006Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"107.435105ms","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-01T10:30:29.632144Z","caller":"traceutil/trace.go:172","msg":"trace[216618404] range","detail":"{range_begin:/registry/serviceaccounts; range_end:; response_count:0; response_revision:4732; }","duration":"107.568778ms","start":"2026-04-01T10:30:29.524563Z","end":"2026-04-01T10:30:29.632131Z","steps":["trace[216618404] 'agreement among raft nodes before linearized reading' (duration: 107.419604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.633244Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"210.95167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.633277Z","caller":"traceutil/trace.go:172","msg":"trace[1072974340] range","detail":"{range_begin:/registry/secrets; range_end:; response_count:0; response_revision:4732; }","duration":"210.986071ms","start":"2026-04-01T10:30:29.422283Z","end":"2026-04-01T10:30:29.633269Z","steps":["trace[1072974340] 'agreement among raft nodes before linearized reading' (duration: 210.928969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.633590Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"109.084892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:47 size:11356"} {"level":"info","ts":"2026-04-01T10:30:29.633625Z","caller":"traceutil/trace.go:172","msg":"trace[1672352424] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:47; response_revision:4732; }","duration":"109.146154ms","start":"2026-04-01T10:30:29.524471Z","end":"2026-04-01T10:30:29.633617Z","steps":["trace[1672352424] 'agreement among raft nodes before linearized reading' (duration: 107.977147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.633774Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"117.411307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.633802Z","caller":"traceutil/trace.go:172","msg":"trace[279345592] range","detail":"{range_begin:/registry/services/specs; range_end:; response_count:0; response_revision:4732; }","duration":"117.440778ms","start":"2026-04-01T10:30:29.516355Z","end":"2026-04-01T10:30:29.633796Z","steps":["trace[279345592] 'agreement among raft nodes before linearized reading' (duration: 117.396027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.634709Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"136.685432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:10000 ","response":"range_response_count:3 size:16424"} {"level":"info","ts":"2026-04-01T10:30:29.634802Z","caller":"traceutil/trace.go:172","msg":"trace[661074521] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:3; response_revision:4732; }","duration":"136.783994ms","start":"2026-04-01T10:30:29.498004Z","end":"2026-04-01T10:30:29.634788Z","steps":["trace[661074521] 'agreement among raft nodes before linearized reading' (duration: 136.210141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.635600Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"145.510157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.635634Z","caller":"traceutil/trace.go:172","msg":"trace[374965648] range","detail":"{range_begin:/registry/services/endpoints; range_end:; response_count:0; response_revision:4732; }","duration":"145.546067ms","start":"2026-04-01T10:30:29.490081Z","end":"2026-04-01T10:30:29.635627Z","steps":["trace[374965648] 'agreement among raft nodes before linearized reading' (duration: 145.490576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.635832Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"223.139912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.635856Z","caller":"traceutil/trace.go:172","msg":"trace[884442527] range","detail":"{range_begin:/registry/resourcequotas; range_end:; response_count:0; response_revision:4732; }","duration":"223.163733ms","start":"2026-04-01T10:30:29.412686Z","end":"2026-04-01T10:30:29.635850Z","steps":["trace[884442527] 'agreement among raft nodes before linearized reading' (duration: 223.125632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.636426Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"214.167724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:11 size:1381431"} {"level":"info","ts":"2026-04-01T10:30:29.636451Z","caller":"traceutil/trace.go:172","msg":"trace[757892711] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:11; response_revision:4732; }","duration":"214.194765ms","start":"2026-04-01T10:30:29.422250Z","end":"2026-04-01T10:30:29.636444Z","steps":["trace[757892711] 'agreement among raft nodes before linearized reading' (duration: 213.720134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.638062Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"265.471552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:31 size:2058093"} {"level":"info","ts":"2026-04-01T10:30:29.638129Z","caller":"traceutil/trace.go:172","msg":"trace[1801681449] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:31; response_revision:4732; }","duration":"265.549214ms","start":"2026-04-01T10:30:29.372566Z","end":"2026-04-01T10:30:29.638115Z","steps":["trace[1801681449] 'agreement among raft nodes before linearized reading' (duration: 258.793388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.639672Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"241.372335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" keys_only:true ","response":"range_response_count:429 size:38025"} {"level":"info","ts":"2026-04-01T10:30:29.639860Z","caller":"traceutil/trace.go:172","msg":"trace[2021574123] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:429; response_revision:4732; }","duration":"241.533879ms","start":"2026-04-01T10:30:29.398282Z","end":"2026-04-01T10:30:29.639816Z","steps":["trace[2021574123] 'agreement among raft nodes before linearized reading' (duration: 230.243418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.645601Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53832","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.661843Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53848","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.670985Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53872","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.689818Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53904","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.701952Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53922","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.715572Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53940","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.725997Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53968","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.737991Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:53990","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.746212Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54000","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.755602Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54016","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.768727Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54042","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.780354Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54066","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.788201Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54088","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.813995Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54114","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.822559Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54134","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.832752Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54152","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.842447Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54164","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.850284Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54194","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.863875Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54222","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.883660Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54244","server-name":"","error":"EOF"} {"level":"info","ts":"2026-04-01T10:30:29.890757Z","caller":"traceutil/trace.go:172","msg":"trace[534929665] linearizableReadLoop","detail":"{readStateIndex:5482; appliedIndex:5485; }","duration":"263.692731ms","start":"2026-04-01T10:30:29.627026Z","end":"2026-04-01T10:30:29.890719Z","steps":["trace[534929665] 'read index received' (duration: 263.685601ms)","trace[534929665] 'applied index is now lower than readState.Index' (duration: 6.11µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:30:29.891024Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54260","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.891320Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"270.266863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T10:30:29.891450Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"270.389455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" limit:10000 ","response":"range_response_count:12 size:6573"} {"level":"info","ts":"2026-04-01T10:30:29.891490Z","caller":"traceutil/trace.go:172","msg":"trace[1595627096] range","detail":"{range_begin:/registry/leases; range_end:; response_count:0; response_revision:4735; }","duration":"270.448078ms","start":"2026-04-01T10:30:29.621028Z","end":"2026-04-01T10:30:29.891476Z","steps":["trace[1595627096] 'agreement among raft nodes before linearized reading' (duration: 269.913805ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:29.891560Z","caller":"traceutil/trace.go:172","msg":"trace[1560552211] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:12; response_revision:4735; }","duration":"270.52535ms","start":"2026-04-01T10:30:29.621013Z","end":"2026-04-01T10:30:29.891539Z","steps":["trace[1560552211] 'agreement among raft nodes before linearized reading' (duration: 270.14734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.893443Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"179.691577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T10:30:29.893544Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"192.679909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/servicecidrs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T10:30:29.893852Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"139.889447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" limit:10000 ","response":"range_response_count:74 size:74054"} {"level":"info","ts":"2026-04-01T10:30:29.893897Z","caller":"traceutil/trace.go:172","msg":"trace[771649519] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:74; response_revision:4735; }","duration":"139.936318ms","start":"2026-04-01T10:30:29.753951Z","end":"2026-04-01T10:30:29.893887Z","steps":["trace[771649519] 'agreement among raft nodes before linearized reading' (duration: 139.515938ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:29.893713Z","caller":"traceutil/trace.go:172","msg":"trace[1107222772] range","detail":"{range_begin:/registry/servicecidrs; range_end:; response_count:0; response_revision:4735; }","duration":"192.843963ms","start":"2026-04-01T10:30:29.700851Z","end":"2026-04-01T10:30:29.893695Z","steps":["trace[1107222772] 'agreement among raft nodes before linearized reading' (duration: 192.657268ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:29.893498Z","caller":"traceutil/trace.go:172","msg":"trace[262399282] range","detail":"{range_begin:/registry/runtimeclasses; range_end:; response_count:0; response_revision:4735; }","duration":"179.760179ms","start":"2026-04-01T10:30:29.713723Z","end":"2026-04-01T10:30:29.893483Z","steps":["trace[262399282] 'agreement among raft nodes before linearized reading' (duration: 179.668687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.894222Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"106.201067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.894252Z","caller":"traceutil/trace.go:172","msg":"trace[2052933125] range","detail":"{range_begin:/registry/storageclasses; range_end:; response_count:0; response_revision:4735; }","duration":"106.231878ms","start":"2026-04-01T10:30:29.788012Z","end":"2026-04-01T10:30:29.894244Z","steps":["trace[2052933125] 'agreement among raft nodes before linearized reading' (duration: 106.184216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.894258Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"140.312067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.894318Z","caller":"traceutil/trace.go:172","msg":"trace[73021503] range","detail":"{range_begin:/registry/clusterroles; range_end:; response_count:0; response_revision:4735; }","duration":"140.360448ms","start":"2026-04-01T10:30:29.753937Z","end":"2026-04-01T10:30:29.894297Z","steps":["trace[73021503] 'agreement among raft nodes before linearized reading' (duration: 140.281217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.894417Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"106.441323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.894502Z","caller":"traceutil/trace.go:172","msg":"trace[1265864790] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:4735; }","duration":"106.526295ms","start":"2026-04-01T10:30:29.787966Z","end":"2026-04-01T10:30:29.894492Z","steps":["trace[1265864790] 'agreement among raft nodes before linearized reading' (duration: 106.423812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.894652Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"115.625356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T10:30:29.894653Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"194.03459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/servicecidrs/\" range_end:\"/registry/servicecidrs0\" limit:10000 ","response":"range_response_count:1 size:997"} {"level":"info","ts":"2026-04-01T10:30:29.894684Z","caller":"traceutil/trace.go:172","msg":"trace[214169860] range","detail":"{range_begin:/registry/priorityclasses; range_end:; response_count:0; response_revision:4735; }","duration":"115.659657ms","start":"2026-04-01T10:30:29.779016Z","end":"2026-04-01T10:30:29.894676Z","steps":["trace[214169860] 'agreement among raft nodes before linearized reading' (duration: 115.599286ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:29.894695Z","caller":"traceutil/trace.go:172","msg":"trace[510515712] range","detail":"{range_begin:/registry/servicecidrs/; range_end:/registry/servicecidrs0; response_count:1; response_revision:4735; }","duration":"194.080351ms","start":"2026-04-01T10:30:29.700604Z","end":"2026-04-01T10:30:29.894684Z","steps":["trace[510515712] 'agreement among raft nodes before linearized reading' (duration: 193.908817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.894741Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"158.470587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" limit:10000 ","response":"range_response_count:16 size:10586"} {"level":"warn","ts":"2026-04-01T10:30:29.894793Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"149.018907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.894811Z","caller":"traceutil/trace.go:172","msg":"trace[522463039] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:16; response_revision:4735; }","duration":"158.540359ms","start":"2026-04-01T10:30:29.736260Z","end":"2026-04-01T10:30:29.894800Z","steps":["trace[522463039] 'agreement among raft nodes before linearized reading' (duration: 158.285833ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:29.894826Z","caller":"traceutil/trace.go:172","msg":"trace[467933763] range","detail":"{range_begin:/registry/rolebindings; range_end:; response_count:0; response_revision:4735; }","duration":"149.048658ms","start":"2026-04-01T10:30:29.745768Z","end":"2026-04-01T10:30:29.894817Z","steps":["trace[467933763] 'agreement among raft nodes before linearized reading' (duration: 149.004377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.894879Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"181.09776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T10:30:29.894897Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"116.041146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" limit:10000 ","response":"range_response_count:2 size:912"} {"level":"info","ts":"2026-04-01T10:30:29.894916Z","caller":"traceutil/trace.go:172","msg":"trace[2040393786] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:4735; }","duration":"181.137181ms","start":"2026-04-01T10:30:29.713771Z","end":"2026-04-01T10:30:29.894908Z","steps":["trace[2040393786] 'agreement among raft nodes before linearized reading' (duration: 181.08517ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:29.894926Z","caller":"traceutil/trace.go:172","msg":"trace[127108992] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:2; response_revision:4735; }","duration":"116.072916ms","start":"2026-04-01T10:30:29.778845Z","end":"2026-04-01T10:30:29.894918Z","steps":["trace[127108992] 'agreement among raft nodes before linearized reading' (duration: 115.983344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.895011Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"169.4143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.895033Z","caller":"traceutil/trace.go:172","msg":"trace[1244813228] range","detail":"{range_begin:/registry/poddisruptionbudgets; range_end:; response_count:0; response_revision:4735; }","duration":"169.466931ms","start":"2026-04-01T10:30:29.725560Z","end":"2026-04-01T10:30:29.895027Z","steps":["trace[1244813228] 'agreement among raft nodes before linearized reading' (duration: 169.43327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.894163Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"157.656237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T10:30:29.895063Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"125.427743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.895089Z","caller":"traceutil/trace.go:172","msg":"trace[483194370] range","detail":"{range_begin:/registry/clusterrolebindings; range_end:; response_count:0; response_revision:4735; }","duration":"125.455623ms","start":"2026-04-01T10:30:29.769626Z","end":"2026-04-01T10:30:29.895081Z","steps":["trace[483194370] 'agreement among raft nodes before linearized reading' (duration: 125.404452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.895139Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"169.950632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.895162Z","caller":"traceutil/trace.go:172","msg":"trace[2144569615] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:4735; }","duration":"169.973113ms","start":"2026-04-01T10:30:29.725182Z","end":"2026-04-01T10:30:29.895155Z","steps":["trace[2144569615] 'agreement among raft nodes before linearized reading' (duration: 169.938232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.894752Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"148.853734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" limit:10000 ","response":"range_response_count:16 size:12046"} {"level":"info","ts":"2026-04-01T10:30:29.895198Z","caller":"traceutil/trace.go:172","msg":"trace[2083720880] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:16; response_revision:4735; }","duration":"149.298225ms","start":"2026-04-01T10:30:29.745887Z","end":"2026-04-01T10:30:29.895185Z","steps":["trace[2083720880] 'agreement among raft nodes before linearized reading' (duration: 148.67592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.895411Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"217.426511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipaddresses/\" range_end:\"/registry/ipaddresses0\" limit:10000 ","response":"range_response_count:3 size:1783"} {"level":"warn","ts":"2026-04-01T10:30:29.895043Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"217.029042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipaddresses\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T10:30:29.895481Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"266.728551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" limit:10000 ","response":"range_response_count:3 size:3353"} {"level":"info","ts":"2026-04-01T10:30:29.895526Z","caller":"traceutil/trace.go:172","msg":"trace[209186666] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:3; response_revision:4735; }","duration":"266.775592ms","start":"2026-04-01T10:30:29.628741Z","end":"2026-04-01T10:30:29.895517Z","steps":["trace[209186666] 'agreement among raft nodes before linearized reading' (duration: 266.651009ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:29.895456Z","caller":"traceutil/trace.go:172","msg":"trace[390481466] range","detail":"{range_begin:/registry/ipaddresses/; range_end:/registry/ipaddresses0; response_count:3; response_revision:4735; }","duration":"217.474252ms","start":"2026-04-01T10:30:29.677972Z","end":"2026-04-01T10:30:29.895446Z","steps":["trace[390481466] 'agreement among raft nodes before linearized reading' (duration: 217.274628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.895562Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"126.083037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" limit:10000 ","response":"range_response_count:60 size:43385"} {"level":"info","ts":"2026-04-01T10:30:29.895598Z","caller":"traceutil/trace.go:172","msg":"trace[461549104] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:60; response_revision:4735; }","duration":"126.119348ms","start":"2026-04-01T10:30:29.769467Z","end":"2026-04-01T10:30:29.895586Z","steps":["trace[461549104] 'agreement among raft nodes before linearized reading' (duration: 125.76895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.895639Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"234.116228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.895671Z","caller":"traceutil/trace.go:172","msg":"trace[1089223490] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:4735; }","duration":"234.149908ms","start":"2026-04-01T10:30:29.661514Z","end":"2026-04-01T10:30:29.895664Z","steps":["trace[1089223490] 'agreement among raft nodes before linearized reading' (duration: 234.098037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.895708Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"250.527316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.895744Z","caller":"traceutil/trace.go:172","msg":"trace[958769675] range","detail":"{range_begin:/registry/networkpolicies; range_end:; response_count:0; response_revision:4735; }","duration":"250.568567ms","start":"2026-04-01T10:30:29.645167Z","end":"2026-04-01T10:30:29.895736Z","steps":["trace[958769675] 'agreement among raft nodes before linearized reading' (duration: 250.507866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.895790Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"225.021286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.895815Z","caller":"traceutil/trace.go:172","msg":"trace[627708589] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:4735; }","duration":"225.047537ms","start":"2026-04-01T10:30:29.670761Z","end":"2026-04-01T10:30:29.895809Z","steps":["trace[627708589] 'agreement among raft nodes before linearized reading' (duration: 225.005696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.895901Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"250.753142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.895934Z","caller":"traceutil/trace.go:172","msg":"trace[1434510567] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:4735; }","duration":"250.788332ms","start":"2026-04-01T10:30:29.645138Z","end":"2026-04-01T10:30:29.895926Z","steps":["trace[1434510567] 'agreement among raft nodes before linearized reading' (duration: 250.736261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.895953Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"225.251702ms","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-01T10:30:29.895088Z","caller":"traceutil/trace.go:172","msg":"trace[1434373474] range","detail":"{range_begin:/registry/roles; range_end:; response_count:0; response_revision:4735; }","duration":"158.60742ms","start":"2026-04-01T10:30:29.736469Z","end":"2026-04-01T10:30:29.895077Z","steps":["trace[1434373474] 'agreement among raft nodes before linearized reading' (duration: 157.557545ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:29.895974Z","caller":"traceutil/trace.go:172","msg":"trace[512585554] range","detail":"{range_begin:/registry/ingressclasses; range_end:; response_count:0; response_revision:4735; }","duration":"225.274242ms","start":"2026-04-01T10:30:29.670694Z","end":"2026-04-01T10:30:29.895968Z","steps":["trace[512585554] 'agreement among raft nodes before linearized reading' (duration: 225.235871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.896071Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"234.514477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.896097Z","caller":"traceutil/trace.go:172","msg":"trace[1550159107] range","detail":"{range_begin:/registry/ingress; range_end:; response_count:0; response_revision:4735; }","duration":"234.540467ms","start":"2026-04-01T10:30:29.661550Z","end":"2026-04-01T10:30:29.896090Z","steps":["trace[1550159107] 'agreement among raft nodes before linearized reading' (duration: 234.499716ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:29.895492Z","caller":"traceutil/trace.go:172","msg":"trace[2088426413] range","detail":"{range_begin:/registry/ipaddresses; range_end:; response_count:0; response_revision:4735; }","duration":"217.453402ms","start":"2026-04-01T10:30:29.678002Z","end":"2026-04-01T10:30:29.895456Z","steps":["trace[2088426413] 'agreement among raft nodes before linearized reading' (duration: 217.004861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.896069Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"267.294814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:29.896607Z","caller":"traceutil/trace.go:172","msg":"trace[1127994843] range","detail":"{range_begin:/registry/endpointslices; range_end:; response_count:0; response_revision:4735; }","duration":"267.832517ms","start":"2026-04-01T10:30:29.628765Z","end":"2026-04-01T10:30:29.896598Z","steps":["trace[1127994843] 'agreement among raft nodes before linearized reading' (duration: 267.277964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:29.911310Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54296","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.918368Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54308","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.928891Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54338","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.938706Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54350","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.947891Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54378","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.957484Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54398","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:29.975573Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54422","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:30.021167Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54442","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:30.029372Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54468","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:30.038512Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54482","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:30.088615Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:54508","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-01T10:30:30.539670Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"531.26392ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-01T10:30:30.539751Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"574.498069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingadmissionpolicybindings/\" range_end:\"/registry/validatingadmissionpolicybindings0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T10:30:30.539767Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"574.644432ms","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-01T10:30:30.539798Z","caller":"traceutil/trace.go:172","msg":"trace[336438315] linearizableReadLoop","detail":"{readStateIndex:5488; appliedIndex:5487; }","duration":"519.277842ms","start":"2026-04-01T10:30:30.020506Z","end":"2026-04-01T10:30:30.539783Z","steps":["trace[336438315] 'read index received' (duration: 1.042424ms)","trace[336438315] 'applied index is now lower than readState.Index' (duration: 518.235008ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T10:30:30.539802Z","caller":"traceutil/trace.go:172","msg":"trace[798386995] range","detail":"{range_begin:/registry/validatingadmissionpolicybindings/; range_end:/registry/validatingadmissionpolicybindings0; response_count:0; response_revision:4738; }","duration":"574.567031ms","start":"2026-04-01T10:30:29.965213Z","end":"2026-04-01T10:30:30.539780Z","steps":["trace[798386995] 'agreement among raft nodes before linearized reading' (duration: 43.897705ms)","trace[798386995] 'range keys from in-memory index tree' (duration: 530.549012ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T10:30:30.539813Z","caller":"traceutil/trace.go:172","msg":"trace[1922413345] range","detail":"{range_begin:/registry/validatingadmissionpolicybindings; range_end:; response_count:0; response_revision:4738; }","duration":"574.679263ms","start":"2026-04-01T10:30:29.965114Z","end":"2026-04-01T10:30:30.539794Z","steps":["trace[1922413345] 'agreement among raft nodes before linearized reading' (duration: 44.013188ms)","trace[1922413345] 'range keys from in-memory index tree' (duration: 530.44944ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:30:30.539865Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:29.965096Z","time spent":"574.761385ms","remote":"127.0.0.1:54406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/validatingadmissionpolicybindings\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:30:30.539844Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:29.965186Z","time spent":"574.647913ms","remote":"127.0.0.1:54406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/validatingadmissionpolicybindings/\" range_end:\"/registry/validatingadmissionpolicybindings0\" limit:10000 "} {"level":"warn","ts":"2026-04-01T10:30:30.539914Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"519.407955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deviceclasses\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T10:30:30.539690Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"583.044867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingadmissionpolicies\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:30.539976Z","caller":"traceutil/trace.go:172","msg":"trace[506273600] range","detail":"{range_begin:/registry/deviceclasses; range_end:; response_count:0; response_revision:4738; }","duration":"519.441895ms","start":"2026-04-01T10:30:30.020500Z","end":"2026-04-01T10:30:30.539942Z","steps":["trace[506273600] 'agreement among raft nodes before linearized reading' (duration: 519.342483ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:30.540001Z","caller":"traceutil/trace.go:172","msg":"trace[498924645] range","detail":"{range_begin:/registry/validatingadmissionpolicies; range_end:; response_count:0; response_revision:4738; }","duration":"583.391325ms","start":"2026-04-01T10:30:29.956603Z","end":"2026-04-01T10:30:30.539994Z","steps":["trace[498924645] 'agreement among raft nodes before linearized reading' (duration: 52.507034ms)","trace[498924645] 'range keys from in-memory index tree' (duration: 530.506861ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:30:30.540033Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:29.956582Z","time spent":"583.442976ms","remote":"127.0.0.1:54388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/validatingadmissionpolicies\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:30:30.539696Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"583.039837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingadmissionpolicies/\" range_end:\"/registry/validatingadmissionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:30.540237Z","caller":"traceutil/trace.go:172","msg":"trace[519080063] range","detail":"{range_begin:/registry/validatingadmissionpolicies/; range_end:/registry/validatingadmissionpolicies0; response_count:0; response_revision:4738; }","duration":"583.560729ms","start":"2026-04-01T10:30:29.956609Z","end":"2026-04-01T10:30:30.540170Z","steps":["trace[519080063] 'agreement among raft nodes before linearized reading' (duration: 52.528095ms)","trace[519080063] 'range keys from in-memory index tree' (duration: 530.476751ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:30:30.540261Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:29.956594Z","time spent":"583.65992ms","remote":"127.0.0.1:54388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/validatingadmissionpolicies/\" range_end:\"/registry/validatingadmissionpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-01T10:30:30.540035Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:30.020475Z","time spent":"519.554088ms","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":27,"response count":0,"response size":28,"request content":"key:\"/registry/deviceclasses\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:30:30.541024Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"566.089455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" keys_only:true ","response":"range_response_count:429 size:38025"} {"level":"info","ts":"2026-04-01T10:30:30.541060Z","caller":"traceutil/trace.go:172","msg":"trace[1981733451] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:429; response_revision:4738; }","duration":"566.127576ms","start":"2026-04-01T10:30:29.974923Z","end":"2026-04-01T10:30:30.541051Z","steps":["trace[1981733451] 'agreement among raft nodes before linearized reading' (duration: 34.212042ms)","trace[1981733451] 'range keys from in-memory index tree' (duration: 530.734277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:30:30.541087Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:29.974888Z","time spent":"566.186207ms","remote":"127.0.0.1:54412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":429,"response size":38048,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" keys_only:true "} {"level":"warn","ts":"2026-04-01T10:30:30.575615Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"529.845717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourceslices\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:30.575676Z","caller":"traceutil/trace.go:172","msg":"trace[1850404203] range","detail":"{range_begin:/registry/resourceslices; range_end:; response_count:0; response_revision:4740; }","duration":"529.914238ms","start":"2026-04-01T10:30:30.045748Z","end":"2026-04-01T10:30:30.575662Z","steps":["trace[1850404203] 'agreement among raft nodes before linearized reading' (duration: 529.816376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:30.575664Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"537.73887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourceclaimtemplates/\" range_end:\"/registry/resourceclaimtemplates0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T10:30:30.575713Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:30.045735Z","time spent":"529.968599ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":28,"request content":"key:\"/registry/resourceslices\" limit:1 "} {"level":"info","ts":"2026-04-01T10:30:30.575736Z","caller":"traceutil/trace.go:172","msg":"trace[37849729] range","detail":"{range_begin:/registry/resourceclaimtemplates/; range_end:/registry/resourceclaimtemplates0; response_count:0; response_revision:4740; }","duration":"537.825212ms","start":"2026-04-01T10:30:30.037895Z","end":"2026-04-01T10:30:30.575720Z","steps":["trace[37849729] 'agreement among raft nodes before linearized reading' (duration: 537.702079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:30.575794Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:30.037886Z","time spent":"537.891363ms","remote":"127.0.0.1:54470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":28,"request content":"key:\"/registry/resourceclaimtemplates/\" range_end:\"/registry/resourceclaimtemplates0\" limit:10000 "} {"level":"warn","ts":"2026-04-01T10:30:30.575770Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"555.192672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deviceclasses/\" range_end:\"/registry/deviceclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:30.575875Z","caller":"traceutil/trace.go:172","msg":"trace[563036226] range","detail":"{range_begin:/registry/deviceclasses/; range_end:/registry/deviceclasses0; response_count:0; response_revision:4740; }","duration":"555.316146ms","start":"2026-04-01T10:30:30.020535Z","end":"2026-04-01T10:30:30.575851Z","steps":["trace[563036226] 'agreement among raft nodes before linearized reading' (duration: 555.152142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:30.575936Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:30.020510Z","time spent":"555.413547ms","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":28,"request content":"key:\"/registry/deviceclasses/\" range_end:\"/registry/deviceclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-01T10:30:30.575977Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"127.016335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" limit:1 ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-01T10:30:30.576017Z","caller":"traceutil/trace.go:172","msg":"trace[1533113657] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:4740; }","duration":"127.052226ms","start":"2026-04-01T10:30:30.448953Z","end":"2026-04-01T10:30:30.576005Z","steps":["trace[1533113657] 'agreement among raft nodes before linearized reading' (duration: 126.920273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:30.576051Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"530.313587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourceslices/\" range_end:\"/registry/resourceslices0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:30.576088Z","caller":"traceutil/trace.go:172","msg":"trace[2122699219] range","detail":"{range_begin:/registry/resourceslices/; range_end:/registry/resourceslices0; response_count:0; response_revision:4740; }","duration":"530.354838ms","start":"2026-04-01T10:30:30.045725Z","end":"2026-04-01T10:30:30.576080Z","steps":["trace[2122699219] 'agreement among raft nodes before linearized reading' (duration: 530.280286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:30.576115Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:30.045686Z","time spent":"530.420979ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":28,"request content":"key:\"/registry/resourceslices/\" range_end:\"/registry/resourceslices0\" limit:10000 "} {"level":"warn","ts":"2026-04-01T10:30:30.576125Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"118.040258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-controller-2\" limit:1 ","response":"range_response_count:1 size:7523"} {"level":"info","ts":"2026-04-01T10:30:30.577736Z","caller":"traceutil/trace.go:172","msg":"trace[337046747] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-controller-2; range_end:; response_count:1; response_revision:4740; }","duration":"119.852899ms","start":"2026-04-01T10:30:30.457863Z","end":"2026-04-01T10:30:30.577715Z","steps":["trace[337046747] 'agreement among raft nodes before linearized reading' (duration: 117.69592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:30.576254Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"220.340074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"compact_rev_key\" ","response":"range_response_count:1 size:40"} {"level":"info","ts":"2026-04-01T10:30:30.578000Z","caller":"traceutil/trace.go:172","msg":"trace[1125734086] range","detail":"{range_begin:compact_rev_key; range_end:; response_count:1; response_revision:4740; }","duration":"222.080464ms","start":"2026-04-01T10:30:30.355907Z","end":"2026-04-01T10:30:30.577987Z","steps":["trace[1125734086] 'agreement among raft nodes before linearized reading' (duration: 220.259082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:30.576293Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"481.310105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:30.578199Z","caller":"traceutil/trace.go:172","msg":"trace[2101343662] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices; range_end:; response_count:0; response_revision:4740; }","duration":"483.21502ms","start":"2026-04-01T10:30:30.094975Z","end":"2026-04-01T10:30:30.578190Z","steps":["trace[2101343662] 'agreement among raft nodes before linearized reading' (duration: 481.296525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:30.578238Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:30.094898Z","time spent":"483.325192ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:30:30.576303Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"538.135609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourceclaimtemplates\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:30.578381Z","caller":"traceutil/trace.go:172","msg":"trace[190812888] range","detail":"{range_begin:/registry/resourceclaimtemplates; range_end:; response_count:0; response_revision:4740; }","duration":"540.209887ms","start":"2026-04-01T10:30:30.038159Z","end":"2026-04-01T10:30:30.578368Z","steps":["trace[190812888] 'agreement among raft nodes before linearized reading' (duration: 538.114628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:30.578421Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:30.038126Z","time spent":"540.284508ms","remote":"127.0.0.1:54470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/resourceclaimtemplates\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:30:30.576271Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"547.570967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourceclaims\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T10:30:30.576452Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"481.53877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" limit:10000 ","response":"range_response_count:29 size:28045"} {"level":"info","ts":"2026-04-01T10:30:30.578737Z","caller":"traceutil/trace.go:172","msg":"trace[1819244281] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:29; response_revision:4740; }","duration":"483.819833ms","start":"2026-04-01T10:30:30.094906Z","end":"2026-04-01T10:30:30.578726Z","steps":["trace[1819244281] 'agreement among raft nodes before linearized reading' (duration: 481.398607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:30.578803Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:30.094880Z","time spent":"483.882535ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":29,"response size":28068,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" limit:10000 "} {"level":"warn","ts":"2026-04-01T10:30:30.576982Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"112.246154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-zjy5m4qienmmbtxvgklj3tgz6i\" limit:1 ","response":"range_response_count:1 size:691"} {"level":"info","ts":"2026-04-01T10:30:30.578947Z","caller":"traceutil/trace.go:172","msg":"trace[1913333344] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-zjy5m4qienmmbtxvgklj3tgz6i; range_end:; response_count:1; response_revision:4740; }","duration":"114.165579ms","start":"2026-04-01T10:30:30.464725Z","end":"2026-04-01T10:30:30.578891Z","steps":["trace[1913333344] 'agreement among raft nodes before linearized reading' (duration: 112.137502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:30.578928Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"550.247649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourceclaims/\" range_end:\"/registry/resourceclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:30.579006Z","caller":"traceutil/trace.go:172","msg":"trace[1713178114] range","detail":"{range_begin:/registry/resourceclaims/; range_end:/registry/resourceclaims0; response_count:0; response_revision:4740; }","duration":"550.328481ms","start":"2026-04-01T10:30:30.028668Z","end":"2026-04-01T10:30:30.578997Z","steps":["trace[1713178114] 'agreement among raft nodes before linearized reading' (duration: 550.216519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:30.579082Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:30.028657Z","time spent":"550.396982ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":28,"request content":"key:\"/registry/resourceclaims/\" range_end:\"/registry/resourceclaims0\" limit:10000 "} {"level":"info","ts":"2026-04-01T10:30:30.578487Z","caller":"traceutil/trace.go:172","msg":"trace[1515864254] range","detail":"{range_begin:/registry/resourceclaims; range_end:; response_count:0; response_revision:4740; }","duration":"549.797948ms","start":"2026-04-01T10:30:30.028679Z","end":"2026-04-01T10:30:30.578477Z","steps":["trace[1515864254] 'agreement among raft nodes before linearized reading' (duration: 546.927282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:30.580855Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:30.028667Z","time spent":"552.168454ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":28,"request content":"key:\"/registry/resourceclaims\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:30:30.662421Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"116.108244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:30.662489Z","caller":"traceutil/trace.go:172","msg":"trace[525218818] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:0; response_revision:4742; }","duration":"116.190036ms","start":"2026-04-01T10:30:30.546288Z","end":"2026-04-01T10:30:30.662478Z","steps":["trace[525218818] 'agreement among raft nodes before linearized reading' (duration: 116.076523ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:30.791284Z","caller":"traceutil/trace.go:172","msg":"trace[491447447] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"103.937032ms","start":"2026-04-01T10:30:30.687334Z","end":"2026-04-01T10:30:30.791271Z","steps":["trace[491447447] 'process raft request' (duration: 103.906311ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:30.791655Z","caller":"traceutil/trace.go:172","msg":"trace[2020893677] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"108.14372ms","start":"2026-04-01T10:30:30.683504Z","end":"2026-04-01T10:30:30.791648Z","steps":["trace[2020893677] 'process raft request' (duration: 107.71704ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:30.791730Z","caller":"traceutil/trace.go:172","msg":"trace[20729967] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"108.459637ms","start":"2026-04-01T10:30:30.683262Z","end":"2026-04-01T10:30:30.791721Z","steps":["trace[20729967] 'process raft request' (duration: 101.541377ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:30.791656Z","caller":"traceutil/trace.go:172","msg":"trace[1730902119] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"108.17359ms","start":"2026-04-01T10:30:30.683465Z","end":"2026-04-01T10:30:30.791639Z","steps":["trace[1730902119] 'process raft request' (duration: 107.73414ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:30.791840Z","caller":"traceutil/trace.go:172","msg":"trace[1203451441] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"108.495028ms","start":"2026-04-01T10:30:30.683333Z","end":"2026-04-01T10:30:30.791828Z","steps":["trace[1203451441] 'process raft request' (duration: 107.839283ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:30.958985Z","caller":"traceutil/trace.go:172","msg":"trace[2144468894] linearizableReadLoop","detail":"{readStateIndex:5505; appliedIndex:5505; }","duration":"115.638723ms","start":"2026-04-01T10:30:30.843323Z","end":"2026-04-01T10:30:30.958962Z","steps":["trace[2144468894] 'read index received' (duration: 115.632553ms)","trace[2144468894] 'applied index is now lower than readState.Index' (duration: 4.78µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:30:30.959159Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"115.812008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:30.959238Z","caller":"traceutil/trace.go:172","msg":"trace[1553334064] range","detail":"{range_begin:/registry/limitranges; range_end:; response_count:0; response_revision:4751; }","duration":"115.8978ms","start":"2026-04-01T10:30:30.843310Z","end":"2026-04-01T10:30:30.959208Z","steps":["trace[1553334064] 'agreement among raft nodes before linearized reading' (duration: 115.737616ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:30.959550Z","caller":"traceutil/trace.go:172","msg":"trace[2013169571] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4752; }","duration":"146.180759ms","start":"2026-04-01T10:30:30.813361Z","end":"2026-04-01T10:30:30.959542Z","steps":["trace[2013169571] 'process raft request' (duration: 145.669508ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:30.961713Z","caller":"traceutil/trace.go:172","msg":"trace[228285963] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4753; }","duration":"147.748095ms","start":"2026-04-01T10:30:30.813954Z","end":"2026-04-01T10:30:30.961702Z","steps":["trace[228285963] 'process raft request' (duration: 145.162686ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:30.961721Z","caller":"traceutil/trace.go:172","msg":"trace[1154648418] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4754; }","duration":"147.294044ms","start":"2026-04-01T10:30:30.814186Z","end":"2026-04-01T10:30:30.961480Z","steps":["trace[1154648418] 'process raft request' (duration: 147.263264ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:31.477252Z","caller":"traceutil/trace.go:172","msg":"trace[1184839359] linearizableReadLoop","detail":"{readStateIndex:5511; appliedIndex:5511; }","duration":"177.817537ms","start":"2026-04-01T10:30:31.299401Z","end":"2026-04-01T10:30:31.477218Z","steps":["trace[1184839359] 'read index received' (duration: 177.811547ms)","trace[1184839359] 'applied index is now lower than readState.Index' (duration: 4.83µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T10:30:31.477291Z","caller":"traceutil/trace.go:172","msg":"trace[574631349] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"505.36791ms","start":"2026-04-01T10:30:30.971908Z","end":"2026-04-01T10:30:31.477276Z","steps":["trace[574631349] 'process raft request' (duration: 505.34454ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:31.477538Z","caller":"traceutil/trace.go:172","msg":"trace[865773127] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"510.759495ms","start":"2026-04-01T10:30:30.966742Z","end":"2026-04-01T10:30:31.477502Z","steps":["trace[865773127] 'process raft request' (duration: 510.381587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:31.477760Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:30.971888Z","time spent":"505.493414ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6075,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T10:30:31.477864Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:30.966735Z","time spent":"510.928149ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3316,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T10:30:31.477741Z","caller":"traceutil/trace.go:172","msg":"trace[1316125024] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"510.713564ms","start":"2026-04-01T10:30:30.966997Z","end":"2026-04-01T10:30:31.477711Z","steps":["trace[1316125024] 'process raft request' (duration: 510.229243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:31.478045Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:30.966991Z","time spent":"510.928719ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4339,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T10:30:31.478151Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"178.701197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:511"} {"level":"info","ts":"2026-04-01T10:30:31.478197Z","caller":"traceutil/trace.go:172","msg":"trace[1114809080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4760; }","duration":"178.791179ms","start":"2026-04-01T10:30:31.299396Z","end":"2026-04-01T10:30:31.478187Z","steps":["trace[1114809080] 'agreement among raft nodes before linearized reading' (duration: 178.41655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:31.480295Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"140.062214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-controller-2\" limit:1 ","response":"range_response_count:1 size:7591"} {"level":"info","ts":"2026-04-01T10:30:31.480400Z","caller":"traceutil/trace.go:172","msg":"trace[160437549] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-controller-2; range_end:; response_count:1; response_revision:4760; }","duration":"140.194047ms","start":"2026-04-01T10:30:31.340184Z","end":"2026-04-01T10:30:31.480378Z","steps":["trace[160437549] 'agreement among raft nodes before linearized reading' (duration: 139.845499ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:31.687857Z","caller":"traceutil/trace.go:172","msg":"trace[827166626] linearizableReadLoop","detail":"{readStateIndex:5515; appliedIndex:5515; }","duration":"205.153157ms","start":"2026-04-01T10:30:31.482672Z","end":"2026-04-01T10:30:31.687825Z","steps":["trace[827166626] 'read index received' (duration: 205.142946ms)","trace[827166626] 'applied index is now lower than readState.Index' (duration: 8.741µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:30:31.688129Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"205.436853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" limit:1 ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-01T10:30:31.688203Z","caller":"traceutil/trace.go:172","msg":"trace[1043864035] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:1; response_revision:4760; }","duration":"205.535245ms","start":"2026-04-01T10:30:31.482653Z","end":"2026-04-01T10:30:31.688189Z","steps":["trace[1043864035] 'agreement among raft nodes before linearized reading' (duration: 205.276069ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:31.688311Z","caller":"traceutil/trace.go:172","msg":"trace[1521517970] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"205.30244ms","start":"2026-04-01T10:30:31.482991Z","end":"2026-04-01T10:30:31.688294Z","steps":["trace[1521517970] 'process raft request' (duration: 204.915082ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:31.829888Z","caller":"traceutil/trace.go:172","msg":"trace[1000774184] linearizableReadLoop","detail":"{readStateIndex:5517; appliedIndex:5517; }","duration":"141.919966ms","start":"2026-04-01T10:30:31.687940Z","end":"2026-04-01T10:30:31.829860Z","steps":["trace[1000774184] 'read index received' (duration: 141.911296ms)","trace[1000774184] 'applied index is now lower than readState.Index' (duration: 7.42µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T10:30:31.830023Z","caller":"traceutil/trace.go:172","msg":"trace[1183432529] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4762; }","duration":"139.095912ms","start":"2026-04-01T10:30:31.690911Z","end":"2026-04-01T10:30:31.830007Z","steps":["trace[1183432529] 'process raft request' (duration: 138.993068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:31.830127Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"343.790268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:30:31.830175Z","caller":"traceutil/trace.go:172","msg":"trace[964779820] range","detail":"{range_begin:/registry/clusterrolebindings; range_end:; response_count:0; response_revision:4762; }","duration":"343.852589ms","start":"2026-04-01T10:30:31.486313Z","end":"2026-04-01T10:30:31.830165Z","steps":["trace[964779820] 'agreement among raft nodes before linearized reading' (duration: 343.739827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:31.830219Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:31.486304Z","time spent":"343.90554ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":33,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings\" limit:1 "} {"level":"info","ts":"2026-04-01T10:30:31.990003Z","caller":"traceutil/trace.go:172","msg":"trace[1314310504] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"127.478404ms","start":"2026-04-01T10:30:31.862506Z","end":"2026-04-01T10:30:31.989984Z","steps":["trace[1314310504] 'process raft request' (duration: 127.369341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:32.006399Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"171.738205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" limit:1 ","response":"range_response_count:1 size:840"} {"level":"info","ts":"2026-04-01T10:30:32.006486Z","caller":"traceutil/trace.go:172","msg":"trace[2021525390] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:1; response_revision:4765; }","duration":"171.841618ms","start":"2026-04-01T10:30:31.834627Z","end":"2026-04-01T10:30:32.006468Z","steps":["trace[2021525390] 'agreement among raft nodes before linearized reading' (duration: 171.604822ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:32.111228Z","caller":"traceutil/trace.go:172","msg":"trace[477799840] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"103.278733ms","start":"2026-04-01T10:30:32.007921Z","end":"2026-04-01T10:30:32.111200Z","steps":["trace[477799840] 'process raft request' (duration: 62.889291ms)","trace[477799840] 'compare' (duration: 40.234609ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T10:30:32.236048Z","caller":"traceutil/trace.go:172","msg":"trace[1219859356] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"121.682467ms","start":"2026-04-01T10:30:32.114349Z","end":"2026-04-01T10:30:32.236031Z","steps":["trace[1219859356] 'process raft request' (duration: 121.534804ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:32.236954Z","caller":"traceutil/trace.go:172","msg":"trace[935971414] linearizableReadLoop","detail":"{readStateIndex:5524; appliedIndex:5525; }","duration":"228.092503ms","start":"2026-04-01T10:30:32.008848Z","end":"2026-04-01T10:30:32.236941Z","steps":["trace[935971414] 'read index received' (duration: 228.089003ms)","trace[935971414] 'applied index is now lower than readState.Index' (duration: 2.74µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:30:32.237285Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"228.41731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" limit:1 ","response":"range_response_count:1 size:2514"} {"level":"info","ts":"2026-04-01T10:30:32.237355Z","caller":"traceutil/trace.go:172","msg":"trace[1904521853] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:1; response_revision:4768; }","duration":"228.504172ms","start":"2026-04-01T10:30:32.008837Z","end":"2026-04-01T10:30:32.237341Z","steps":["trace[1904521853] 'agreement among raft nodes before linearized reading' (duration: 228.277697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:33.020708Z","caller":"rafthttp/stream.go:420","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3","error":"unexpected EOF"} {"level":"warn","ts":"2026-04-01T10:30:33.020890Z","caller":"rafthttp/peer_status.go:66","msg":"peer became inactive (message send to peer failed)","peer-id":"4951500f65e17c3","error":"failed to read 4951500f65e17c3 on stream MsgApp v2 (unexpected EOF)"} {"level":"warn","ts":"2026-04-01T10:30:33.020764Z","caller":"rafthttp/stream.go:420","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3","error":"unexpected EOF"} {"level":"warn","ts":"2026-04-01T10:30:33.509451Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":496694794034542796,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T10:30:33.529545Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"11b599b5a29506e4","to":"4951500f65e17c3","stream-type":"stream MsgApp v2"} {"level":"info","ts":"2026-04-01T10:30:33.529619Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"4951500f65e17c3"} {"level":"warn","ts":"2026-04-01T10:30:33.529674Z","caller":"rafthttp/stream.go:264","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:33.529725Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:33.532584Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"11b599b5a29506e4","to":"4951500f65e17c3","stream-type":"stream Message"} {"level":"warn","ts":"2026-04-01T10:30:33.532622Z","caller":"rafthttp/stream.go:264","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:33.532640Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:33.550175Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3"} {"level":"info","ts":"2026-04-01T10:30:33.550655Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"11b599b5a29506e4","remote-peer-id":"4951500f65e17c3"} {"level":"warn","ts":"2026-04-01T10:30:34.010066Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":496694794034542796,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T10:30:34.074313Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1233","msg":"11b599b5a29506e4 [logterm: 8, index: 5566, vote: 4951500f65e17c3] cast MsgPreVote for 4951500f65e17c3 [logterm: 8, index: 5566] at term 8"} {"level":"info","ts":"2026-04-01T10:30:34.077831Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1116","msg":"11b599b5a29506e4 [term: 8] received a MsgVote message with higher term from 4951500f65e17c3 [term: 9]"} {"level":"info","ts":"2026-04-01T10:30:34.077901Z","logger":"raft","caller":"v3@v3.6.0/raft.go:897","msg":"11b599b5a29506e4 became follower at term 9"} {"level":"info","ts":"2026-04-01T10:30:34.077927Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1233","msg":"11b599b5a29506e4 [logterm: 8, index: 5566, vote: 0] cast MsgVote for 4951500f65e17c3 [logterm: 8, index: 5566] at term 9"} {"level":"info","ts":"2026-04-01T10:30:34.077948Z","logger":"raft","caller":"v3@v3.6.0/node.go:376","msg":"raft.node: 11b599b5a29506e4 lost leader 4951500f65e17c3 at term 9"} {"level":"info","ts":"2026-04-01T10:30:34.082773Z","logger":"raft","caller":"v3@v3.6.0/node.go:370","msg":"raft.node: 11b599b5a29506e4 elected leader 4951500f65e17c3 at term 9"} {"level":"warn","ts":"2026-04-01T10:30:34.083056Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.073683611s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-dns\" limit:1 ","response":"","error":"etcdserver: leader changed"} {"level":"info","ts":"2026-04-01T10:30:34.083193Z","caller":"traceutil/trace.go:172","msg":"trace[1218441235] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-dns; range_end:; }","duration":"1.073852214s","start":"2026-04-01T10:30:33.009306Z","end":"2026-04-01T10:30:34.083159Z","steps":["trace[1218441235] 'agreement among raft nodes before linearized reading' (duration: 1.07367621s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:34.083269Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:33.009299Z","time spent":"1.073956537s","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":0,"request content":"key:\"/registry/clusterrolebindings/system:kube-dns\" limit:1 "} {"level":"info","ts":"2026-04-01T10:30:34.085294Z","caller":"etcdserver/v3_server.go:903","msg":"first commit in current term: resending ReadIndex request"} {"level":"warn","ts":"2026-04-01T10:30:34.086828Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"220.882798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T10:30:34.086870Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"566.959953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:511"} {"level":"info","ts":"2026-04-01T10:30:34.086912Z","caller":"traceutil/trace.go:172","msg":"trace[989812905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4809; }","duration":"220.995511ms","start":"2026-04-01T10:30:33.865894Z","end":"2026-04-01T10:30:34.086889Z","steps":["trace[989812905] 'agreement among raft nodes before linearized reading' (duration: 220.815407ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:34.086939Z","caller":"traceutil/trace.go:172","msg":"trace[2144166116] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4809; }","duration":"567.033865ms","start":"2026-04-01T10:30:33.519888Z","end":"2026-04-01T10:30:34.086922Z","steps":["trace[2144166116] 'agreement among raft nodes before linearized reading' (duration: 566.785899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:34.086977Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:30:33.519860Z","time spent":"567.108266ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:30:34.088371Z","caller":"etcdserver/v3_server.go:888","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":496694794034542798,"received-request-id":496694794034542797} {"level":"warn","ts":"2026-04-01T10:30:34.402803Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"102.915799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/extension-apiserver-authentication-reader\" limit:1 ","response":"range_response_count:1 size:671"} {"level":"info","ts":"2026-04-01T10:30:34.402882Z","caller":"traceutil/trace.go:172","msg":"trace[327260167] range","detail":"{range_begin:/registry/roles/kube-system/extension-apiserver-authentication-reader; range_end:; response_count:1; response_revision:4813; }","duration":"103.020722ms","start":"2026-04-01T10:30:34.299845Z","end":"2026-04-01T10:30:34.402866Z","steps":["trace[327260167] 'range keys from in-memory index tree' (duration: 102.07252ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:34.818123Z","caller":"traceutil/trace.go:172","msg":"trace[870688092] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"108.687972ms","start":"2026-04-01T10:30:34.709417Z","end":"2026-04-01T10:30:34.818105Z","steps":["trace[870688092] 'process raft request' (duration: 108.555469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:30:40.949160Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"133.809512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T10:30:41.073810Z","caller":"traceutil/trace.go:172","msg":"trace[331798480] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"169.371483ms","start":"2026-04-01T10:30:40.904415Z","end":"2026-04-01T10:30:41.073786Z","steps":["trace[331798480] 'process raft request' (duration: 169.123738ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:41.482468Z","caller":"traceutil/trace.go:172","msg":"trace[1532816694] linearizableReadLoop","detail":"{readStateIndex:5639; appliedIndex:5639; }","duration":"129.265884ms","start":"2026-04-01T10:30:41.353163Z","end":"2026-04-01T10:30:41.482429Z","steps":["trace[1532816694] 'read index received' (duration: 129.247854ms)","trace[1532816694] 'applied index is now lower than readState.Index' (duration: 16.14µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:30:41.482741Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"129.548511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-controller-2\" limit:1 ","response":"range_response_count:1 size:7801"} {"level":"info","ts":"2026-04-01T10:30:41.482847Z","caller":"traceutil/trace.go:172","msg":"trace[1168993496] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-controller-2; range_end:; response_count:1; response_revision:4876; }","duration":"129.654903ms","start":"2026-04-01T10:30:41.353156Z","end":"2026-04-01T10:30:41.482811Z","steps":["trace[1168993496] 'agreement among raft nodes before linearized reading' (duration: 129.396797ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:41.679354Z","caller":"traceutil/trace.go:172","msg":"trace[116950477] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"186.912032ms","start":"2026-04-01T10:30:41.492429Z","end":"2026-04-01T10:30:41.679341Z","steps":["trace[116950477] 'process raft request' (duration: 186.790159ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:44.798770Z","caller":"traceutil/trace.go:172","msg":"trace[1755935724] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"123.647009ms","start":"2026-04-01T10:30:44.675103Z","end":"2026-04-01T10:30:44.798750Z","steps":["trace[1755935724] 'process raft request' (duration: 123.520836ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:49.010695Z","caller":"traceutil/trace.go:172","msg":"trace[1145020588] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"101.448843ms","start":"2026-04-01T10:30:48.909223Z","end":"2026-04-01T10:30:49.010672Z","steps":["trace[1145020588] 'process raft request' (duration: 78.86014ms)","trace[1145020588] 'compare' (duration: 22.487491ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T10:30:50.025382Z","caller":"traceutil/trace.go:172","msg":"trace[1082283820] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"114.308122ms","start":"2026-04-01T10:30:49.911049Z","end":"2026-04-01T10:30:50.025357Z","steps":["trace[1082283820] 'process raft request' (duration: 114.161699ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:51.485256Z","caller":"traceutil/trace.go:172","msg":"trace[784729155] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"177.604791ms","start":"2026-04-01T10:30:51.307633Z","end":"2026-04-01T10:30:51.485238Z","steps":["trace[784729155] 'process raft request' (duration: 177.375266ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:30:51.768285Z","caller":"traceutil/trace.go:172","msg":"trace[328390401] linearizableReadLoop","detail":"{readStateIndex:5768; appliedIndex:5767; }","duration":"227.827106ms","start":"2026-04-01T10:30:51.540436Z","end":"2026-04-01T10:30:51.768263Z","steps":["trace[328390401] 'read index received' (duration: 206.213867ms)","trace[328390401] 'applied index is now lower than readState.Index' (duration: 21.611549ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:30:51.768453Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"227.989811ms","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-01T10:30:51.768537Z","caller":"traceutil/trace.go:172","msg":"trace[2062099265] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4995; }","duration":"228.092623ms","start":"2026-04-01T10:30:51.540428Z","end":"2026-04-01T10:30:51.768521Z","steps":["trace[2062099265] 'agreement among raft nodes before linearized reading' (duration: 227.94069ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:31:02.735962Z","caller":"traceutil/trace.go:172","msg":"trace[815401143] linearizableReadLoop","detail":"{readStateIndex:5824; appliedIndex:5824; }","duration":"151.243169ms","start":"2026-04-01T10:31:02.584702Z","end":"2026-04-01T10:31:02.735946Z","steps":["trace[815401143] 'read index received' (duration: 151.239229ms)","trace[815401143] 'applied index is now lower than readState.Index' (duration: 3.08µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:31:02.835437Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"250.699256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:484"} {"level":"info","ts":"2026-04-01T10:31:02.835613Z","caller":"traceutil/trace.go:172","msg":"trace[1795683142] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5044; }","duration":"250.893032ms","start":"2026-04-01T10:31:02.584697Z","end":"2026-04-01T10:31:02.835590Z","steps":["trace[1795683142] 'agreement among raft nodes before linearized reading' (duration: 151.350911ms)","trace[1795683142] 'range keys from in-memory index tree' (duration: 99.216813ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T10:31:08.652932Z","caller":"traceutil/trace.go:172","msg":"trace[1273070021] linearizableReadLoop","detail":"{readStateIndex:5848; appliedIndex:5848; }","duration":"156.674251ms","start":"2026-04-01T10:31:08.496209Z","end":"2026-04-01T10:31:08.652883Z","steps":["trace[1273070021] 'read index received' (duration: 156.666641ms)","trace[1273070021] 'applied index is now lower than readState.Index' (duration: 6.23µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:31:08.653011Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"156.782473ms","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-01T10:31:08.653081Z","caller":"traceutil/trace.go:172","msg":"trace[597456480] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5065; }","duration":"156.861895ms","start":"2026-04-01T10:31:08.496205Z","end":"2026-04-01T10:31:08.653067Z","steps":["trace[597456480] 'agreement among raft nodes before linearized reading' (duration: 156.759723ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:31:14.826968Z","caller":"traceutil/trace.go:172","msg":"trace[557710831] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"135.765558ms","start":"2026-04-01T10:31:14.691178Z","end":"2026-04-01T10:31:14.826943Z","steps":["trace[557710831] 'process raft request' (duration: 135.668086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:31:18.961380Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"107.483903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:31:18.961448Z","caller":"traceutil/trace.go:172","msg":"trace[316163501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5104; }","duration":"107.564265ms","start":"2026-04-01T10:31:18.853875Z","end":"2026-04-01T10:31:18.961440Z","steps":["trace[316163501] 'agreement among raft nodes before linearized reading' (duration: 107.461682ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:31:18.961284Z","caller":"traceutil/trace.go:172","msg":"trace[1710158955] linearizableReadLoop","detail":"{readStateIndex:5893; appliedIndex:5893; }","duration":"107.330719ms","start":"2026-04-01T10:31:18.853900Z","end":"2026-04-01T10:31:18.961231Z","steps":["trace[1710158955] 'read index received' (duration: 107.325759ms)","trace[1710158955] 'applied index is now lower than readState.Index' (duration: 4.16µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:31:19.389849Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"110.465458ms","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-01T10:31:19.389967Z","caller":"traceutil/trace.go:172","msg":"trace[1553385985] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5107; }","duration":"110.626831ms","start":"2026-04-01T10:31:19.279321Z","end":"2026-04-01T10:31:19.389947Z","steps":["trace[1553385985] 'range keys from in-memory index tree' (duration: 110.391566ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:31:22.178152Z","caller":"traceutil/trace.go:172","msg":"trace[1978094382] linearizableReadLoop","detail":"{readStateIndex:5910; appliedIndex:5909; }","duration":"101.579314ms","start":"2026-04-01T10:31:22.076549Z","end":"2026-04-01T10:31:22.178128Z","steps":["trace[1978094382] 'read index received' (duration: 98.962927ms)","trace[1978094382] 'applied index is now lower than readState.Index' (duration: 2.614347ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:31:22.178351Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"101.782689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:484"} {"level":"info","ts":"2026-04-01T10:31:22.178412Z","caller":"traceutil/trace.go:172","msg":"trace[250797166] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5119; }","duration":"101.860761ms","start":"2026-04-01T10:31:22.076537Z","end":"2026-04-01T10:31:22.178398Z","steps":["trace[250797166] 'agreement among raft nodes before linearized reading' (duration: 101.684337ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:31:36.729792Z","caller":"traceutil/trace.go:172","msg":"trace[1851864113] linearizableReadLoop","detail":"{readStateIndex:5975; appliedIndex:5975; }","duration":"109.885059ms","start":"2026-04-01T10:31:36.619886Z","end":"2026-04-01T10:31:36.729771Z","steps":["trace[1851864113] 'read index received' (duration: 109.879279ms)","trace[1851864113] 'applied index is now lower than readState.Index' (duration: 4.57µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:31:36.729911Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"110.011023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:31:36.729941Z","caller":"traceutil/trace.go:172","msg":"trace[612198694] range","detail":"{range_begin:/registry/volumeattachments; range_end:; response_count:0; response_revision:5174; }","duration":"110.055114ms","start":"2026-04-01T10:31:36.619877Z","end":"2026-04-01T10:31:36.729932Z","steps":["trace[612198694] 'agreement among raft nodes before linearized reading' (duration: 109.982632ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:31:37.809378Z","caller":"traceutil/trace.go:172","msg":"trace[1576567869] linearizableReadLoop","detail":"{readStateIndex:5979; appliedIndex:5979; }","duration":"193.741489ms","start":"2026-04-01T10:31:37.615610Z","end":"2026-04-01T10:31:37.809352Z","steps":["trace[1576567869] 'read index received' (duration: 193.723158ms)","trace[1576567869] 'applied index is now lower than readState.Index' (duration: 10.001µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:31:37.809888Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"194.234399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-01T10:31:37.810333Z","caller":"traceutil/trace.go:172","msg":"trace[1134669267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5179; }","duration":"194.706859ms","start":"2026-04-01T10:31:37.615606Z","end":"2026-04-01T10:31:37.810312Z","steps":["trace[1134669267] 'agreement among raft nodes before linearized reading' (duration: 193.902402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:31:37.811127Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"180.875259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-04-01T10:31:37.811143Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"121.05023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:31:37.811196Z","caller":"traceutil/trace.go:172","msg":"trace[403105994] range","detail":"{range_begin:/registry/resourcequotas; range_end:; response_count:0; response_revision:5179; }","duration":"121.108531ms","start":"2026-04-01T10:31:37.690076Z","end":"2026-04-01T10:31:37.811184Z","steps":["trace[403105994] 'agreement among raft nodes before linearized reading' (duration: 121.014599ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:31:37.811180Z","caller":"traceutil/trace.go:172","msg":"trace[1700562193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5179; }","duration":"180.956981ms","start":"2026-04-01T10:31:37.630212Z","end":"2026-04-01T10:31:37.811169Z","steps":["trace[1700562193] 'agreement among raft nodes before linearized reading' (duration: 180.827678ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:31:37.922676Z","caller":"traceutil/trace.go:172","msg":"trace[981879305] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"107.304421ms","start":"2026-04-01T10:31:37.815334Z","end":"2026-04-01T10:31:37.922638Z","steps":["trace[981879305] 'process raft request' (duration: 68.447418ms)","trace[981879305] 'compare' (duration: 38.536086ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T10:31:49.975358Z","caller":"traceutil/trace.go:172","msg":"trace[116832217] linearizableReadLoop","detail":"{readStateIndex:6037; appliedIndex:6037; }","duration":"121.529994ms","start":"2026-04-01T10:31:49.853807Z","end":"2026-04-01T10:31:49.975337Z","steps":["trace[116832217] 'read index received' (duration: 121.524114ms)","trace[116832217] 'applied index is now lower than readState.Index' (duration: 4.87µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:31:49.975519Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"121.692677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:31:49.975565Z","caller":"traceutil/trace.go:172","msg":"trace[1770348190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5228; }","duration":"121.749669ms","start":"2026-04-01T10:31:49.853802Z","end":"2026-04-01T10:31:49.975552Z","steps":["trace[1770348190] 'agreement among raft nodes before linearized reading' (duration: 121.664697ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:31:52.933648Z","caller":"traceutil/trace.go:172","msg":"trace[1853504576] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"250.23962ms","start":"2026-04-01T10:31:52.683384Z","end":"2026-04-01T10:31:52.933623Z","steps":["trace[1853504576] 'process raft request' (duration: 250.079747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:31:57.089380Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"236.012307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:31:57.089490Z","caller":"traceutil/trace.go:172","msg":"trace[2059959955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5255; }","duration":"236.13562ms","start":"2026-04-01T10:31:56.853332Z","end":"2026-04-01T10:31:57.089468Z","steps":["trace[2059959955] 'range keys from in-memory index tree' (duration: 226.833271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:31:57.474668Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"139.199095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:31:57.476085Z","caller":"traceutil/trace.go:172","msg":"trace[507979917] range","detail":"{range_begin:/registry/configmaps; range_end:; response_count:0; response_revision:5257; }","duration":"139.342298ms","start":"2026-04-01T10:31:57.335427Z","end":"2026-04-01T10:31:57.474770Z","steps":["trace[507979917] 'range keys from in-memory index tree' (duration: 137.342936ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:31:58.387795Z","caller":"traceutil/trace.go:172","msg":"trace[1169196517] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"278.802595ms","start":"2026-04-01T10:31:58.108966Z","end":"2026-04-01T10:31:58.387769Z","steps":["trace[1169196517] 'process raft request' (duration: 278.58606ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:31:59.945227Z","caller":"traceutil/trace.go:172","msg":"trace[431772238] linearizableReadLoop","detail":"{readStateIndex:6081; appliedIndex:6080; }","duration":"250.515486ms","start":"2026-04-01T10:31:59.694689Z","end":"2026-04-01T10:31:59.945204Z","steps":["trace[431772238] 'read index received' (duration: 248.986873ms)","trace[431772238] 'applied index is now lower than readState.Index' (duration: 1.527693ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:31:59.945469Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"250.756722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:484"} {"level":"info","ts":"2026-04-01T10:31:59.945501Z","caller":"traceutil/trace.go:172","msg":"trace[471397206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5266; }","duration":"250.809233ms","start":"2026-04-01T10:31:59.694682Z","end":"2026-04-01T10:31:59.945491Z","steps":["trace[471397206] 'agreement among raft nodes before linearized reading' (duration: 250.66434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:08.033646Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"141.92609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:32:08.033745Z","caller":"traceutil/trace.go:172","msg":"trace[2060271936] range","detail":"{range_begin:/registry/secrets; range_end:; response_count:0; response_revision:5299; }","duration":"142.052873ms","start":"2026-04-01T10:32:07.891674Z","end":"2026-04-01T10:32:08.033727Z","steps":["trace[2060271936] 'range keys from in-memory index tree' (duration: 140.06037ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:12.052410Z","caller":"traceutil/trace.go:172","msg":"trace[1536736116] linearizableReadLoop","detail":"{readStateIndex:6136; appliedIndex:6135; }","duration":"201.698295ms","start":"2026-04-01T10:32:11.850690Z","end":"2026-04-01T10:32:12.052388Z","steps":["trace[1536736116] 'read index received' (duration: 192.160283ms)","trace[1536736116] 'applied index is now lower than readState.Index' (duration: 9.536602ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:12.052567Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"201.862818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:32:12.052645Z","caller":"traceutil/trace.go:172","msg":"trace[1375662210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5313; }","duration":"201.95285ms","start":"2026-04-01T10:32:11.850676Z","end":"2026-04-01T10:32:12.052629Z","steps":["trace[1375662210] 'agreement among raft nodes before linearized reading' (duration: 201.818537ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:12.733167Z","caller":"traceutil/trace.go:172","msg":"trace[793297521] transaction","detail":"{read_only:false; response_revision:5315; number_of_response:1; }","duration":"136.25068ms","start":"2026-04-01T10:32:12.596885Z","end":"2026-04-01T10:32:12.733135Z","steps":["trace[793297521] 'process raft request' (duration: 136.008135ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:19.965477Z","caller":"traceutil/trace.go:172","msg":"trace[486689556] linearizableReadLoop","detail":"{readStateIndex:6169; appliedIndex:6169; }","duration":"111.302159ms","start":"2026-04-01T10:32:19.854154Z","end":"2026-04-01T10:32:19.965456Z","steps":["trace[486689556] 'read index received' (duration: 111.297069ms)","trace[486689556] 'applied index is now lower than readState.Index' (duration: 4.13µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:19.993754Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"139.572754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:32:19.993837Z","caller":"traceutil/trace.go:172","msg":"trace[1357513705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5342; }","duration":"139.681187ms","start":"2026-04-01T10:32:19.854141Z","end":"2026-04-01T10:32:19.993823Z","steps":["trace[1357513705] 'agreement among raft nodes before linearized reading' (duration: 111.409001ms)","trace[1357513705] 'range keys from in-memory index tree' (duration: 28.109342ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:20.344570Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"110.965281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T10:32:20.344671Z","caller":"traceutil/trace.go:172","msg":"trace[609305425] linearizableReadLoop","detail":"{readStateIndex:6171; appliedIndex:6170; }","duration":"159.638952ms","start":"2026-04-01T10:32:20.185016Z","end":"2026-04-01T10:32:20.344655Z","steps":["trace[609305425] 'read index received' (duration: 49.276283ms)","trace[609305425] 'applied index is now lower than readState.Index' (duration: 110.361639ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:20.344814Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"159.792756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:484"} {"level":"info","ts":"2026-04-01T10:32:20.344839Z","caller":"traceutil/trace.go:172","msg":"trace[1976794968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5344; }","duration":"159.823837ms","start":"2026-04-01T10:32:20.185008Z","end":"2026-04-01T10:32:20.344831Z","steps":["trace[1976794968] 'agreement among raft nodes before linearized reading' (duration: 159.689194ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:21.093173Z","caller":"traceutil/trace.go:172","msg":"trace[1504831579] transaction","detail":"{read_only:false; response_revision:5346; number_of_response:1; }","duration":"138.812736ms","start":"2026-04-01T10:32:20.954340Z","end":"2026-04-01T10:32:21.093152Z","steps":["trace[1504831579] 'process raft request' (duration: 138.678083ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:24.649137Z","caller":"traceutil/trace.go:172","msg":"trace[421410926] linearizableReadLoop","detail":"{readStateIndex:6190; appliedIndex:6190; }","duration":"117.598554ms","start":"2026-04-01T10:32:24.531513Z","end":"2026-04-01T10:32:24.649111Z","steps":["trace[421410926] 'read index received' (duration: 117.590283ms)","trace[421410926] 'applied index is now lower than readState.Index' (duration: 6.521µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:24.649287Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"117.756257ms","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-01T10:32:24.649320Z","caller":"traceutil/trace.go:172","msg":"trace[182497570] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5361; }","duration":"117.808078ms","start":"2026-04-01T10:32:24.531504Z","end":"2026-04-01T10:32:24.649312Z","steps":["trace[182497570] 'agreement among raft nodes before linearized reading' (duration: 117.723056ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:24.947371Z","caller":"traceutil/trace.go:172","msg":"trace[986282667] linearizableReadLoop","detail":"{readStateIndex:6193; appliedIndex:6192; }","duration":"238.454454ms","start":"2026-04-01T10:32:24.708900Z","end":"2026-04-01T10:32:24.947355Z","steps":["trace[986282667] 'read index received' (duration: 198.954827ms)","trace[986282667] 'applied index is now lower than readState.Index' (duration: 39.498897ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:24.947694Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"238.784281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" limit:1 ","response":"range_response_count:1 size:461"} {"level":"info","ts":"2026-04-01T10:32:24.947725Z","caller":"traceutil/trace.go:172","msg":"trace[1270186810] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5363; }","duration":"238.831992ms","start":"2026-04-01T10:32:24.708887Z","end":"2026-04-01T10:32:24.947719Z","steps":["trace[1270186810] 'agreement among raft nodes before linearized reading' (duration: 238.677929ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:25.273896Z","caller":"traceutil/trace.go:172","msg":"trace[1813626260] linearizableReadLoop","detail":"{readStateIndex:6194; appliedIndex:6193; }","duration":"131.541444ms","start":"2026-04-01T10:32:25.142330Z","end":"2026-04-01T10:32:25.273871Z","steps":["trace[1813626260] 'read index received' (duration: 79.296651ms)","trace[1813626260] 'applied index is now lower than readState.Index' (duration: 52.243273ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:25.274230Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"131.813649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T10:32:25.273983Z","caller":"traceutil/trace.go:172","msg":"trace[294576794] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"168.455316ms","start":"2026-04-01T10:32:25.105499Z","end":"2026-04-01T10:32:25.273955Z","steps":["trace[294576794] 'process raft request' (duration: 114.21193ms)","trace[294576794] 'compare' (duration: 54.004531ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T10:32:25.274442Z","caller":"traceutil/trace.go:172","msg":"trace[441242622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5364; }","duration":"132.122857ms","start":"2026-04-01T10:32:25.142302Z","end":"2026-04-01T10:32:25.274425Z","steps":["trace[441242622] 'agreement among raft nodes before linearized reading' (duration: 131.698337ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:25.432766Z","caller":"traceutil/trace.go:172","msg":"trace[1573606068] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"153.724098ms","start":"2026-04-01T10:32:25.279013Z","end":"2026-04-01T10:32:25.432737Z","steps":["trace[1573606068] 'process raft request' (duration: 141.002422ms)","trace[1573606068] 'compare' (duration: 12.617424ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T10:32:26.160404Z","caller":"traceutil/trace.go:172","msg":"trace[1923331790] linearizableReadLoop","detail":"{readStateIndex:6197; appliedIndex:6198; }","duration":"167.317649ms","start":"2026-04-01T10:32:25.993066Z","end":"2026-04-01T10:32:26.160384Z","steps":["trace[1923331790] 'read index received' (duration: 167.313259ms)","trace[1923331790] 'applied index is now lower than readState.Index' (duration: 3.39µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:26.160597Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"167.484042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-04-01T10:32:26.160694Z","caller":"traceutil/trace.go:172","msg":"trace[345198834] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5368; }","duration":"167.631865ms","start":"2026-04-01T10:32:25.993050Z","end":"2026-04-01T10:32:26.160682Z","steps":["trace[345198834] 'agreement among raft nodes before linearized reading' (duration: 167.442221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:26.161673Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"121.348417ms","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-01T10:32:26.161744Z","caller":"traceutil/trace.go:172","msg":"trace[689531425] range","detail":"{range_begin:/registry/replicasets; range_end:; response_count:0; response_revision:5368; }","duration":"121.433709ms","start":"2026-04-01T10:32:26.040293Z","end":"2026-04-01T10:32:26.161727Z","steps":["trace[689531425] 'agreement among raft nodes before linearized reading' (duration: 121.308056ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:31.701654Z","caller":"traceutil/trace.go:172","msg":"trace[1670866325] transaction","detail":"{read_only:false; response_revision:5386; number_of_response:1; }","duration":"220.445114ms","start":"2026-04-01T10:32:31.481176Z","end":"2026-04-01T10:32:31.701621Z","steps":["trace[1670866325] 'process raft request' (duration: 220.013565ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:31.702496Z","caller":"traceutil/trace.go:172","msg":"trace[247822877] linearizableReadLoop","detail":"{readStateIndex:6220; appliedIndex:6220; }","duration":"207.166427ms","start":"2026-04-01T10:32:31.495304Z","end":"2026-04-01T10:32:31.702470Z","steps":["trace[247822877] 'read index received' (duration: 207.158277ms)","trace[247822877] 'applied index is now lower than readState.Index' (duration: 6.5µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:31.702605Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"207.28679ms","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-01T10:32:31.702632Z","caller":"traceutil/trace.go:172","msg":"trace[1980800767] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5386; }","duration":"207.32662ms","start":"2026-04-01T10:32:31.495298Z","end":"2026-04-01T10:32:31.702624Z","steps":["trace[1980800767] 'agreement among raft nodes before linearized reading' (duration: 207.260439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:31.703803Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"194.110195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-01T10:32:31.703842Z","caller":"traceutil/trace.go:172","msg":"trace[1093984868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5386; }","duration":"194.152766ms","start":"2026-04-01T10:32:31.509680Z","end":"2026-04-01T10:32:31.703833Z","steps":["trace[1093984868] 'agreement among raft nodes before linearized reading' (duration: 194.026604ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:31.979663Z","caller":"traceutil/trace.go:172","msg":"trace[1250981134] linearizableReadLoop","detail":"{readStateIndex:6220; appliedIndex:6221; }","duration":"127.116558ms","start":"2026-04-01T10:32:31.852523Z","end":"2026-04-01T10:32:31.979639Z","steps":["trace[1250981134] 'read index received' (duration: 127.106468ms)","trace[1250981134] 'applied index is now lower than readState.Index' (duration: 8.39µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:32.024803Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"172.25389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:32:32.024878Z","caller":"traceutil/trace.go:172","msg":"trace[1291048112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5387; }","duration":"172.344381ms","start":"2026-04-01T10:32:31.852518Z","end":"2026-04-01T10:32:32.024862Z","steps":["trace[1291048112] 'agreement among raft nodes before linearized reading' (duration: 127.204611ms)","trace[1291048112] 'range keys from in-memory index tree' (duration: 45.020428ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:32.845601Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"349.928657ms","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-01T10:32:32.845559Z","caller":"traceutil/trace.go:172","msg":"trace[823282919] linearizableReadLoop","detail":"{readStateIndex:6226; appliedIndex:6226; }","duration":"349.832315ms","start":"2026-04-01T10:32:32.495574Z","end":"2026-04-01T10:32:32.845406Z","steps":["trace[823282919] 'read index received' (duration: 349.826485ms)","trace[823282919] 'applied index is now lower than readState.Index' (duration: 4.86µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T10:32:32.845731Z","caller":"traceutil/trace.go:172","msg":"trace[200637704] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5391; }","duration":"350.153161ms","start":"2026-04-01T10:32:32.495567Z","end":"2026-04-01T10:32:32.845720Z","steps":["trace[200637704] 'agreement among raft nodes before linearized reading' (duration: 349.894986ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:33.917145Z","caller":"traceutil/trace.go:172","msg":"trace[1233860807] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"491.997699ms","start":"2026-04-01T10:32:33.425129Z","end":"2026-04-01T10:32:33.917127Z","steps":["trace[1233860807] 'process raft request' (duration: 491.735433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:33.917463Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"422.082434ms","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-01T10:32:33.917554Z","caller":"traceutil/trace.go:172","msg":"trace[349428677] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5394; }","duration":"422.187136ms","start":"2026-04-01T10:32:33.495346Z","end":"2026-04-01T10:32:33.917533Z","steps":["trace[349428677] 'agreement among raft nodes before linearized reading' (duration: 422.030903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:33.917635Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:33.425105Z","time spent":"492.123402ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T10:32:33.917291Z","caller":"traceutil/trace.go:172","msg":"trace[914690875] linearizableReadLoop","detail":"{readStateIndex:6228; appliedIndex:6227; }","duration":"421.905391ms","start":"2026-04-01T10:32:33.495353Z","end":"2026-04-01T10:32:33.917258Z","steps":["trace[914690875] 'read index received' (duration: 421.433871ms)","trace[914690875] 'applied index is now lower than readState.Index' (duration: 464.999µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:34.255055Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"199.693282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T10:32:34.255261Z","caller":"traceutil/trace.go:172","msg":"trace[1979500569] linearizableReadLoop","detail":"{readStateIndex:6230; appliedIndex:6229; }","duration":"336.835625ms","start":"2026-04-01T10:32:33.918409Z","end":"2026-04-01T10:32:34.255245Z","steps":["trace[1979500569] 'read index received' (duration: 138.360139ms)","trace[1979500569] 'applied index is now lower than readState.Index' (duration: 198.474526ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:34.255342Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"625.297029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:483"} {"level":"info","ts":"2026-04-01T10:32:34.255368Z","caller":"traceutil/trace.go:172","msg":"trace[327147379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5396; }","duration":"625.33363ms","start":"2026-04-01T10:32:33.630026Z","end":"2026-04-01T10:32:34.255360Z","steps":["trace[327147379] 'agreement among raft nodes before linearized reading' (duration: 625.266668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:34.255367Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"528.684227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:32:34.255456Z","caller":"traceutil/trace.go:172","msg":"trace[1391883326] range","detail":"{range_begin:/registry/services/endpoints; range_end:; response_count:0; response_revision:5396; }","duration":"528.793609ms","start":"2026-04-01T10:32:33.726641Z","end":"2026-04-01T10:32:34.255434Z","steps":["trace[1391883326] 'agreement among raft nodes before linearized reading' (duration: 528.628916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:34.255522Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:33.726611Z","time spent":"528.903073ms","remote":"127.0.0.1:53636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":32,"response count":0,"response size":28,"request content":"key:\"/registry/services/endpoints\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:32:34.255549Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"548.737536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-01T10:32:34.255401Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:33.630004Z","time spent":"625.38662ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 "} {"level":"info","ts":"2026-04-01T10:32:34.255572Z","caller":"traceutil/trace.go:172","msg":"trace[1872089409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5396; }","duration":"548.760666ms","start":"2026-04-01T10:32:33.706806Z","end":"2026-04-01T10:32:34.255567Z","steps":["trace[1872089409] 'agreement among raft nodes before linearized reading' (duration: 548.615532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:34.255991Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"403.474802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:32:34.256082Z","caller":"traceutil/trace.go:172","msg":"trace[1127776154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5396; }","duration":"403.576294ms","start":"2026-04-01T10:32:33.852492Z","end":"2026-04-01T10:32:34.256069Z","steps":["trace[1127776154] 'agreement among raft nodes before linearized reading' (duration: 402.870179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:34.256143Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:33.852473Z","time spent":"403.664196ms","remote":"127.0.0.1:53356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T10:32:34.256114Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:33.706783Z","time spent":"549.079913ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:32:34.757616Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":496694794034543593,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T10:32:35.054036Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"121.470516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-04-01T10:32:35.053863Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"557.683453ms","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-01T10:32:35.054150Z","caller":"traceutil/trace.go:172","msg":"trace[1875527615] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5396; }","duration":"557.97748ms","start":"2026-04-01T10:32:34.496114Z","end":"2026-04-01T10:32:35.054092Z","steps":["trace[1875527615] 'range keys from in-memory index tree' (duration: 557.629843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:35.258429Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":496694794034543593,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T10:32:35.759177Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":496694794034543593,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T10:32:35.866802Z","caller":"traceutil/trace.go:172","msg":"trace[717009258] linearizableReadLoop","detail":"{readStateIndex:6232; appliedIndex:6233; }","duration":"1.60929043s","start":"2026-04-01T10:32:34.257488Z","end":"2026-04-01T10:32:35.866778Z","steps":["trace[717009258] 'read index received' (duration: 1.60928503s)","trace[717009258] 'applied index is now lower than readState.Index' (duration: 4.33µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:35.866962Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.609439483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:32:35.866994Z","caller":"traceutil/trace.go:172","msg":"trace[1761448974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5398; }","duration":"1.609501904s","start":"2026-04-01T10:32:34.257483Z","end":"2026-04-01T10:32:35.866985Z","steps":["trace[1761448974] 'agreement among raft nodes before linearized reading' (duration: 1.609401262s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:35.867028Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:34.257469Z","time spent":"1.609552545s","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-01T10:32:35.867190Z","caller":"traceutil/trace.go:172","msg":"trace[19568484] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"1.606330988s","start":"2026-04-01T10:32:34.260835Z","end":"2026-04-01T10:32:35.867166Z","steps":["trace[19568484] 'process raft request' (duration: 1.606261566s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:35.867345Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:34.260822Z","time spent":"1.606439301s","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T10:32:35.966990Z","caller":"etcdserver/v3_server.go:888","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":496694794034543596,"received-request-id":496694794034543593} {"level":"warn","ts":"2026-04-01T10:32:36.100218Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"133.084415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T10:32:36.100326Z","caller":"traceutil/trace.go:172","msg":"trace[1994626761] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"672.058293ms","start":"2026-04-01T10:32:35.428257Z","end":"2026-04-01T10:32:36.100315Z","steps":["trace[1994626761] 'process raft request' (duration: 538.825495ms)","trace[1994626761] 'compare' (duration: 132.988543ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:36.100362Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:35.428224Z","time spent":"672.123815ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T10:32:36.136042Z","caller":"traceutil/trace.go:172","msg":"trace[254250067] linearizableReadLoop","detail":"{readStateIndex:6237; appliedIndex:6237; }","duration":"269.136631ms","start":"2026-04-01T10:32:35.866874Z","end":"2026-04-01T10:32:36.136010Z","steps":["trace[254250067] 'read index received' (duration: 269.12341ms)","trace[254250067] 'applied index is now lower than readState.Index' (duration: 11.101µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:36.136243Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.136033606s","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-01T10:32:36.136270Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.06983432s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-01T10:32:36.136290Z","caller":"traceutil/trace.go:172","msg":"trace[2136970096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5402; }","duration":"1.136097787s","start":"2026-04-01T10:32:35.000177Z","end":"2026-04-01T10:32:36.136275Z","steps":["trace[2136970096] 'agreement among raft nodes before linearized reading' (duration: 1.135988355s)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:36.136321Z","caller":"traceutil/trace.go:172","msg":"trace[1648964514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5402; }","duration":"1.069890801s","start":"2026-04-01T10:32:35.066420Z","end":"2026-04-01T10:32:36.136310Z","steps":["trace[1648964514] 'agreement among raft nodes before linearized reading' (duration: 1.069666436s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:36.136351Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:35.066404Z","time spent":"1.069939252s","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:32:36.136394Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.288913414s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T10:32:36.136376Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.081870669s","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-01T10:32:36.136429Z","caller":"traceutil/trace.go:172","msg":"trace[691561179] range","detail":"{range_begin:/registry/csistoragecapacities; range_end:; response_count:0; response_revision:5402; }","duration":"1.288944315s","start":"2026-04-01T10:32:34.847472Z","end":"2026-04-01T10:32:36.136416Z","steps":["trace[691561179] 'agreement among raft nodes before linearized reading' (duration: 1.288894174s)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:36.136448Z","caller":"traceutil/trace.go:172","msg":"trace[1267976181] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5402; }","duration":"1.082207886s","start":"2026-04-01T10:32:35.054225Z","end":"2026-04-01T10:32:36.136433Z","steps":["trace[1267976181] 'agreement among raft nodes before linearized reading' (duration: 1.081833289s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:36.136460Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:34.847445Z","time spent":"1.289006877s","remote":"127.0.0.1:54178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:32:36.136341Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:35.000157Z","time spent":"1.136172218s","remote":"127.0.0.1:53792","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-01T10:32:36.136781Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.665443531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" limit:1 ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-01T10:32:36.136823Z","caller":"traceutil/trace.go:172","msg":"trace[460992703] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:5402; }","duration":"1.665483692s","start":"2026-04-01T10:32:34.471325Z","end":"2026-04-01T10:32:36.136809Z","steps":["trace[460992703] 'agreement among raft nodes before linearized reading' (duration: 1.665361909s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:36.136857Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:34.471301Z","time spent":"1.665547334s","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.205\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:32:36.138776Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"264.400674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:32:36.138858Z","caller":"traceutil/trace.go:172","msg":"trace[454878289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5402; }","duration":"264.482855ms","start":"2026-04-01T10:32:35.874355Z","end":"2026-04-01T10:32:36.138838Z","steps":["trace[454878289] 'agreement among raft nodes before linearized reading' (duration: 264.359293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:36.285896Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"147.675886ms","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-01T10:32:36.285995Z","caller":"traceutil/trace.go:172","msg":"trace[817958946] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5402; }","duration":"147.788548ms","start":"2026-04-01T10:32:36.138183Z","end":"2026-04-01T10:32:36.285971Z","steps":["trace[817958946] 'agreement among raft nodes before linearized reading' (duration: 68.671525ms)","trace[817958946] 'range keys from in-memory index tree' (duration: 78.98162ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:36.286682Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"146.885431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:32:36.286769Z","caller":"traceutil/trace.go:172","msg":"trace[2048664409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5402; }","duration":"146.987233ms","start":"2026-04-01T10:32:36.139761Z","end":"2026-04-01T10:32:36.286748Z","steps":["trace[2048664409] 'agreement among raft nodes before linearized reading' (duration: 146.82948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:36.763504Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"107.199355ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-01T10:32:36.880268Z","caller":"traceutil/trace.go:172","msg":"trace[66285401] linearizableReadLoop","detail":"{readStateIndex:6241; appliedIndex:6242; }","duration":"360.545416ms","start":"2026-04-01T10:32:36.519689Z","end":"2026-04-01T10:32:36.880234Z","steps":["trace[66285401] 'read index received' (duration: 360.535115ms)","trace[66285401] 'applied index is now lower than readState.Index' (duration: 8.011µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:36.880389Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"360.673519ms","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-01T10:32:36.880447Z","caller":"traceutil/trace.go:172","msg":"trace[355129686] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5405; }","duration":"360.751981ms","start":"2026-04-01T10:32:36.519683Z","end":"2026-04-01T10:32:36.880435Z","steps":["trace[355129686] 'agreement among raft nodes before linearized reading' (duration: 360.658239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:41.641781Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"146.039332ms","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-01T10:32:41.641866Z","caller":"traceutil/trace.go:172","msg":"trace[835371726] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5421; }","duration":"146.146874ms","start":"2026-04-01T10:32:41.495702Z","end":"2026-04-01T10:32:41.641849Z","steps":["trace[835371726] 'range keys from in-memory index tree' (duration: 145.994981ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:42.205450Z","caller":"traceutil/trace.go:172","msg":"trace[812813336] linearizableReadLoop","detail":"{readStateIndex:6262; appliedIndex:6262; }","duration":"299.921574ms","start":"2026-04-01T10:32:41.905498Z","end":"2026-04-01T10:32:42.205420Z","steps":["trace[812813336] 'read index received' (duration: 299.894954ms)","trace[812813336] 'applied index is now lower than readState.Index' (duration: 24.92µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:42.294531Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"389.012567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-01T10:32:42.294789Z","caller":"traceutil/trace.go:172","msg":"trace[251815238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5422; }","duration":"389.283973ms","start":"2026-04-01T10:32:41.905485Z","end":"2026-04-01T10:32:42.294769Z","steps":["trace[251815238] 'agreement among raft nodes before linearized reading' (duration: 300.009156ms)","trace[251815238] 'range keys from in-memory index tree' (duration: 88.889379ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:42.294826Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:41.905460Z","time spent":"389.357423ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:32:42.388880Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"183.417614ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-01T10:32:42.657462Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"162.268596ms","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-01T10:32:42.657532Z","caller":"traceutil/trace.go:172","msg":"trace[741347901] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5423; }","duration":"162.353698ms","start":"2026-04-01T10:32:42.495166Z","end":"2026-04-01T10:32:42.657520Z","steps":["trace[741347901] 'range keys from in-memory index tree' (duration: 162.222536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:42.657683Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"224.992464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T10:32:42.657856Z","caller":"traceutil/trace.go:172","msg":"trace[581663146] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"358.78942ms","start":"2026-04-01T10:32:42.299053Z","end":"2026-04-01T10:32:42.657843Z","steps":["trace[581663146] 'process raft request' (duration: 358.703448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:42.657931Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:42.299033Z","time spent":"358.854142ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T10:32:43.142233Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"288.093597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:32:43.142293Z","caller":"traceutil/trace.go:172","msg":"trace[432308024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5426; }","duration":"288.161128ms","start":"2026-04-01T10:32:42.854120Z","end":"2026-04-01T10:32:43.142281Z","steps":["trace[432308024] 'range keys from in-memory index tree' (duration: 286.067065ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:44.329548Z","caller":"traceutil/trace.go:172","msg":"trace[2048442678] linearizableReadLoop","detail":"{readStateIndex:6271; appliedIndex:6271; }","duration":"126.238806ms","start":"2026-04-01T10:32:44.203280Z","end":"2026-04-01T10:32:44.329519Z","steps":["trace[2048442678] 'read index received' (duration: 126.227286ms)","trace[2048442678] 'applied index is now lower than readState.Index' (duration: 9.93µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:44.331434Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"128.149345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-04-01T10:32:44.331514Z","caller":"traceutil/trace.go:172","msg":"trace[1514613678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5430; }","duration":"128.247107ms","start":"2026-04-01T10:32:44.203253Z","end":"2026-04-01T10:32:44.331500Z","steps":["trace[1514613678] 'agreement among raft nodes before linearized reading' (duration: 126.371209ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:44.600465Z","caller":"traceutil/trace.go:172","msg":"trace[1603616770] linearizableReadLoop","detail":"{readStateIndex:6274; appliedIndex:6274; }","duration":"128.843982ms","start":"2026-04-01T10:32:44.471599Z","end":"2026-04-01T10:32:44.600443Z","steps":["trace[1603616770] 'read index received' (duration: 128.838872ms)","trace[1603616770] 'applied index is now lower than readState.Index' (duration: 4.24µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:44.600697Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"129.089077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" limit:1 ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-01T10:32:44.600737Z","caller":"traceutil/trace.go:172","msg":"trace[359595104] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:5433; }","duration":"129.144428ms","start":"2026-04-01T10:32:44.471584Z","end":"2026-04-01T10:32:44.600728Z","steps":["trace[359595104] 'agreement among raft nodes before linearized reading' (duration: 128.968984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:54.809097Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"313.130644ms","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-01T10:32:54.809190Z","caller":"traceutil/trace.go:172","msg":"trace[1355298577] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5468; }","duration":"313.226277ms","start":"2026-04-01T10:32:54.495934Z","end":"2026-04-01T10:32:54.809160Z","steps":["trace[1355298577] 'range keys from in-memory index tree' (duration: 313.080922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:54.809555Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"354.230688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-04-01T10:32:54.972483Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":496694794034543696,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T10:32:55.442455Z","caller":"traceutil/trace.go:172","msg":"trace[1548266775] linearizableReadLoop","detail":"{readStateIndex:6319; appliedIndex:6320; }","duration":"970.31328ms","start":"2026-04-01T10:32:54.472107Z","end":"2026-04-01T10:32:55.442420Z","steps":["trace[1548266775] 'read index received' (duration: 970.3083ms)","trace[1548266775] 'applied index is now lower than readState.Index' (duration: 4.05µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:55.442803Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"970.655247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" limit:1 ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-01T10:32:55.442897Z","caller":"traceutil/trace.go:172","msg":"trace[26989731] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:5474; }","duration":"970.78718ms","start":"2026-04-01T10:32:54.472093Z","end":"2026-04-01T10:32:55.442880Z","steps":["trace[26989731] 'agreement among raft nodes before linearized reading' (duration: 970.433353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:55.442950Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:54.472073Z","time spent":"970.866481ms","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.205\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:32:55.444598Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"589.6864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T10:32:55.444630Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"635.304696ms","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-01T10:32:55.444674Z","caller":"traceutil/trace.go:172","msg":"trace[2125064613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5474; }","duration":"589.769982ms","start":"2026-04-01T10:32:54.854889Z","end":"2026-04-01T10:32:55.444658Z","steps":["trace[2125064613] 'agreement among raft nodes before linearized reading' (duration: 589.64945ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:55.444687Z","caller":"traceutil/trace.go:172","msg":"trace[370540881] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5474; }","duration":"635.364687ms","start":"2026-04-01T10:32:54.809307Z","end":"2026-04-01T10:32:55.444671Z","steps":["trace[370540881] 'agreement among raft nodes before linearized reading' (duration: 635.272935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:55.444673Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"649.447226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-01T10:32:55.444711Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:54.854873Z","time spent":"589.831373ms","remote":"127.0.0.1:53356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-01T10:32:55.444740Z","caller":"traceutil/trace.go:172","msg":"trace[906453171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5474; }","duration":"649.525307ms","start":"2026-04-01T10:32:54.795196Z","end":"2026-04-01T10:32:55.444721Z","steps":["trace[906453171] 'agreement among raft nodes before linearized reading' (duration: 649.328803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:55.444793Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:54.795175Z","time spent":"649.61011ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:32:56.806368Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"274.908542ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-01T10:32:57.023291Z","caller":"traceutil/trace.go:172","msg":"trace[377766816] transaction","detail":"{read_only:false; response_revision:5479; number_of_response:1; }","duration":"509.357466ms","start":"2026-04-01T10:32:56.513914Z","end":"2026-04-01T10:32:57.023271Z","steps":["trace[377766816] 'process raft request' (duration: 509.212223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:32:57.023430Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:32:56.513874Z","time spent":"509.490509ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T10:32:57.027217Z","caller":"traceutil/trace.go:172","msg":"trace[418984787] linearizableReadLoop","detail":"{readStateIndex:6328; appliedIndex:6328; }","duration":"174.181798ms","start":"2026-04-01T10:32:56.853021Z","end":"2026-04-01T10:32:57.027203Z","steps":["trace[418984787] 'read index received' (duration: 174.178058ms)","trace[418984787] 'applied index is now lower than readState.Index' (duration: 3.08µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:57.027309Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"174.28363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:32:57.027456Z","caller":"traceutil/trace.go:172","msg":"trace[1858951473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5479; }","duration":"174.442743ms","start":"2026-04-01T10:32:56.853007Z","end":"2026-04-01T10:32:57.027450Z","steps":["trace[1858951473] 'agreement among raft nodes before linearized reading' (duration: 174.257309ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:32:57.579021Z","caller":"traceutil/trace.go:172","msg":"trace[466183371] linearizableReadLoop","detail":"{readStateIndex:6333; appliedIndex:6332; }","duration":"109.491212ms","start":"2026-04-01T10:32:57.469514Z","end":"2026-04-01T10:32:57.579005Z","steps":["trace[466183371] 'read index received' (duration: 91.501024ms)","trace[466183371] 'applied index is now lower than readState.Index' (duration: 17.989538ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:32:57.579253Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"109.724157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-01T10:32:57.579513Z","caller":"traceutil/trace.go:172","msg":"trace[1896489286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5483; }","duration":"109.993712ms","start":"2026-04-01T10:32:57.469502Z","end":"2026-04-01T10:32:57.579496Z","steps":["trace[1896489286] 'agreement among raft nodes before linearized reading' (duration: 109.603835ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:33:01.604184Z","caller":"traceutil/trace.go:172","msg":"trace[421732627] linearizableReadLoop","detail":"{readStateIndex:6348; appliedIndex:6348; }","duration":"108.21148ms","start":"2026-04-01T10:33:01.495918Z","end":"2026-04-01T10:33:01.604130Z","steps":["trace[421732627] 'read index received' (duration: 108.20606ms)","trace[421732627] 'applied index is now lower than readState.Index' (duration: 4.38µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:01.604306Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"108.373024ms","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-01T10:33:01.604543Z","caller":"traceutil/trace.go:172","msg":"trace[1945978699] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5496; }","duration":"108.535397ms","start":"2026-04-01T10:33:01.495899Z","end":"2026-04-01T10:33:01.604434Z","steps":["trace[1945978699] 'agreement among raft nodes before linearized reading' (duration: 108.307452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:07.549247Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"251.184049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T10:33:07.549342Z","caller":"traceutil/trace.go:172","msg":"trace[444793605] linearizableReadLoop","detail":"{readStateIndex:6376; appliedIndex:6375; }","duration":"265.610203ms","start":"2026-04-01T10:33:07.283720Z","end":"2026-04-01T10:33:07.549331Z","steps":["trace[444793605] 'read index received' (duration: 15.890433ms)","trace[444793605] 'applied index is now lower than readState.Index' (duration: 249.71896ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T10:33:07.549351Z","caller":"traceutil/trace.go:172","msg":"trace[1395034852] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"380.339576ms","start":"2026-04-01T10:33:07.168985Z","end":"2026-04-01T10:33:07.549325Z","steps":["trace[1395034852] 'process raft request' (duration: 129.022974ms)","trace[1395034852] 'compare' (duration: 250.520556ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:07.549682Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:33:07.168965Z","time spent":"380.646233ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":536,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T10:33:07.549817Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"266.103564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:483"} {"level":"info","ts":"2026-04-01T10:33:07.549859Z","caller":"traceutil/trace.go:172","msg":"trace[728789649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5520; }","duration":"266.148105ms","start":"2026-04-01T10:33:07.283705Z","end":"2026-04-01T10:33:07.549854Z","steps":["trace[728789649] 'agreement among raft nodes before linearized reading' (duration: 265.97869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:07.551732Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"117.212435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-04-01T10:33:07.551792Z","caller":"traceutil/trace.go:172","msg":"trace[874284284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5520; }","duration":"117.274366ms","start":"2026-04-01T10:33:07.434512Z","end":"2026-04-01T10:33:07.551786Z","steps":["trace[874284284] 'agreement among raft nodes before linearized reading' (duration: 117.165144ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:33:08.140479Z","caller":"traceutil/trace.go:172","msg":"trace[1718396857] transaction","detail":"{read_only:false; response_revision:5523; number_of_response:1; }","duration":"234.225183ms","start":"2026-04-01T10:33:07.906233Z","end":"2026-04-01T10:33:08.140458Z","steps":["trace[1718396857] 'process raft request' (duration: 233.935887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:08.451261Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"185.038772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T10:33:08.451352Z","caller":"traceutil/trace.go:172","msg":"trace[663473843] linearizableReadLoop","detail":"{readStateIndex:6380; appliedIndex:6379; }","duration":"233.597009ms","start":"2026-04-01T10:33:08.217744Z","end":"2026-04-01T10:33:08.451341Z","steps":["trace[663473843] 'read index received' (duration: 51.368124ms)","trace[663473843] 'applied index is now lower than readState.Index' (duration: 182.227875ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:08.451464Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"233.718532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:33:08.451634Z","caller":"traceutil/trace.go:172","msg":"trace[1346945336] range","detail":"{range_begin:/registry/services/specs; range_end:; response_count:0; response_revision:5524; }","duration":"233.892985ms","start":"2026-04-01T10:33:08.217731Z","end":"2026-04-01T10:33:08.451624Z","steps":["trace[1346945336] 'agreement among raft nodes before linearized reading' (duration: 233.696101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:09.248943Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":496694794034543773,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T10:33:09.260568Z","caller":"traceutil/trace.go:172","msg":"trace[1472147862] linearizableReadLoop","detail":"{readStateIndex:6381; appliedIndex:6381; }","duration":"512.414685ms","start":"2026-04-01T10:33:08.748134Z","end":"2026-04-01T10:33:09.260548Z","steps":["trace[1472147862] 'read index received' (duration: 512.409435ms)","trace[1472147862] 'applied index is now lower than readState.Index' (duration: 4.03µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:09.453641Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"174.182068ms","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-01T10:33:09.453717Z","caller":"traceutil/trace.go:172","msg":"trace[304798991] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5525; }","duration":"174.27105ms","start":"2026-04-01T10:33:09.279430Z","end":"2026-04-01T10:33:09.453701Z","steps":["trace[304798991] 'range keys from in-memory index tree' (duration: 174.130528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:09.453863Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"705.707922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-04-01T10:33:09.454199Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"193.379859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T10:33:09.454381Z","caller":"traceutil/trace.go:172","msg":"trace[7637287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5525; }","duration":"705.863205ms","start":"2026-04-01T10:33:08.748119Z","end":"2026-04-01T10:33:09.453983Z","steps":["trace[7637287] 'agreement among raft nodes before linearized reading' (duration: 512.501556ms)","trace[7637287] 'range keys from in-memory index tree' (duration: 193.094964ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:09.454622Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:33:08.748093Z","time spent":"706.328235ms","remote":"127.0.0.1:53792","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":"warn","ts":"2026-04-01T10:33:09.495391Z","caller":"etcdserver/v3_server.go:888","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":496694794034543774,"received-request-id":496694794034543773} {"level":"warn","ts":"2026-04-01T10:33:09.900339Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"169.74714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:33:09.900372Z","caller":"traceutil/trace.go:172","msg":"trace[1339995072] range","detail":"{range_begin:/registry/deployments; range_end:; response_count:0; response_revision:5528; }","duration":"169.796331ms","start":"2026-04-01T10:33:09.730571Z","end":"2026-04-01T10:33:09.900367Z","steps":["trace[1339995072] 'agreement among raft nodes before linearized reading' (duration: 169.72839ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:33:09.900252Z","caller":"traceutil/trace.go:172","msg":"trace[872105599] linearizableReadLoop","detail":"{readStateIndex:6384; appliedIndex:6384; }","duration":"169.654507ms","start":"2026-04-01T10:33:09.730584Z","end":"2026-04-01T10:33:09.900238Z","steps":["trace[872105599] 'read index received' (duration: 169.592846ms)","trace[872105599] 'applied index is now lower than readState.Index' (duration: 60.511µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:10.041986Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"188.403558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:33:10.042079Z","caller":"traceutil/trace.go:172","msg":"trace[2028942212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5528; }","duration":"188.5057ms","start":"2026-04-01T10:33:09.853552Z","end":"2026-04-01T10:33:10.042057Z","steps":["trace[2028942212] 'agreement among raft nodes before linearized reading' (duration: 67.976951ms)","trace[2028942212] 'range keys from in-memory index tree' (duration: 120.400106ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:10.042001Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"250.005499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:33:10.042254Z","caller":"traceutil/trace.go:172","msg":"trace[1245541016] range","detail":"{range_begin:/registry/secrets; range_end:; response_count:0; response_revision:5528; }","duration":"250.269934ms","start":"2026-04-01T10:33:09.791961Z","end":"2026-04-01T10:33:10.042231Z","steps":["trace[1245541016] 'agreement among raft nodes before linearized reading' (duration: 129.552192ms)","trace[1245541016] 'range keys from in-memory index tree' (duration: 120.435556ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:10.341345Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"196.805336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-01T10:33:10.341431Z","caller":"traceutil/trace.go:172","msg":"trace[729843521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5529; }","duration":"196.900098ms","start":"2026-04-01T10:33:10.144512Z","end":"2026-04-01T10:33:10.341413Z","steps":["trace[729843521] 'range keys from in-memory index tree' (duration: 195.046731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:10.789201Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"293.296668ms","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-01T10:33:10.789291Z","caller":"traceutil/trace.go:172","msg":"trace[1187310504] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5530; }","duration":"293.40727ms","start":"2026-04-01T10:33:10.495867Z","end":"2026-04-01T10:33:10.789275Z","steps":["trace[1187310504] 'range keys from in-memory index tree' (duration: 293.252767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:10.789728Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"327.848399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T10:33:10.789860Z","caller":"traceutil/trace.go:172","msg":"trace[199056565] linearizableReadLoop","detail":"{readStateIndex:6387; appliedIndex:6386; }","duration":"298.889882ms","start":"2026-04-01T10:33:10.490956Z","end":"2026-04-01T10:33:10.789846Z","steps":["trace[199056565] 'read index received' (duration: 1.002241ms)","trace[199056565] 'applied index is now lower than readState.Index' (duration: 297.886671ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T10:33:10.789892Z","caller":"traceutil/trace.go:172","msg":"trace[2034459329] transaction","detail":"{read_only:false; response_revision:5531; number_of_response:1; }","duration":"442.778933ms","start":"2026-04-01T10:33:10.347089Z","end":"2026-04-01T10:33:10.789868Z","steps":["trace[2034459329] 'process raft request' (duration: 114.72271ms)","trace[2034459329] 'compare' (duration: 327.663905ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:10.790031Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:33:10.347063Z","time spent":"442.884805ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T10:33:10.790215Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"299.242579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:33:10.790321Z","caller":"traceutil/trace.go:172","msg":"trace[1803706800] range","detail":"{range_begin:/registry/statefulsets; range_end:; response_count:0; response_revision:5531; }","duration":"299.355021ms","start":"2026-04-01T10:33:10.490949Z","end":"2026-04-01T10:33:10.790304Z","steps":["trace[1803706800] 'agreement among raft nodes before linearized reading' (duration: 298.997214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:10.896216Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"106.3713ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-01T10:33:10.896350Z","caller":"traceutil/trace.go:172","msg":"trace[1365558985] linearizableReadLoop","detail":"{readStateIndex:6388; appliedIndex:6387; }","duration":"106.439631ms","start":"2026-04-01T10:33:10.789891Z","end":"2026-04-01T10:33:10.896331Z","steps":["trace[1365558985] 'read index received' (duration: 1.694344ms)","trace[1365558985] 'applied index is now lower than readState.Index' (duration: 104.743787ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:10.896484Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"106.759358ms","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-01T10:33:10.896560Z","caller":"traceutil/trace.go:172","msg":"trace[2029172497] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5531; }","duration":"106.870771ms","start":"2026-04-01T10:33:10.789675Z","end":"2026-04-01T10:33:10.896545Z","steps":["trace[2029172497] 'agreement among raft nodes before linearized reading' (duration: 106.711737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:11.763395Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"141.070754ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-01T10:33:11.996850Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":496694794034543787,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T10:33:12.214794Z","caller":"traceutil/trace.go:172","msg":"trace[1526620200] linearizableReadLoop","detail":"{readStateIndex:6391; appliedIndex:6392; }","duration":"718.603451ms","start":"2026-04-01T10:33:11.496162Z","end":"2026-04-01T10:33:12.214765Z","steps":["trace[1526620200] 'read index received' (duration: 718.596021ms)","trace[1526620200] 'applied index is now lower than readState.Index' (duration: 6.19µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:12.217827Z","caller":"etcdserver/v3_server.go:888","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":496694794034543788,"received-request-id":496694794034543787} {"level":"warn","ts":"2026-04-01T10:33:12.219266Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"454.45159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-01T10:33:12.219338Z","caller":"traceutil/trace.go:172","msg":"trace[229907894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5535; }","duration":"454.535272ms","start":"2026-04-01T10:33:11.764787Z","end":"2026-04-01T10:33:12.219322Z","steps":["trace[229907894] 'agreement among raft nodes before linearized reading' (duration: 454.335688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:12.219396Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:33:11.764727Z","time spent":"454.652414ms","remote":"127.0.0.1:53792","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":"warn","ts":"2026-04-01T10:33:12.219420Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"326.548024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-04-01T10:33:12.219453Z","caller":"traceutil/trace.go:172","msg":"trace[1641756744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5535; }","duration":"326.585025ms","start":"2026-04-01T10:33:11.892859Z","end":"2026-04-01T10:33:12.219444Z","steps":["trace[1641756744] 'agreement among raft nodes before linearized reading' (duration: 326.522854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:12.219476Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:33:11.892840Z","time spent":"326.629846ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:33:12.219573Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"365.687008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:33:12.219614Z","caller":"traceutil/trace.go:172","msg":"trace[1327416843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5535; }","duration":"365.72513ms","start":"2026-04-01T10:33:11.853876Z","end":"2026-04-01T10:33:12.219601Z","steps":["trace[1327416843] 'agreement among raft nodes before linearized reading' (duration: 365.661308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:12.219645Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:33:11.853858Z","time spent":"365.778891ms","remote":"127.0.0.1:53356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T10:33:12.221283Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"725.143373ms","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-01T10:33:12.221351Z","caller":"traceutil/trace.go:172","msg":"trace[634699540] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5534; }","duration":"725.213165ms","start":"2026-04-01T10:33:11.496122Z","end":"2026-04-01T10:33:12.221335Z","steps":["trace[634699540] 'agreement among raft nodes before linearized reading' (duration: 718.780335ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:33:12.348663Z","caller":"traceutil/trace.go:172","msg":"trace[1616072669] linearizableReadLoop","detail":"{readStateIndex:6393; appliedIndex:6394; }","duration":"127.66049ms","start":"2026-04-01T10:33:12.220983Z","end":"2026-04-01T10:33:12.348643Z","steps":["trace[1616072669] 'read index received' (duration: 127.65559ms)","trace[1616072669] 'applied index is now lower than readState.Index' (duration: 4.04µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:12.349076Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"128.070648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:33:12.349173Z","caller":"traceutil/trace.go:172","msg":"trace[238049659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5536; }","duration":"128.17929ms","start":"2026-04-01T10:33:12.220978Z","end":"2026-04-01T10:33:12.349157Z","steps":["trace[238049659] 'agreement among raft nodes before linearized reading' (duration: 127.961576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:15.426787Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"101.467864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T10:33:17.723302Z","caller":"traceutil/trace.go:172","msg":"trace[1841211009] linearizableReadLoop","detail":"{readStateIndex:6420; appliedIndex:6420; }","duration":"227.831607ms","start":"2026-04-01T10:33:17.495451Z","end":"2026-04-01T10:33:17.723283Z","steps":["trace[1841211009] 'read index received' (duration: 227.822286ms)","trace[1841211009] 'applied index is now lower than readState.Index' (duration: 8.24µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:17.723409Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"227.945329ms","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-01T10:33:17.723466Z","caller":"traceutil/trace.go:172","msg":"trace[832310011] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5558; }","duration":"228.01374ms","start":"2026-04-01T10:33:17.495440Z","end":"2026-04-01T10:33:17.723454Z","steps":["trace[832310011] 'agreement among raft nodes before linearized reading' (duration: 227.925078ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:33:18.142055Z","caller":"traceutil/trace.go:172","msg":"trace[1473411886] transaction","detail":"{read_only:false; response_revision:5559; number_of_response:1; }","duration":"210.085776ms","start":"2026-04-01T10:33:17.931949Z","end":"2026-04-01T10:33:18.142035Z","steps":["trace[1473411886] 'process raft request' (duration: 209.947184ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:33:18.287434Z","caller":"traceutil/trace.go:172","msg":"trace[1265519894] linearizableReadLoop","detail":"{readStateIndex:6421; appliedIndex:6421; }","duration":"308.690139ms","start":"2026-04-01T10:33:17.978711Z","end":"2026-04-01T10:33:18.287401Z","steps":["trace[1265519894] 'read index received' (duration: 308.684618ms)","trace[1265519894] 'applied index is now lower than readState.Index' (duration: 4.47µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:18.305411Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"326.679432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-01T10:33:18.305473Z","caller":"traceutil/trace.go:172","msg":"trace[1626872704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5559; }","duration":"326.754983ms","start":"2026-04-01T10:33:17.978705Z","end":"2026-04-01T10:33:18.305460Z","steps":["trace[1626872704] 'agreement among raft nodes before linearized reading' (duration: 308.893802ms)","trace[1626872704] 'range keys from in-memory index tree' (duration: 17.704277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:18.305502Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:33:17.978679Z","time spent":"326.815985ms","remote":"127.0.0.1:53792","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-01T10:33:19.245394Z","caller":"traceutil/trace.go:172","msg":"trace[20703776] transaction","detail":"{read_only:false; response_revision:5563; number_of_response:1; }","duration":"273.167327ms","start":"2026-04-01T10:33:18.972206Z","end":"2026-04-01T10:33:19.245374Z","steps":["trace[20703776] 'process raft request' (duration: 273.026804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:19.953846Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"284.891803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T10:33:19.954456Z","caller":"traceutil/trace.go:172","msg":"trace[1885441159] linearizableReadLoop","detail":"{readStateIndex:6428; appliedIndex:6426; }","duration":"151.56427ms","start":"2026-04-01T10:33:19.802873Z","end":"2026-04-01T10:33:19.954437Z","steps":["trace[1885441159] 'read index received' (duration: 109.282677ms)","trace[1885441159] 'applied index is now lower than readState.Index' (duration: 42.280613ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:19.954909Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"151.999811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-01T10:33:19.954958Z","caller":"traceutil/trace.go:172","msg":"trace[316562569] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5566; }","duration":"152.086352ms","start":"2026-04-01T10:33:19.802858Z","end":"2026-04-01T10:33:19.954944Z","steps":["trace[316562569] 'agreement among raft nodes before linearized reading' (duration: 151.702305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:19.955939Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"104.394208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:33:19.955987Z","caller":"traceutil/trace.go:172","msg":"trace[1273419417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5566; }","duration":"104.442509ms","start":"2026-04-01T10:33:19.851533Z","end":"2026-04-01T10:33:19.955975Z","steps":["trace[1273419417] 'agreement among raft nodes before linearized reading' (duration: 104.372207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:20.471867Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"304.992746ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-01T10:33:20.472645Z","caller":"traceutil/trace.go:172","msg":"trace[1221085499] linearizableReadLoop","detail":"{readStateIndex:6429; appliedIndex:6428; }","duration":"405.111227ms","start":"2026-04-01T10:33:20.067506Z","end":"2026-04-01T10:33:20.472617Z","steps":["trace[1221085499] 'read index received' (duration: 100.215323ms)","trace[1221085499] 'applied index is now lower than readState.Index' (duration: 304.893034ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:20.472925Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"405.414114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:33:20.473020Z","caller":"traceutil/trace.go:172","msg":"trace[1038647457] range","detail":"{range_begin:/registry/leases; range_end:; response_count:0; response_revision:5567; }","duration":"405.514386ms","start":"2026-04-01T10:33:20.067499Z","end":"2026-04-01T10:33:20.473014Z","steps":["trace[1038647457] 'agreement among raft nodes before linearized reading' (duration: 405.400773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:20.473042Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:33:20.067477Z","time spent":"405.557606ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":20,"response count":0,"response size":28,"request content":"key:\"/registry/leases\" limit:1 "} {"level":"warn","ts":"2026-04-01T10:33:20.474571Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"338.163134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T10:33:20.474593Z","caller":"traceutil/trace.go:172","msg":"trace[628998982] range","detail":"{range_begin:/registry/clusterrolebindings; range_end:; response_count:0; response_revision:5567; }","duration":"338.186855ms","start":"2026-04-01T10:33:20.136400Z","end":"2026-04-01T10:33:20.474587Z","steps":["trace[628998982] 'agreement among raft nodes before linearized reading' (duration: 338.150654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T10:33:20.474607Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-01T10:33:20.136373Z","time spent":"338.229536ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":33,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings\" limit:1 "} {"level":"info","ts":"2026-04-01T10:33:21.416995Z","caller":"traceutil/trace.go:172","msg":"trace[1550840326] transaction","detail":"{read_only:false; response_revision:5570; number_of_response:1; }","duration":"161.673582ms","start":"2026-04-01T10:33:21.255299Z","end":"2026-04-01T10:33:21.416973Z","steps":["trace[1550840326] 'process raft request' (duration: 161.496129ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T10:33:21.623364Z","caller":"traceutil/trace.go:172","msg":"trace[247554545] linearizableReadLoop","detail":"{readStateIndex:6434; appliedIndex:6433; }","duration":"126.868489ms","start":"2026-04-01T10:33:21.496467Z","end":"2026-04-01T10:33:21.623335Z","steps":["trace[247554545] 'read index received' (duration: 62.179124ms)","trace[247554545] 'applied index is now lower than readState.Index' (duration: 64.686815ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T10:33:21.623596Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"127.109753ms","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-01T10:33:21.623729Z","caller":"traceutil/trace.go:172","msg":"trace[703849738] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5571; }","duration":"127.242416ms","start":"2026-04-01T10:33:21.496449Z","end":"2026-04-01T10:33:21.623692Z","steps":["trace[703849738] 'agreement among raft nodes before linearized reading' (duration: 127.020051ms)"],"step_count":1}