{"level":"warn","ts":"2026-07-06T14:29:48.214150Z","caller":"embed/config.go:1209","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"warn","ts":"2026-07-06T14:29:48.214303Z","caller":"etcdmain/config.go:270","msg":"--snapshot-count is deprecated in 3.6 and will be decommissioned in 3.7."} {"level":"info","ts":"2026-07-06T14:29:48.214320Z","caller":"etcdmain/etcd.go:64","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.166:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--feature-gates=InitialCorruptCheck=true","--initial-advertise-peer-urls=https://199.19.213.166:2380","--initial-cluster=controller-3=https://199.19.213.166:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.166:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.166:2380","--name=controller-3","--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-07-06T14:29:48.214416Z","caller":"etcdmain/etcd.go:107","msg":"server has already been initialized","data-dir":"/var/lib/etcd","dir-type":"member"} {"level":"warn","ts":"2026-07-06T14:29:48.214438Z","caller":"embed/config.go:1209","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T14:29:48.214453Z","caller":"embed/etcd.go:138","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.166:2380"]} {"level":"info","ts":"2026-07-06T14:29:48.214480Z","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-07-06T14:29:48.219009Z","caller":"embed/etcd.go:146","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.166:2379"]} {"level":"info","ts":"2026-07-06T14:29:48.219321Z","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-3","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.166:2380"],"listen-peer-urls":["https://199.19.213.166:2380"],"advertise-client-urls":["https://199.19.213.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.166: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-07-06T14:29:48.220546Z","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: 0xc00011a4e8}"} {"level":"info","ts":"2026-07-06T14:29:48.229575Z","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-07-06T14:29:48.229639Z","caller":"storage/backend.go:80","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.298217ms"} {"level":"info","ts":"2026-07-06T14:29:48.229836Z","caller":"etcdserver/bootstrap.go:220","msg":"restore consistentIndex","index":5331} {"level":"info","ts":"2026-07-06T14:29:48.272087Z","caller":"etcdserver/bootstrap.go:441","msg":"No snapshot found. Recovering WAL from scratch!"} {"level":"info","ts":"2026-07-06T14:29:48.272161Z","caller":"etcdserver/bootstrap.go:232","msg":"recovered v3 backend","backend-size-bytes":25419776,"backend-size":"25 MB","backend-size-in-use-bytes":8474624,"backend-size-in-use":"8.5 MB"} {"level":"info","ts":"2026-07-06T14:29:48.272289Z","caller":"etcdserver/bootstrap.go:90","msg":"Bootstrapping WAL from snapshot"} {"level":"info","ts":"2026-07-06T14:29:48.327110Z","caller":"etcdserver/bootstrap.go:599","msg":"restarting local member","cluster-id":"bea7a5ea8f053dc3","local-member-id":"75c3b00930144e6c","commit-index":5330} {"level":"info","ts":"2026-07-06T14:29:48.327177Z","caller":"etcdserver/bootstrap.go:94","msg":"bootstrapping cluster"} {"level":"info","ts":"2026-07-06T14:29:48.327447Z","caller":"etcdserver/bootstrap.go:101","msg":"bootstrapping storage"} {"level":"info","ts":"2026-07-06T14:29:48.327697Z","caller":"membership/cluster.go:605","msg":"Detected member only in v3store but missing in v2store","member":"{ID:75c3b00930144e6c RaftAttributes:{PeerURLs:[https://199.19.213.166:2380] IsLearner:false} Attributes:{Name:controller-3 ClientURLs:[https://199.19.213.166:2379]}}"} {"level":"info","ts":"2026-07-06T14:29:48.327742Z","caller":"membership/cluster.go:605","msg":"Detected member only in v3store but missing in v2store","member":"{ID:a99ab19297e6b4a0 RaftAttributes:{PeerURLs:[https://199.19.213.81:2380] IsLearner:false} Attributes:{Name:controller-2 ClientURLs:[https://199.19.213.81:2379]}}"} {"level":"info","ts":"2026-07-06T14:29:48.327756Z","caller":"membership/cluster.go:605","msg":"Detected member only in v3store but missing in v2store","member":"{ID:c51d7a9ea5537d6e RaftAttributes:{PeerURLs:[https://199.19.213.237:2380] IsLearner:false} Attributes:{Name:controller-1 ClientURLs:[https://199.19.213.237:2379]}}"} {"level":"info","ts":"2026-07-06T14:29:48.327991Z","caller":"api/capability.go:76","msg":"enabled capabilities for version","cluster-version":"3.6"} {"level":"info","ts":"2026-07-06T14:29:48.328016Z","caller":"membership/cluster.go:297","msg":"recovered/added member from store","cluster-id":"bea7a5ea8f053dc3","local-member-id":"75c3b00930144e6c","recovered-remote-peer-id":"75c3b00930144e6c","recovered-remote-peer-urls":["https://199.19.213.166:2380"],"recovered-remote-peer-is-learner":false} {"level":"info","ts":"2026-07-06T14:29:48.328030Z","caller":"membership/cluster.go:297","msg":"recovered/added member from store","cluster-id":"bea7a5ea8f053dc3","local-member-id":"75c3b00930144e6c","recovered-remote-peer-id":"a99ab19297e6b4a0","recovered-remote-peer-urls":["https://199.19.213.81:2380"],"recovered-remote-peer-is-learner":false} {"level":"info","ts":"2026-07-06T14:29:48.328039Z","caller":"membership/cluster.go:297","msg":"recovered/added member from store","cluster-id":"bea7a5ea8f053dc3","local-member-id":"75c3b00930144e6c","recovered-remote-peer-id":"c51d7a9ea5537d6e","recovered-remote-peer-urls":["https://199.19.213.237:2380"],"recovered-remote-peer-is-learner":false} {"level":"info","ts":"2026-07-06T14:29:48.328067Z","caller":"membership/cluster.go:307","msg":"set cluster version from store","cluster-version":"3.6"} {"level":"info","ts":"2026-07-06T14:29:48.328197Z","caller":"etcdserver/bootstrap.go:109","msg":"bootstrapping raft"} {"level":"info","ts":"2026-07-06T14:29:48.328329Z","caller":"etcdserver/server.go:312","msg":"bootstrap successfully"} {"level":"info","ts":"2026-07-06T14:29:48.328497Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"75c3b00930144e6c switched to configuration voters=()"} {"level":"info","ts":"2026-07-06T14:29:48.328546Z","logger":"raft","caller":"v3@v3.6.0/raft.go:897","msg":"75c3b00930144e6c became follower at term 6"} {"level":"info","ts":"2026-07-06T14:29:48.328628Z","logger":"raft","caller":"v3@v3.6.0/raft.go:493","msg":"newRaft 75c3b00930144e6c [peers: [], term: 6, commit: 5330, applied: 0, lastindex: 5331, lastterm: 6]"} {"level":"warn","ts":"2026-07-06T14:29:48.330491Z","caller":"auth/store.go:1135","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-06T14:29:48.332320Z","caller":"mvcc/kvstore.go:334","msg":"restored last compact revision","meta-bucket-name-key":"finishedCompactRev","restored-compact-revision":2361} {"level":"info","ts":"2026-07-06T14:29:48.336455Z","caller":"mvcc/kvstore.go:408","msg":"kvstore restored","current-rev":4619} {"level":"info","ts":"2026-07-06T14:29:48.340827Z","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-07-06T14:29:48.341324Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"a99ab19297e6b4a0"} {"level":"info","ts":"2026-07-06T14:29:48.341353Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"75c3b00930144e6c","remote-peer-id":"a99ab19297e6b4a0"} {"level":"info","ts":"2026-07-06T14:29:48.342210Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"a99ab19297e6b4a0"} {"level":"info","ts":"2026-07-06T14:29:48.342248Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"75c3b00930144e6c","remote-peer-id":"a99ab19297e6b4a0","remote-peer-urls":["https://199.19.213.81:2380"]} {"level":"info","ts":"2026-07-06T14:29:48.342284Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"c51d7a9ea5537d6e"} {"level":"info","ts":"2026-07-06T14:29:48.342300Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"75c3b00930144e6c","remote-peer-id":"c51d7a9ea5537d6e"} {"level":"info","ts":"2026-07-06T14:29:48.342529Z","caller":"rafthttp/stream.go:394","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"75c3b00930144e6c","remote-peer-id":"a99ab19297e6b4a0"} {"level":"info","ts":"2026-07-06T14:29:48.342688Z","caller":"rafthttp/stream.go:394","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"75c3b00930144e6c","remote-peer-id":"a99ab19297e6b4a0"} {"level":"info","ts":"2026-07-06T14:29:48.342802Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"c51d7a9ea5537d6e"} {"level":"info","ts":"2026-07-06T14:29:48.342826Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"75c3b00930144e6c","remote-peer-id":"c51d7a9ea5537d6e","remote-peer-urls":["https://199.19.213.237:2380"]} {"level":"info","ts":"2026-07-06T14:29:48.342846Z","caller":"etcdserver/corrupt.go:91","msg":"starting initial corruption check","local-member-id":"75c3b00930144e6c","timeout":"7s"} {"level":"info","ts":"2026-07-06T14:29:48.342867Z","caller":"rafthttp/stream.go:168","msg":"started stream writer with remote peer","local-member-id":"75c3b00930144e6c","remote-peer-id":"c51d7a9ea5537d6e"} {"level":"info","ts":"2026-07-06T14:29:48.342839Z","caller":"rafthttp/stream.go:168","msg":"started stream writer with remote peer","local-member-id":"75c3b00930144e6c","remote-peer-id":"c51d7a9ea5537d6e"} {"level":"info","ts":"2026-07-06T14:29:48.342885Z","caller":"rafthttp/stream.go:394","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"75c3b00930144e6c","remote-peer-id":"c51d7a9ea5537d6e"} {"level":"info","ts":"2026-07-06T14:29:48.342958Z","caller":"rafthttp/stream.go:394","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"75c3b00930144e6c","remote-peer-id":"c51d7a9ea5537d6e"} {"level":"info","ts":"2026-07-06T14:29:48.342980Z","caller":"rafthttp/stream.go:168","msg":"started stream writer with remote peer","local-member-id":"75c3b00930144e6c","remote-peer-id":"a99ab19297e6b4a0"} {"level":"info","ts":"2026-07-06T14:29:48.342655Z","caller":"rafthttp/stream.go:168","msg":"started stream writer with remote peer","local-member-id":"75c3b00930144e6c","remote-peer-id":"a99ab19297e6b4a0"} {"level":"warn","ts":"2026-07-06T14:29:48.349182Z","caller":"etcdserver/corrupt.go:494","msg":"failed hash kv request","local-member-id":"75c3b00930144e6c","requested-revision":4619,"remote-peer-endpoint":"https://199.19.213.81:2380","error":"Get \"https://199.19.213.81:2380/members/hashkv\": dial tcp 199.19.213.81:2380: connect: connection refused"} {"level":"info","ts":"2026-07-06T14:29:48.356035Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"c51d7a9ea5537d6e"} {"level":"info","ts":"2026-07-06T14:29:48.356091Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"75c3b00930144e6c","remote-peer-id":"c51d7a9ea5537d6e"} {"level":"info","ts":"2026-07-06T14:29:48.358768Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"75c3b00930144e6c","remote-peer-id":"c51d7a9ea5537d6e"} {"level":"info","ts":"2026-07-06T14:29:48.361380Z","caller":"etcdserver/corrupt.go:172","msg":"initial corruption checking passed; no corruption","local-member-id":"75c3b00930144e6c"} {"level":"info","ts":"2026-07-06T14:29:48.361432Z","caller":"etcdserver/server.go:589","msg":"starting etcd server","local-member-id":"75c3b00930144e6c","local-server-version":"3.6.4","cluster-id":"bea7a5ea8f053dc3","cluster-version":"3.6"} {"level":"info","ts":"2026-07-06T14:29:48.361543Z","caller":"etcdserver/server.go:499","msg":"starting initial election tick advance","election-ticks":10} {"level":"info","ts":"2026-07-06T14:29:48.361613Z","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-07-06T14:29:48.361679Z","caller":"embed/etcd.go:640","msg":"serving peer traffic","address":"199.19.213.166:2380"} {"level":"info","ts":"2026-07-06T14:29:48.361661Z","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-07-06T14:29:48.361708Z","caller":"embed/etcd.go:611","msg":"cmux::serve","address":"199.19.213.166:2380"} {"level":"info","ts":"2026-07-06T14:29:48.361688Z","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-07-06T14:29:48.361774Z","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-07-06T14:29:48.361828Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"75c3b00930144e6c switched to configuration voters=(14203643621593546094)"} {"level":"info","ts":"2026-07-06T14:29:48.361933Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"75c3b00930144e6c","to":"c51d7a9ea5537d6e","stream-type":"stream Message"} {"level":"info","ts":"2026-07-06T14:29:48.361949Z","caller":"membership/cluster.go:433","msg":"ignore already added member","cluster-id":"bea7a5ea8f053dc3","local-member-id":"75c3b00930144e6c","added-peer-id":"c51d7a9ea5537d6e","added-peer-peer-urls":["https://199.19.213.237:2380"],"added-peer-is-learner":false} {"level":"info","ts":"2026-07-06T14:29:48.361953Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"75c3b00930144e6c","remote-peer-id":"c51d7a9ea5537d6e"} {"level":"info","ts":"2026-07-06T14:29:48.362004Z","caller":"embed/etcd.go:292","msg":"now serving peer/client/metrics","local-member-id":"75c3b00930144e6c","initial-advertise-peer-urls":["https://199.19.213.166:2380"],"listen-peer-urls":["https://199.19.213.166:2380"],"advertise-client-urls":["https://199.19.213.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-06T14:29:48.362032Z","caller":"embed/etcd.go:890","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-06T14:29:48.362069Z","caller":"membership/cluster.go:674","msg":"updated cluster version","cluster-id":"bea7a5ea8f053dc3","local-member-id":"75c3b00930144e6c","from":"3.6","to":"3.5"} {"level":"info","ts":"2026-07-06T14:29:48.362130Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"75c3b00930144e6c","to":"c51d7a9ea5537d6e","stream-type":"stream MsgApp v2"} {"level":"info","ts":"2026-07-06T14:29:48.362146Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"75c3b00930144e6c","remote-peer-id":"c51d7a9ea5537d6e"} {"level":"info","ts":"2026-07-06T14:29:48.362371Z","caller":"api/capability.go:76","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T14:29:48.362389Z","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-07-06T14:29:48.362518Z","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-07-06T14:29:48.363883Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"75c3b00930144e6c switched to configuration voters=(14203643621593546094) learners=(12221275781995082912)"} {"level":"info","ts":"2026-07-06T14:29:48.363930Z","caller":"membership/cluster.go:433","msg":"ignore already added member","cluster-id":"bea7a5ea8f053dc3","local-member-id":"75c3b00930144e6c","added-peer-id":"a99ab19297e6b4a0","added-peer-peer-urls":["https://199.19.213.81:2380"],"added-peer-is-learner":true} {"level":"info","ts":"2026-07-06T14:29:48.364032Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"75c3b00930144e6c switched to configuration voters=(12221275781995082912 14203643621593546094)"} {"level":"info","ts":"2026-07-06T14:29:48.364081Z","caller":"membership/cluster.go:574","msg":"ignore already promoted member in v3store","cluster-id":"bea7a5ea8f053dc3","local-member-id":"75c3b00930144e6c","promoted-member-id":"a99ab19297e6b4a0"} {"level":"info","ts":"2026-07-06T14:29:48.364767Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"75c3b00930144e6c switched to configuration voters=(12221275781995082912 14203643621593546094) learners=(8485819676403977836)"} {"level":"info","ts":"2026-07-06T14:29:48.364804Z","caller":"membership/cluster.go:433","msg":"ignore already added member","cluster-id":"bea7a5ea8f053dc3","local-member-id":"75c3b00930144e6c","added-peer-id":"75c3b00930144e6c","added-peer-peer-urls":["https://199.19.213.166:2380"],"added-peer-is-learner":true} {"level":"info","ts":"2026-07-06T14:29:48.364911Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"75c3b00930144e6c switched to configuration voters=(8485819676403977836 12221275781995082912 14203643621593546094)"} {"level":"info","ts":"2026-07-06T14:29:48.364975Z","caller":"membership/cluster.go:574","msg":"ignore already promoted member in v3store","cluster-id":"bea7a5ea8f053dc3","local-member-id":"75c3b00930144e6c","promoted-member-id":"75c3b00930144e6c"} {"level":"info","ts":"2026-07-06T14:29:48.365690Z","caller":"etcdserver/server.go:2185","msg":"triggering snapshot","local-member-id":"75c3b00930144e6c","local-member-applied-index":839,"local-member-snapshot-index":0,"local-member-snapshot-count":10000,"snapshot-forced":true} {"level":"info","ts":"2026-07-06T14:29:48.537378Z","caller":"schema/migration.go:65","msg":"updated storage version","new-storage-version":"3.5.0"} {"level":"info","ts":"2026-07-06T14:29:48.693696Z","caller":"etcdserver/server.go:2230","msg":"saved snapshot to disk","snapshot-index":839} {"level":"info","ts":"2026-07-06T14:29:48.709965Z","caller":"membership/cluster.go:674","msg":"updated cluster version","cluster-id":"bea7a5ea8f053dc3","local-member-id":"75c3b00930144e6c","from":"3.5","to":"3.6"} {"level":"info","ts":"2026-07-06T14:29:48.710234Z","caller":"api/capability.go:76","msg":"enabled capabilities for version","cluster-version":"3.6"} {"level":"info","ts":"2026-07-06T14:29:48.710464Z","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-07-06T14:29:48.710545Z","caller":"schema/migration.go:65","msg":"updated storage version","new-storage-version":"3.6.0"} {"level":"info","ts":"2026-07-06T14:29:49.249046Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1233","msg":"75c3b00930144e6c [logterm: 6, index: 5331, vote: 75c3b00930144e6c] cast MsgPreVote for c51d7a9ea5537d6e [logterm: 6, index: 5331] at term 6"} {"level":"info","ts":"2026-07-06T14:29:49.252148Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1116","msg":"75c3b00930144e6c [term: 6] received a MsgVote message with higher term from c51d7a9ea5537d6e [term: 7]"} {"level":"info","ts":"2026-07-06T14:29:49.252195Z","logger":"raft","caller":"v3@v3.6.0/raft.go:897","msg":"75c3b00930144e6c became follower at term 7"} {"level":"info","ts":"2026-07-06T14:29:49.252212Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1233","msg":"75c3b00930144e6c [logterm: 6, index: 5331, vote: 0] cast MsgVote for c51d7a9ea5537d6e [logterm: 6, index: 5331] at term 7"} {"level":"info","ts":"2026-07-06T14:29:49.255223Z","logger":"raft","caller":"v3@v3.6.0/node.go:370","msg":"raft.node: 75c3b00930144e6c elected leader c51d7a9ea5537d6e at term 7"} {"level":"info","ts":"2026-07-06T14:29:49.260466Z","caller":"etcdserver/server.go:1804","msg":"published local member to cluster through raft","local-member-id":"75c3b00930144e6c","local-member-attributes":"{Name:controller-3 ClientURLs:[https://199.19.213.166:2379]}","cluster-id":"bea7a5ea8f053dc3","publish-timeout":"7s"} {"level":"info","ts":"2026-07-06T14:29:49.260628Z","caller":"embed/serve.go:138","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T14:29:49.260660Z","caller":"embed/serve.go:138","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T14:29:49.260870Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-06T14:29:49.260948Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-06T14:29:49.261881Z","caller":"v3rpc/health.go:63","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"warn","ts":"2026-07-06T14:29:49.262191Z","caller":"v3rpc/grpc.go:52","msg":"etcdserver: failed to register grpc metrics","error":"duplicate metrics collector registration attempted"} {"level":"info","ts":"2026-07-06T14:29:49.262302Z","caller":"v3rpc/health.go:63","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-07-06T14:29:49.263802Z","caller":"embed/serve.go:283","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.166:2379"} {"level":"info","ts":"2026-07-06T14:29:49.264639Z","caller":"embed/serve.go:283","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-06T14:29:49.370817Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45270","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.397138Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45292","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.405115Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45300","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.419121Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45332","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.445471Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45354","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.459169Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45366","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.467278Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45398","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.477351Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45408","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.493025Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45420","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.507538Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45438","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.517187Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45456","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.526374Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45472","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.535021Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45498","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.542522Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45516","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.549526Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45538","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.560029Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45548","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.579956Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45562","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.589810Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45588","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.599269Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45596","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.611609Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45614","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.645967Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45624","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.657693Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45640","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.670429Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45654","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.680081Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45674","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.690803Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45690","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.700165Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45706","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.710039Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45734","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.718829Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45750","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.727971Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45762","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.737634Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45782","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.751194Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45794","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.759409Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45808","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.767454Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45826","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.779327Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45842","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.788105Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45866","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.795365Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45898","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.804843Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45930","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.814981Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45952","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.824737Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45954","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.846135Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45968","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.857088Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:45980","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.867497Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46000","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.877283Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46020","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.887352Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46036","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.896285Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46054","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.904107Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46078","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.928145Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46110","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.937509Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46130","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.944933Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46138","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.956930Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46156","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.966071Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46194","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.983002Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46202","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.994093Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46216","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:49.999744Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46236","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:50.005839Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46256","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:50.037732Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46302","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:50.046297Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46318","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:50.096537Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46344","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-07-06T14:29:53.342811Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_SNAPSHOT","remote-peer-id":"a99ab19297e6b4a0","rtt":"0s","error":"dial tcp 199.19.213.81:2380: connect: connection refused"} {"level":"warn","ts":"2026-07-06T14:29:53.342969Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_RAFT_MESSAGE","remote-peer-id":"a99ab19297e6b4a0","rtt":"0s","error":"dial tcp 199.19.213.81:2380: connect: connection refused"} {"level":"warn","ts":"2026-07-06T14:29:55.410645Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"318.991067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T14:29:55.410743Z","caller":"traceutil/trace.go:172","msg":"trace[111724019] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"689.98373ms","start":"2026-07-06T14:29:54.720746Z","end":"2026-07-06T14:29:55.410729Z","steps":["trace[111724019] 'process raft request' (duration: 370.59597ms)","trace[111724019] 'compare' (duration: 318.179429ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:29:55.410792Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:54.720717Z","time spent":"690.05302ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20074,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T14:29:55.550579Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5651066694602631523,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T14:29:55.881394Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"173.782838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T14:29:55.987641Z","caller":"traceutil/trace.go:172","msg":"trace[1810423056] linearizableReadLoop","detail":"{readStateIndex:5392; appliedIndex:5392; }","duration":"938.014935ms","start":"2026-07-06T14:29:55.049599Z","end":"2026-07-06T14:29:55.987614Z","steps":["trace[1810423056] 'read index received' (duration: 938.008895ms)","trace[1810423056] 'applied index is now lower than readState.Index' (duration: 5.02µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:29:56.040344Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"75c3b00930144e6c","to":"a99ab19297e6b4a0","stream-type":"stream Message"} {"level":"info","ts":"2026-07-06T14:29:56.040405Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"a99ab19297e6b4a0"} {"level":"info","ts":"2026-07-06T14:29:56.040448Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"75c3b00930144e6c","remote-peer-id":"a99ab19297e6b4a0"} {"level":"info","ts":"2026-07-06T14:29:56.046212Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"75c3b00930144e6c","to":"a99ab19297e6b4a0","stream-type":"stream MsgApp v2"} {"level":"info","ts":"2026-07-06T14:29:56.046239Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"75c3b00930144e6c","remote-peer-id":"a99ab19297e6b4a0"} {"level":"warn","ts":"2026-07-06T14:29:56.208806Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"222.766822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-07-06T14:29:56.208856Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.159223484s","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-07-06T14:29:56.208944Z","caller":"traceutil/trace.go:172","msg":"trace[1912073886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4675; }","duration":"1.159322595s","start":"2026-07-06T14:29:55.049595Z","end":"2026-07-06T14:29:56.208917Z","steps":["trace[1912073886] 'agreement among raft nodes before linearized reading' (duration: 938.148106ms)","trace[1912073886] 'range keys from in-memory index tree' (duration: 218.652448ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:29:56.208995Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:55.049577Z","time spent":"1.159405205s","remote":"127.0.0.1:45696","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":"info","ts":"2026-07-06T14:29:56.209061Z","caller":"traceutil/trace.go:172","msg":"trace[1036238166] linearizableReadLoop","detail":"{readStateIndex:5395; appliedIndex:5392; }","duration":"221.334431ms","start":"2026-07-06T14:29:55.987711Z","end":"2026-07-06T14:29:56.209045Z","steps":["trace[1036238166] 'read index received' (duration: 8.240428ms)","trace[1036238166] 'applied index is now lower than readState.Index' (duration: 213.093113ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:29:56.209147Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"348.4025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-06T14:29:56.209190Z","caller":"traceutil/trace.go:172","msg":"trace[34793435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4678; }","duration":"348.449171ms","start":"2026-07-06T14:29:55.860733Z","end":"2026-07-06T14:29:56.209182Z","steps":["trace[34793435] 'agreement among raft nodes before linearized reading' (duration: 348.34172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:29:56.209198Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"904.61258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:443"} {"level":"warn","ts":"2026-07-06T14:29:56.209216Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:55.860710Z","time spent":"348.499292ms","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"info","ts":"2026-07-06T14:29:56.209225Z","caller":"traceutil/trace.go:172","msg":"trace[1626119304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4678; }","duration":"904.64792ms","start":"2026-07-06T14:29:55.304569Z","end":"2026-07-06T14:29:56.209217Z","steps":["trace[1626119304] 'agreement among raft nodes before linearized reading' (duration: 904.525729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:29:56.209218Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"831.417617ms","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":"warn","ts":"2026-07-06T14:29:56.209267Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:55.304540Z","time spent":"904.71844ms","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":466,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-07-06T14:29:56.209273Z","caller":"traceutil/trace.go:172","msg":"trace[2024937608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4678; }","duration":"831.486577ms","start":"2026-07-06T14:29:55.377775Z","end":"2026-07-06T14:29:56.209262Z","steps":["trace[2024937608] 'agreement among raft nodes before linearized reading' (duration: 831.315066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:29:56.209386Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:55.377755Z","time spent":"831.599348ms","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":507,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 "} {"level":"info","ts":"2026-07-06T14:29:56.226387Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"75c3b00930144e6c","remote-peer-id":"a99ab19297e6b4a0"} {"level":"info","ts":"2026-07-06T14:29:56.226816Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"75c3b00930144e6c","remote-peer-id":"a99ab19297e6b4a0"} {"level":"warn","ts":"2026-07-06T14:29:56.557794Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"167.789031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T14:29:56.886615Z","caller":"traceutil/trace.go:172","msg":"trace[797796026] transaction","detail":"{read_only:false; response_revision:4695; number_of_response:1; }","duration":"659.5389ms","start":"2026-07-06T14:29:56.227049Z","end":"2026-07-06T14:29:56.886588Z","steps":["trace[797796026] 'process raft request' (duration: 568.092957ms)","trace[797796026] 'compare' (duration: 91.22443ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:29:56.886762Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:56.227029Z","time spent":"659.661841ms","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T14:29:57.270710Z","caller":"traceutil/trace.go:172","msg":"trace[142456575] linearizableReadLoop","detail":"{readStateIndex:5435; appliedIndex:5435; }","duration":"379.334516ms","start":"2026-07-06T14:29:56.891360Z","end":"2026-07-06T14:29:57.270695Z","steps":["trace[142456575] 'read index received' (duration: 379.331336ms)","trace[142456575] 'applied index is now lower than readState.Index' (duration: 2.43µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:29:57.270876Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"379.494658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-66bc5c9577-c4twq\" limit:1 ","response":"range_response_count:1 size:5708"} {"level":"info","ts":"2026-07-06T14:29:57.270897Z","caller":"traceutil/trace.go:172","msg":"trace[2061181599] range","detail":"{range_begin:/registry/pods/kube-system/coredns-66bc5c9577-c4twq; range_end:; response_count:1; response_revision:4716; }","duration":"379.533588ms","start":"2026-07-06T14:29:56.891357Z","end":"2026-07-06T14:29:57.270891Z","steps":["trace[2061181599] 'agreement among raft nodes before linearized reading' (duration: 379.411217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:29:57.270917Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:56.891343Z","time spent":"379.569328ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":5731,"request content":"key:\"/registry/pods/kube-system/coredns-66bc5c9577-c4twq\" limit:1 "} {"level":"info","ts":"2026-07-06T14:29:57.799814Z","caller":"traceutil/trace.go:172","msg":"trace[1245233993] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"520.72619ms","start":"2026-07-06T14:29:57.279040Z","end":"2026-07-06T14:29:57.799766Z","steps":["trace[1245233993] 'process raft request' (duration: 520.499377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:29:57.799994Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:57.279014Z","time spent":"520.903381ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T14:29:58.128007Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"116.063845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-07-06T14:29:58.188608Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:57.675536Z","time spent":"513.031039ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-06T14:29:58.517118Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5651066694602631528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T14:29:58.738366Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"143.955801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T14:29:58.860803Z","caller":"traceutil/trace.go:172","msg":"trace[1605890037] linearizableReadLoop","detail":"{readStateIndex:5446; appliedIndex:5446; }","duration":"843.827823ms","start":"2026-07-06T14:29:58.016952Z","end":"2026-07-06T14:29:58.860780Z","steps":["trace[1605890037] 'read index received' (duration: 843.820473ms)","trace[1605890037] 'applied index is now lower than readState.Index' (duration: 6.47µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:29:58.888880Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"871.913522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-8b6d98d9b-8zhr9\" limit:1 ","response":"range_response_count:1 size:6476"} {"level":"info","ts":"2026-07-06T14:29:58.888939Z","caller":"traceutil/trace.go:172","msg":"trace[1405730197] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-8b6d98d9b-8zhr9; range_end:; response_count:1; response_revision:4726; }","duration":"871.988863ms","start":"2026-07-06T14:29:58.016936Z","end":"2026-07-06T14:29:58.888925Z","steps":["trace[1405730197] 'agreement among raft nodes before linearized reading' (duration: 843.944955ms)","trace[1405730197] 'range keys from in-memory index tree' (duration: 27.871447ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:29:58.888970Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:58.016909Z","time spent":"872.055393ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":6499,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-8b6d98d9b-8zhr9\" limit:1 "} {"level":"info","ts":"2026-07-06T14:29:58.889074Z","caller":"traceutil/trace.go:172","msg":"trace[1607554657] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"698.748654ms","start":"2026-07-06T14:29:58.190305Z","end":"2026-07-06T14:29:58.889053Z","steps":["trace[1607554657] 'process raft request' (duration: 695.205454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:29:58.889193Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:58.190275Z","time spent":"698.854735ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":717,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T14:29:59.067072Z","caller":"traceutil/trace.go:172","msg":"trace[843791888] linearizableReadLoop","detail":"{readStateIndex:5447; appliedIndex:5447; }","duration":"174.701769ms","start":"2026-07-06T14:29:58.892311Z","end":"2026-07-06T14:29:59.067013Z","steps":["trace[843791888] 'read index received' (duration: 174.671728ms)","trace[843791888] 'applied index is now lower than readState.Index' (duration: 28.121µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:29:59.311433Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"244.328721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T14:29:59.311525Z","caller":"traceutil/trace.go:172","msg":"trace[115843810] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"419.480174ms","start":"2026-07-06T14:29:58.892032Z","end":"2026-07-06T14:29:59.311512Z","steps":["trace[115843810] 'process raft request' (duration: 175.028772ms)","trace[115843810] 'compare' (duration: 244.23116ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:29:59.311558Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:58.892011Z","time spent":"419.535614ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":747,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T14:29:59.311539Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"419.174571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-06T14:29:59.311685Z","caller":"traceutil/trace.go:172","msg":"trace[1234223392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4727; }","duration":"419.347092ms","start":"2026-07-06T14:29:58.892308Z","end":"2026-07-06T14:29:59.311655Z","steps":["trace[1234223392] 'agreement among raft nodes before linearized reading' (duration: 174.83254ms)","trace[1234223392] 'range keys from in-memory index tree' (duration: 244.143189ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:29:59.311757Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:58.892297Z","time spent":"419.446353ms","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"info","ts":"2026-07-06T14:29:59.354826Z","caller":"traceutil/trace.go:172","msg":"trace[1952385252] linearizableReadLoop","detail":"{readStateIndex:5448; appliedIndex:5448; }","duration":"287.584314ms","start":"2026-07-06T14:29:59.067155Z","end":"2026-07-06T14:29:59.354739Z","steps":["trace[1952385252] 'read index received' (duration: 287.573284ms)","trace[1952385252] 'applied index is now lower than readState.Index' (duration: 9.54µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:29:59.355082Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"378.30738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:442"} {"level":"info","ts":"2026-07-06T14:29:59.355155Z","caller":"traceutil/trace.go:172","msg":"trace[787214679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4728; }","duration":"378.392011ms","start":"2026-07-06T14:29:58.976747Z","end":"2026-07-06T14:29:59.355139Z","steps":["trace[787214679] 'agreement among raft nodes before linearized reading' (duration: 378.183419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:29:59.355197Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:58.976720Z","time spent":"378.467111ms","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-07-06T14:29:59.355586Z","caller":"traceutil/trace.go:172","msg":"trace[1761380622] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"458.137867ms","start":"2026-07-06T14:29:58.897438Z","end":"2026-07-06T14:29:59.355576Z","steps":["trace[1761380622] 'process raft request' (duration: 457.441772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:29:59.355661Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:58.897420Z","time spent":"458.199348ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6271,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T14:29:59.649851Z","caller":"traceutil/trace.go:172","msg":"trace[342853752] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"332.379718ms","start":"2026-07-06T14:29:59.317413Z","end":"2026-07-06T14:29:59.649792Z","steps":["trace[342853752] 'process raft request' (duration: 332.307678ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:29:59.649895Z","caller":"traceutil/trace.go:172","msg":"trace[161545511] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"334.643958ms","start":"2026-07-06T14:29:59.315218Z","end":"2026-07-06T14:29:59.649862Z","steps":["trace[161545511] 'process raft request' (duration: 334.357235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:29:59.650034Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:59.317396Z","time spent":"332.555081ms","remote":"127.0.0.1:45696","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-07-06T14:29:59.650051Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:59.315192Z","time spent":"334.78558ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":793,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T14:29:59.682231Z","caller":"traceutil/trace.go:172","msg":"trace[292373463] linearizableReadLoop","detail":"{readStateIndex:5459; appliedIndex:5459; }","duration":"327.295283ms","start":"2026-07-06T14:29:59.354915Z","end":"2026-07-06T14:29:59.682210Z","steps":["trace[292373463] 'read index received' (duration: 327.288573ms)","trace[292373463] 'applied index is now lower than readState.Index' (duration: 5.7µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:29:59.729235Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"434.026403ms","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-07-06T14:29:59.729330Z","caller":"traceutil/trace.go:172","msg":"trace[1876064900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4738; }","duration":"434.132534ms","start":"2026-07-06T14:29:59.295175Z","end":"2026-07-06T14:29:59.729307Z","steps":["trace[1876064900] 'agreement among raft nodes before linearized reading' (duration: 387.12736ms)","trace[1876064900] 'range keys from in-memory index tree' (duration: 46.755982ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:29:59.729385Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:29:59.295154Z","time spent":"434.215524ms","remote":"127.0.0.1:45696","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":"info","ts":"2026-07-06T14:29:59.766233Z","caller":"traceutil/trace.go:172","msg":"trace[465278718] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"112.595178ms","start":"2026-07-06T14:29:59.653602Z","end":"2026-07-06T14:29:59.766197Z","steps":["trace[465278718] 'process raft request' (duration: 112.352216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:00.068090Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"130.182267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T14:30:00.068192Z","caller":"traceutil/trace.go:172","msg":"trace[30709730] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"298.318313ms","start":"2026-07-06T14:29:59.769858Z","end":"2026-07-06T14:30:00.068176Z","steps":["trace[30709730] 'process raft request' (duration: 167.998295ms)","trace[30709730] 'compare' (duration: 129.903575ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:30:00.258753Z","caller":"traceutil/trace.go:172","msg":"trace[1015680774] linearizableReadLoop","detail":"{readStateIndex:5464; appliedIndex:5463; }","duration":"120.134798ms","start":"2026-07-06T14:30:00.138596Z","end":"2026-07-06T14:30:00.258731Z","steps":["trace[1015680774] 'read index received' (duration: 77.358795ms)","trace[1015680774] 'applied index is now lower than readState.Index' (duration: 42.774583ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:30:00.258783Z","caller":"traceutil/trace.go:172","msg":"trace[1991553628] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"187.01183ms","start":"2026-07-06T14:30:00.071747Z","end":"2026-07-06T14:30:00.258759Z","steps":["trace[1991553628] 'process raft request' (duration: 142.197039ms)","trace[1991553628] 'compare' (duration: 44.522899ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:00.259985Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"121.387219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-controller-3\" limit:1 ","response":"range_response_count:1 size:4956"} {"level":"info","ts":"2026-07-06T14:30:00.260064Z","caller":"traceutil/trace.go:172","msg":"trace[1019266256] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-controller-3; range_end:; response_count:1; response_revision:4743; }","duration":"121.47298ms","start":"2026-07-06T14:30:00.138572Z","end":"2026-07-06T14:30:00.260045Z","steps":["trace[1019266256] 'agreement among raft nodes before linearized reading' (duration: 120.245769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:00.669225Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"102.467354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T14:30:00.697369Z","caller":"traceutil/trace.go:172","msg":"trace[783420510] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"429.040152ms","start":"2026-07-06T14:30:00.268307Z","end":"2026-07-06T14:30:00.697347Z","steps":["trace[783420510] 'process raft request' (duration: 428.938311ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:00.697383Z","caller":"traceutil/trace.go:172","msg":"trace[954883425] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"430.356444ms","start":"2026-07-06T14:30:00.267003Z","end":"2026-07-06T14:30:00.697360Z","steps":["trace[954883425] 'process raft request' (duration: 430.145332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:00.697910Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:00.268292Z","time spent":"429.352514ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4941,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T14:30:00.697972Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:00.266979Z","time spent":"430.869988ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":940,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T14:30:01.102461Z","caller":"traceutil/trace.go:172","msg":"trace[436373042] linearizableReadLoop","detail":"{readStateIndex:5468; appliedIndex:5468; }","duration":"399.533037ms","start":"2026-07-06T14:30:00.702903Z","end":"2026-07-06T14:30:01.102436Z","steps":["trace[436373042] 'read index received' (duration: 399.525687ms)","trace[436373042] 'applied index is now lower than readState.Index' (duration: 6.05µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:01.102683Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"399.747809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-controller-3\" limit:1 ","response":"range_response_count:1 size:4956"} {"level":"info","ts":"2026-07-06T14:30:01.102736Z","caller":"traceutil/trace.go:172","msg":"trace[1718648960] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-controller-3; range_end:; response_count:1; response_revision:4747; }","duration":"399.8252ms","start":"2026-07-06T14:30:00.702896Z","end":"2026-07-06T14:30:01.102721Z","steps":["trace[1718648960] 'agreement among raft nodes before linearized reading' (duration: 399.668969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:01.102786Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:00.702874Z","time spent":"399.89637ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":4979,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-controller-3\" limit:1 "} {"level":"info","ts":"2026-07-06T14:30:01.102782Z","caller":"traceutil/trace.go:172","msg":"trace[1814755398] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"246.762514ms","start":"2026-07-06T14:30:00.855996Z","end":"2026-07-06T14:30:01.102758Z","steps":["trace[1814755398] 'process raft request' (duration: 246.694614ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:01.102822Z","caller":"traceutil/trace.go:172","msg":"trace[386013219] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"399.73444ms","start":"2026-07-06T14:30:00.703067Z","end":"2026-07-06T14:30:01.102801Z","steps":["trace[386013219] 'process raft request' (duration: 399.475447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:01.102930Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:00.703052Z","time spent":"399.81145ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":862,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T14:30:01.556692Z","caller":"traceutil/trace.go:172","msg":"trace[1039763536] linearizableReadLoop","detail":"{readStateIndex:5470; appliedIndex:5470; }","duration":"454.128746ms","start":"2026-07-06T14:30:01.102542Z","end":"2026-07-06T14:30:01.556671Z","steps":["trace[1039763536] 'read index received' (duration: 454.122576ms)","trace[1039763536] 'applied index is now lower than readState.Index' (duration: 4.92µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:01.556932Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"584.694007ms","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-07-06T14:30:01.556980Z","caller":"traceutil/trace.go:172","msg":"trace[1354106319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4750; }","duration":"584.757278ms","start":"2026-07-06T14:30:00.972212Z","end":"2026-07-06T14:30:01.556969Z","steps":["trace[1354106319] 'agreement among raft nodes before linearized reading' (duration: 584.555176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:01.557006Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:00.972186Z","time spent":"584.816088ms","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":507,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 "} {"level":"info","ts":"2026-07-06T14:30:01.557202Z","caller":"traceutil/trace.go:172","msg":"trace[196615783] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"445.086326ms","start":"2026-07-06T14:30:01.112088Z","end":"2026-07-06T14:30:01.557174Z","steps":["trace[196615783] 'process raft request' (duration: 444.741073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:01.557325Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:01.112077Z","time spent":"445.180297ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4749,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T14:30:01.557218Z","caller":"traceutil/trace.go:172","msg":"trace[1741138239] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"449.809388ms","start":"2026-07-06T14:30:01.107397Z","end":"2026-07-06T14:30:01.557206Z","steps":["trace[1741138239] 'process raft request' (duration: 449.336864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:01.557420Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:01.107370Z","time spent":"450.00473ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T14:30:02.155688Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5651066694602631553,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T14:30:02.622439Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"559.965839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-07-06T14:30:02.656498Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5651066694602631553,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T14:30:02.695681Z","caller":"traceutil/trace.go:172","msg":"trace[1734754295] linearizableReadLoop","detail":"{readStateIndex:5476; appliedIndex:5476; }","duration":"1.040882107s","start":"2026-07-06T14:30:01.654772Z","end":"2026-07-06T14:30:02.695654Z","steps":["trace[1734754295] 'read index received' (duration: 1.040870677s)","trace[1734754295] 'applied index is now lower than readState.Index' (duration: 10.09µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:02.695994Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.041186849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-06T14:30:02.696067Z","caller":"traceutil/trace.go:172","msg":"trace[1854609326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4755; }","duration":"1.04129284s","start":"2026-07-06T14:30:01.654756Z","end":"2026-07-06T14:30:02.696049Z","steps":["trace[1854609326] 'agreement among raft nodes before linearized reading' (duration: 1.041054148s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:02.696106Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:01.654732Z","time spent":"1.041365381s","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"info","ts":"2026-07-06T14:30:02.696174Z","caller":"traceutil/trace.go:172","msg":"trace[316088800] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"1.136094008s","start":"2026-07-06T14:30:01.560069Z","end":"2026-07-06T14:30:02.696163Z","steps":["trace[316088800] 'process raft request' (duration: 1.135724155s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:02.696245Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:01.560048Z","time spent":"1.136157919s","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T14:30:03.196574Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5651066694602631555,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T14:30:03.223241Z","caller":"traceutil/trace.go:172","msg":"trace[893225425] linearizableReadLoop","detail":"{readStateIndex:5479; appliedIndex:5479; }","duration":"527.386065ms","start":"2026-07-06T14:30:02.695766Z","end":"2026-07-06T14:30:03.223152Z","steps":["trace[893225425] 'read index received' (duration: 527.374505ms)","trace[893225425] 'applied index is now lower than readState.Index' (duration: 10.08µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:03.223563Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.141796542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.166\" limit:1 ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-06T14:30:03.223633Z","caller":"traceutil/trace.go:172","msg":"trace[447050677] range","detail":"{range_begin:/registry/masterleases/199.19.213.166; range_end:; response_count:1; response_revision:4758; }","duration":"1.141900243s","start":"2026-07-06T14:30:02.081714Z","end":"2026-07-06T14:30:03.223615Z","steps":["trace[447050677] 'agreement among raft nodes before linearized reading' (duration: 1.141608751s)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:03.223632Z","caller":"traceutil/trace.go:172","msg":"trace[22751165] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"523.005545ms","start":"2026-07-06T14:30:02.700610Z","end":"2026-07-06T14:30:03.223615Z","steps":["trace[22751165] 'process raft request' (duration: 522.911314ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:03.223669Z","caller":"traceutil/trace.go:172","msg":"trace[900770231] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"523.946833ms","start":"2026-07-06T14:30:02.699696Z","end":"2026-07-06T14:30:03.223642Z","steps":["trace[900770231] 'process raft request' (duration: 523.682571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:03.223676Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:02.081687Z","time spent":"1.141979624s","remote":"127.0.0.1:45308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.166\" limit:1 "} {"level":"warn","ts":"2026-07-06T14:30:03.226013Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:02.699666Z","time spent":"524.066724ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T14:30:03.226078Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"884.565796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:442"} {"level":"info","ts":"2026-07-06T14:30:03.226217Z","caller":"traceutil/trace.go:172","msg":"trace[693275738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4758; }","duration":"887.290631ms","start":"2026-07-06T14:30:02.338897Z","end":"2026-07-06T14:30:03.226188Z","steps":["trace[693275738] 'agreement among raft nodes before linearized reading' (duration: 884.369034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:03.226196Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:02.700566Z","time spent":"523.134187ms","remote":"127.0.0.1:45696","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-07-06T14:30:03.226292Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:02.338872Z","time spent":"887.398303ms","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-07-06T14:30:03.385376Z","caller":"traceutil/trace.go:172","msg":"trace[1130000777] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"153.799496ms","start":"2026-07-06T14:30:03.231551Z","end":"2026-07-06T14:30:03.385350Z","steps":["trace[1130000777] 'process raft request' (duration: 153.674165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:03.426471Z","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":5651066694602631558,"received-request-id":5651066694602631555} {"level":"warn","ts":"2026-07-06T14:30:03.927537Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5651066694602631558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T14:30:03.961795Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"359.784959ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-07-06T14:30:04.237251Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"113.586655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-07-06T14:30:04.428304Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5651066694602631558,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T14:30:04.585120Z","caller":"traceutil/trace.go:172","msg":"trace[484110524] linearizableReadLoop","detail":"{readStateIndex:5528; appliedIndex:5528; }","duration":"1.158629157s","start":"2026-07-06T14:30:03.426456Z","end":"2026-07-06T14:30:04.585085Z","steps":["trace[484110524] 'read index received' (duration: 1.158616846s)","trace[484110524] 'applied index is now lower than readState.Index' (duration: 9.921µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:30:04.674195Z","caller":"traceutil/trace.go:172","msg":"trace[265276173] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"1.244742228s","start":"2026-07-06T14:30:03.429417Z","end":"2026-07-06T14:30:04.674159Z","steps":["trace[265276173] 'process raft request' (duration: 1.155911822s)","trace[265276173] 'compare' (duration: 88.613334ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:04.674204Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.247388943s","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-07-06T14:30:04.674317Z","caller":"traceutil/trace.go:172","msg":"trace[1096437430] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4805; }","duration":"1.247840248s","start":"2026-07-06T14:30:03.426450Z","end":"2026-07-06T14:30:04.674291Z","steps":["trace[1096437430] 'agreement among raft nodes before linearized reading' (duration: 1.158787108s)","trace[1096437430] 'range keys from in-memory index tree' (duration: 88.367683ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:04.674344Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:03.429393Z","time spent":"1.24487636s","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T14:30:04.674371Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:03.426428Z","time spent":"1.247929518s","remote":"127.0.0.1:45552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":484,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" limit:1 "} {"level":"info","ts":"2026-07-06T14:30:04.674411Z","caller":"traceutil/trace.go:172","msg":"trace[1879536271] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"1.155241856s","start":"2026-07-06T14:30:03.519150Z","end":"2026-07-06T14:30:04.674392Z","steps":["trace[1879536271] 'process raft request' (duration: 1.154936323s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:04.674504Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:03.519121Z","time spent":"1.155330927s","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T14:30:04.865738Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"191.420371ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-06T14:30:04.910123Z","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":5651066694602631566,"received-request-id":5651066694602631558} {"level":"warn","ts":"2026-07-06T14:30:05.096673Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5651066694602631566,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T14:30:05.291177Z","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":5651066694602631566,"received-request-id":5651066694602631558} {"level":"info","ts":"2026-07-06T14:30:05.299420Z","caller":"traceutil/trace.go:172","msg":"trace[505621600] linearizableReadLoop","detail":"{readStateIndex:5541; appliedIndex:5541; }","duration":"714.173099ms","start":"2026-07-06T14:30:04.585218Z","end":"2026-07-06T14:30:05.299391Z","steps":["trace[505621600] 'read index received' (duration: 714.163019ms)","trace[505621600] 'applied index is now lower than readState.Index' (duration: 8.23µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:05.299635Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"934.329576ms","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-07-06T14:30:05.299756Z","caller":"traceutil/trace.go:172","msg":"trace[824095387] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4817; }","duration":"934.427687ms","start":"2026-07-06T14:30:04.365272Z","end":"2026-07-06T14:30:05.299700Z","steps":["trace[824095387] 'agreement among raft nodes before linearized reading' (duration: 934.173514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:05.299746Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.539285726s","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":"warn","ts":"2026-07-06T14:30:05.299810Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:04.365241Z","time spent":"934.551678ms","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":507,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 "} {"level":"info","ts":"2026-07-06T14:30:05.299783Z","caller":"traceutil/trace.go:172","msg":"trace[1915531829] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"621.714278ms","start":"2026-07-06T14:30:04.678038Z","end":"2026-07-06T14:30:05.299752Z","steps":["trace[1915531829] 'process raft request' (duration: 621.389535ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:05.299847Z","caller":"traceutil/trace.go:172","msg":"trace[311924124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4817; }","duration":"1.539409527s","start":"2026-07-06T14:30:03.760412Z","end":"2026-07-06T14:30:05.299821Z","steps":["trace[311924124] 'agreement among raft nodes before linearized reading' (duration: 1.539079894s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:05.299909Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:03.760391Z","time spent":"1.539504567s","remote":"127.0.0.1:45696","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-07-06T14:30:05.299922Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:04.678020Z","time spent":"621.844909ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T14:30:05.303548Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"715.785964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-m4hzh\" limit:1 ","response":"range_response_count:1 size:20089"} {"level":"info","ts":"2026-07-06T14:30:05.303587Z","caller":"traceutil/trace.go:172","msg":"trace[671811885] range","detail":"{range_begin:/registry/pods/kube-system/cilium-m4hzh; range_end:; response_count:1; response_revision:4818; }","duration":"715.829634ms","start":"2026-07-06T14:30:04.587749Z","end":"2026-07-06T14:30:05.303579Z","steps":["trace[671811885] 'agreement among raft nodes before linearized reading' (duration: 715.720413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:05.303607Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:04.587715Z","time spent":"715.886855ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":20112,"request content":"key:\"/registry/pods/kube-system/cilium-m4hzh\" limit:1 "} {"level":"warn","ts":"2026-07-06T14:30:05.303645Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"626.571383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:3 size:389"} {"level":"warn","ts":"2026-07-06T14:30:05.303670Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"437.826866ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:05.303693Z","caller":"traceutil/trace.go:172","msg":"trace[29946572] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:3; response_revision:4818; }","duration":"626.621313ms","start":"2026-07-06T14:30:04.677059Z","end":"2026-07-06T14:30:05.303680Z","steps":["trace[29946572] 'agreement among raft nodes before linearized reading' (duration: 626.458302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:05.303724Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:04.677041Z","time spent":"626.675044ms","remote":"127.0.0.1:45308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":412,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-07-06T14:30:05.303833Z","caller":"traceutil/trace.go:172","msg":"trace[1545667261] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4818; }","duration":"437.999427ms","start":"2026-07-06T14:30:04.865707Z","end":"2026-07-06T14:30:05.303706Z","steps":["trace[1545667261] 'agreement among raft nodes before linearized reading' (duration: 437.793446ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:05.721620Z","caller":"traceutil/trace.go:172","msg":"trace[790719084] linearizableReadLoop","detail":"{readStateIndex:5544; appliedIndex:5544; }","duration":"369.433764ms","start":"2026-07-06T14:30:05.352161Z","end":"2026-07-06T14:30:05.721595Z","steps":["trace[790719084] 'read index received' (duration: 369.426734ms)","trace[790719084] 'applied index is now lower than readState.Index' (duration: 5.81µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:05.721781Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"412.417695ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:05.721855Z","caller":"traceutil/trace.go:172","msg":"trace[1892787031] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4820; }","duration":"412.513886ms","start":"2026-07-06T14:30:05.309332Z","end":"2026-07-06T14:30:05.721846Z","steps":["trace[1892787031] 'agreement among raft nodes before linearized reading' (duration: 412.384475ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:05.722692Z","caller":"traceutil/trace.go:172","msg":"trace[1841881614] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"400.269102ms","start":"2026-07-06T14:30:05.322363Z","end":"2026-07-06T14:30:05.722632Z","steps":["trace[1841881614] 'process raft request' (duration: 399.528305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:05.723671Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:05.322351Z","time spent":"400.384643ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19886,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T14:30:06.058376Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"335.99026ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-06T14:30:06.229763Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"171.265377ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-06T14:30:06.230114Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5651066694602631575,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T14:30:06.389613Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"159.694308ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-06T14:30:06.738314Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"348.610041ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-06T14:30:06.881478Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"142.842169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T14:30:06.881710Z","caller":"traceutil/trace.go:172","msg":"trace[851550948] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"1.525469789s","start":"2026-07-06T14:30:05.356206Z","end":"2026-07-06T14:30:06.881676Z","steps":["trace[851550948] 'process raft request' (duration: 1.382359137s)","trace[851550948] 'compare' (duration: 142.685217ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:06.881913Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:05.356177Z","time spent":"1.52562172s","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":747,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T14:30:06.881760Z","caller":"traceutil/trace.go:172","msg":"trace[1156749199] linearizableReadLoop","detail":"{readStateIndex:5555; appliedIndex:5549; }","duration":"1.152095128s","start":"2026-07-06T14:30:05.729649Z","end":"2026-07-06T14:30:06.881744Z","steps":["trace[1156749199] 'read index received' (duration: 591.14887ms)","trace[1156749199] 'applied index is now lower than readState.Index' (duration: 560.945388ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:06.882233Z","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":5651066694602631576,"received-request-id":5651066694602631575} {"level":"warn","ts":"2026-07-06T14:30:06.881948Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.152269209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-controller-3\" limit:1 ","response":"range_response_count:1 size:7368"} {"level":"info","ts":"2026-07-06T14:30:06.882299Z","caller":"traceutil/trace.go:172","msg":"trace[1884962151] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-controller-3; range_end:; response_count:1; response_revision:4829; }","duration":"1.152654033s","start":"2026-07-06T14:30:05.729637Z","end":"2026-07-06T14:30:06.882291Z","steps":["trace[1884962151] 'agreement among raft nodes before linearized reading' (duration: 1.152145588s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:06.882407Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:05.729611Z","time spent":"1.152709513s","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":7391,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-controller-3\" limit:1 "} {"level":"info","ts":"2026-07-06T14:30:07.201083Z","caller":"traceutil/trace.go:172","msg":"trace[1694297512] linearizableReadLoop","detail":"{readStateIndex:5560; appliedIndex:5560; }","duration":"318.853785ms","start":"2026-07-06T14:30:06.882202Z","end":"2026-07-06T14:30:07.201056Z","steps":["trace[1694297512] 'read index received' (duration: 318.846335ms)","trace[1694297512] 'applied index is now lower than readState.Index' (duration: 6.25µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:07.201247Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"811.623998ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:07.201342Z","caller":"traceutil/trace.go:172","msg":"trace[274402113] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4831; }","duration":"811.738629ms","start":"2026-07-06T14:30:06.389584Z","end":"2026-07-06T14:30:07.201322Z","steps":["trace[274402113] 'agreement among raft nodes before linearized reading' (duration: 811.560248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:07.442217Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"133.247145ms","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-07-06T14:30:07.442330Z","caller":"traceutil/trace.go:172","msg":"trace[1543842523] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4831; }","duration":"133.377667ms","start":"2026-07-06T14:30:07.308931Z","end":"2026-07-06T14:30:07.442308Z","steps":["trace[1543842523] 'range keys from in-memory index tree' (duration: 133.180404ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:07.442480Z","caller":"traceutil/trace.go:172","msg":"trace[894350619] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"552.790503ms","start":"2026-07-06T14:30:06.889673Z","end":"2026-07-06T14:30:07.442463Z","steps":["trace[894350619] 'process raft request' (duration: 530.247237ms)","trace[894350619] 'compare' (duration: 22.288444ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:30:07.442543Z","caller":"traceutil/trace.go:172","msg":"trace[485720747] linearizableReadLoop","detail":"{readStateIndex:5561; appliedIndex:5560; }","duration":"241.357158ms","start":"2026-07-06T14:30:07.201162Z","end":"2026-07-06T14:30:07.442519Z","steps":["trace[485720747] 'read index received' (duration: 218.694211ms)","trace[485720747] 'applied index is now lower than readState.Index' (duration: 22.661727ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:07.442591Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:06.889644Z","time spent":"552.885734ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":717,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T14:30:07.442682Z","caller":"traceutil/trace.go:172","msg":"trace[70133909] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"550.899075ms","start":"2026-07-06T14:30:06.891734Z","end":"2026-07-06T14:30:07.442633Z","steps":["trace[70133909] 'process raft request' (duration: 550.653642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:07.442928Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:06.891720Z","time spent":"551.075976ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7143,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T14:30:07.442918Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"254.413033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:442"} {"level":"info","ts":"2026-07-06T14:30:07.443010Z","caller":"traceutil/trace.go:172","msg":"trace[359398196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4833; }","duration":"254.530134ms","start":"2026-07-06T14:30:07.188460Z","end":"2026-07-06T14:30:07.442990Z","steps":["trace[359398196] 'agreement among raft nodes before linearized reading' (duration: 254.213221ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:07.660781Z","caller":"traceutil/trace.go:172","msg":"trace[371882177] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"204.423085ms","start":"2026-07-06T14:30:07.456315Z","end":"2026-07-06T14:30:07.660738Z","steps":["trace[371882177] 'process raft request' (duration: 174.228086ms)","trace[371882177] 'compare' (duration: 29.913766ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:30:07.660824Z","caller":"traceutil/trace.go:172","msg":"trace[818721896] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"167.134838ms","start":"2026-07-06T14:30:07.493657Z","end":"2026-07-06T14:30:07.660792Z","steps":["trace[818721896] 'process raft request' (duration: 166.936786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:07.807796Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"146.980095ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-06T14:30:07.808045Z","caller":"traceutil/trace.go:172","msg":"trace[173982677] linearizableReadLoop","detail":"{readStateIndex:5568; appliedIndex:5567; }","duration":"179.803729ms","start":"2026-07-06T14:30:07.628213Z","end":"2026-07-06T14:30:07.808016Z","steps":["trace[173982677] 'read index received' (duration: 142.445702ms)","trace[173982677] 'applied index is now lower than readState.Index' (duration: 37.355707ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:30:07.808128Z","caller":"traceutil/trace.go:172","msg":"trace[727988010] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"141.913726ms","start":"2026-07-06T14:30:07.666197Z","end":"2026-07-06T14:30:07.808111Z","steps":["trace[727988010] 'process raft request' (duration: 141.761695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:07.808226Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"179.98333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:07.808339Z","caller":"traceutil/trace.go:172","msg":"trace[1682566434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4839; }","duration":"180.083761ms","start":"2026-07-06T14:30:07.628207Z","end":"2026-07-06T14:30:07.808291Z","steps":["trace[1682566434] 'agreement among raft nodes before linearized reading' (duration: 179.93298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:08.260451Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"105.106657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-07-06T14:30:08.308853Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5651066694602631588,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T14:30:08.574786Z","caller":"traceutil/trace.go:172","msg":"trace[1546100384] linearizableReadLoop","detail":"{readStateIndex:5574; appliedIndex:5574; }","duration":"766.631289ms","start":"2026-07-06T14:30:07.808112Z","end":"2026-07-06T14:30:08.574743Z","steps":["trace[1546100384] 'read index received' (duration: 766.615139ms)","trace[1546100384] 'applied index is now lower than readState.Index' (duration: 13.76µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:08.654625Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"898.123704ms","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-07-06T14:30:08.654659Z","caller":"traceutil/trace.go:172","msg":"trace[1790248707] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"843.077549ms","start":"2026-07-06T14:30:07.811546Z","end":"2026-07-06T14:30:08.654623Z","steps":["trace[1790248707] 'process raft request' (duration: 763.379148ms)","trace[1790248707] 'compare' (duration: 79.489239ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:08.654681Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"989.734771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-66bc5c9577-c4twq\" limit:1 ","response":"range_response_count:1 size:5719"} {"level":"info","ts":"2026-07-06T14:30:08.654734Z","caller":"traceutil/trace.go:172","msg":"trace[768697201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4844; }","duration":"898.259536ms","start":"2026-07-06T14:30:07.756452Z","end":"2026-07-06T14:30:08.654711Z","steps":["trace[768697201] 'agreement among raft nodes before linearized reading' (duration: 818.335343ms)","trace[768697201] 'range keys from in-memory index tree' (duration: 79.63692ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:08.654783Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:07.756407Z","time spent":"898.366666ms","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":507,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 "} {"level":"info","ts":"2026-07-06T14:30:08.654778Z","caller":"traceutil/trace.go:172","msg":"trace[1879451445] range","detail":"{range_begin:/registry/pods/kube-system/coredns-66bc5c9577-c4twq; range_end:; response_count:1; response_revision:4844; }","duration":"989.853122ms","start":"2026-07-06T14:30:07.664903Z","end":"2026-07-06T14:30:08.654756Z","steps":["trace[1879451445] 'agreement among raft nodes before linearized reading' (duration: 909.966339ms)","trace[1879451445] 'range keys from in-memory index tree' (duration: 79.59116ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:08.654818Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:07.811509Z","time spent":"843.24546ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T14:30:08.654831Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:07.664882Z","time spent":"989.938343ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":5742,"request content":"key:\"/registry/pods/kube-system/coredns-66bc5c9577-c4twq\" limit:1 "} {"level":"warn","ts":"2026-07-06T14:30:09.009558Z","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":5651066694602631590,"received-request-id":5651066694602631588} {"level":"warn","ts":"2026-07-06T14:30:09.083404Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5651066694602631590,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T14:30:09.368357Z","caller":"traceutil/trace.go:172","msg":"trace[1687718919] linearizableReadLoop","detail":"{readStateIndex:5580; appliedIndex:5580; }","duration":"793.442303ms","start":"2026-07-06T14:30:08.574881Z","end":"2026-07-06T14:30:09.368323Z","steps":["trace[1687718919] 'read index received' (duration: 793.434313ms)","trace[1687718919] 'applied index is now lower than readState.Index' (duration: 6.8µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:09.380953Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.074245724s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:09.381014Z","caller":"traceutil/trace.go:172","msg":"trace[1093373209] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4850; }","duration":"1.074330545s","start":"2026-07-06T14:30:08.306670Z","end":"2026-07-06T14:30:09.381001Z","steps":["trace[1093373209] 'agreement among raft nodes before linearized reading' (duration: 1.06167463s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:09.381141Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.424977824s","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-07-06T14:30:09.381232Z","caller":"traceutil/trace.go:172","msg":"trace[425772905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4850; }","duration":"1.425087315s","start":"2026-07-06T14:30:07.956121Z","end":"2026-07-06T14:30:09.381209Z","steps":["trace[425772905] 'agreement among raft nodes before linearized reading' (duration: 1.412238878s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:09.381321Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.564351247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T14:30:09.381330Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:07.956099Z","time spent":"1.425216676s","remote":"127.0.0.1:45696","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":"info","ts":"2026-07-06T14:30:09.381361Z","caller":"traceutil/trace.go:172","msg":"trace[948157357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4850; }","duration":"1.564403107s","start":"2026-07-06T14:30:07.816946Z","end":"2026-07-06T14:30:09.381349Z","steps":["trace[948157357] 'agreement among raft nodes before linearized reading' (duration: 1.55148931s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:09.381401Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:07.816925Z","time spent":"1.564468457s","remote":"127.0.0.1:45256","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-07-06T14:30:09.381857Z","caller":"traceutil/trace.go:172","msg":"trace[1048060587] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"724.912832ms","start":"2026-07-06T14:30:08.656928Z","end":"2026-07-06T14:30:09.381841Z","steps":["trace[1048060587] 'process raft request' (duration: 711.5435ms)","trace[1048060587] 'compare' (duration: 12.424003ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:09.381955Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:08.656826Z","time spent":"725.066783ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5704,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T14:30:09.382115Z","caller":"traceutil/trace.go:172","msg":"trace[492428535] transaction","detail":"{read_only:false; response_revision:4853; number_of_response:1; }","duration":"721.942513ms","start":"2026-07-06T14:30:08.660139Z","end":"2026-07-06T14:30:09.382082Z","steps":["trace[492428535] 'process raft request' (duration: 721.853952ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:09.382139Z","caller":"traceutil/trace.go:172","msg":"trace[1596189678] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"722.187925ms","start":"2026-07-06T14:30:08.659933Z","end":"2026-07-06T14:30:09.382121Z","steps":["trace[1596189678] 'process raft request' (duration: 721.917593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:09.382870Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:08.660094Z","time spent":"722.128675ms","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":468,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T14:30:09.383343Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:08.659908Z","time spent":"722.63799ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":842,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T14:30:09.956169Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5651066694602631601,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T14:30:10.270200Z","caller":"traceutil/trace.go:172","msg":"trace[2077697505] linearizableReadLoop","detail":"{readStateIndex:5594; appliedIndex:5594; }","duration":"814.678773ms","start":"2026-07-06T14:30:09.455488Z","end":"2026-07-06T14:30:10.270167Z","steps":["trace[2077697505] 'read index received' (duration: 814.668893ms)","trace[2077697505] 'applied index is now lower than readState.Index' (duration: 8.17µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:10.270358Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"879.779191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:10.270448Z","caller":"traceutil/trace.go:172","msg":"trace[1462929643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4862; }","duration":"879.877002ms","start":"2026-07-06T14:30:09.390550Z","end":"2026-07-06T14:30:10.270427Z","steps":["trace[1462929643] 'agreement among raft nodes before linearized reading' (duration: 879.738021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:10.270491Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:09.390538Z","time spent":"879.945793ms","remote":"127.0.0.1:45282","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-07-06T14:30:10.270574Z","caller":"traceutil/trace.go:172","msg":"trace[1982172860] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"769.753993ms","start":"2026-07-06T14:30:09.500784Z","end":"2026-07-06T14:30:10.270538Z","steps":["trace[1982172860] 'process raft request' (duration: 769.706653ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:10.270625Z","caller":"traceutil/trace.go:172","msg":"trace[1595074817] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4863; }","duration":"810.938136ms","start":"2026-07-06T14:30:09.459641Z","end":"2026-07-06T14:30:10.270579Z","steps":["trace[1595074817] 'process raft request' (duration: 810.650134ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:10.270775Z","caller":"traceutil/trace.go:172","msg":"trace[1900236131] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"808.860446ms","start":"2026-07-06T14:30:09.461898Z","end":"2026-07-06T14:30:10.270758Z","steps":["trace[1900236131] 'process raft request' (duration: 808.543393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:10.270849Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:09.461885Z","time spent":"808.927577ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":747,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T14:30:10.270872Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:09.459617Z","time spent":"811.095578ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":55,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-07-06T14:30:10.271575Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:09.500739Z","time spent":"769.919855ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":846,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T14:30:10.770883Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5651066694602631605,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T14:30:10.778913Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"417.65292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T14:30:10.812213Z","caller":"traceutil/trace.go:172","msg":"trace[41261601] linearizableReadLoop","detail":"{readStateIndex:5598; appliedIndex:5598; }","duration":"541.887843ms","start":"2026-07-06T14:30:10.270285Z","end":"2026-07-06T14:30:10.812173Z","steps":["trace[41261601] 'read index received' (duration: 541.856233ms)","trace[41261601] 'applied index is now lower than readState.Index' (duration: 17.2µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:10.812255Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.283729693s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:443"} {"level":"info","ts":"2026-07-06T14:30:10.812319Z","caller":"traceutil/trace.go:172","msg":"trace[624753965] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"536.591471ms","start":"2026-07-06T14:30:10.275712Z","end":"2026-07-06T14:30:10.812304Z","steps":["trace[624753965] 'process raft request' (duration: 536.51668ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:10.812349Z","caller":"traceutil/trace.go:172","msg":"trace[1099300081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4866; }","duration":"1.283841234s","start":"2026-07-06T14:30:09.528486Z","end":"2026-07-06T14:30:10.812327Z","steps":["trace[1099300081] 'agreement among raft nodes before linearized reading' (duration: 1.283678323s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:10.812372Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:10.275692Z","time spent":"536.662122ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":846,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T14:30:10.812394Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:09.528431Z","time spent":"1.283953046s","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":466,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-07-06T14:30:10.812465Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.314728836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-06T14:30:10.812543Z","caller":"traceutil/trace.go:172","msg":"trace[789963764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4867; }","duration":"1.314825187s","start":"2026-07-06T14:30:09.497703Z","end":"2026-07-06T14:30:10.812528Z","steps":["trace[789963764] 'agreement among raft nodes before linearized reading' (duration: 1.314558265s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:10.812582Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:09.497574Z","time spent":"1.31500093s","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"info","ts":"2026-07-06T14:30:10.812623Z","caller":"traceutil/trace.go:172","msg":"trace[415811952] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"536.739562ms","start":"2026-07-06T14:30:10.275870Z","end":"2026-07-06T14:30:10.812609Z","steps":["trace[415811952] 'process raft request' (duration: 536.403789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:10.812726Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:10.275853Z","time spent":"536.806002ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":793,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T14:30:10.952946Z","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":5651066694602631608,"received-request-id":5651066694602631605} {"level":"warn","ts":"2026-07-06T14:30:11.009457Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"131.341574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T14:30:11.109574Z","caller":"traceutil/trace.go:172","msg":"trace[742514360] linearizableReadLoop","detail":"{readStateIndex:5602; appliedIndex:5602; }","duration":"297.21114ms","start":"2026-07-06T14:30:10.812314Z","end":"2026-07-06T14:30:11.109525Z","steps":["trace[742514360] 'read index received' (duration: 297.196289ms)","trace[742514360] 'applied index is now lower than readState.Index' (duration: 13.301µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:11.157349Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"885.064184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:11.157484Z","caller":"traceutil/trace.go:172","msg":"trace[1584738395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4870; }","duration":"885.236055ms","start":"2026-07-06T14:30:10.272217Z","end":"2026-07-06T14:30:11.157453Z","steps":["trace[1584738395] 'agreement among raft nodes before linearized reading' (duration: 837.451576ms)","trace[1584738395] 'range keys from in-memory index tree' (duration: 47.572177ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:30:11.157519Z","caller":"traceutil/trace.go:172","msg":"trace[1287517967] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"339.942919ms","start":"2026-07-06T14:30:10.817548Z","end":"2026-07-06T14:30:11.157491Z","steps":["trace[1287517967] 'process raft request' (duration: 292.23411ms)","trace[1287517967] 'compare' (duration: 47.412316ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:11.157586Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:10.272195Z","time spent":"885.367706ms","remote":"127.0.0.1:45256","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-07-06T14:30:11.157571Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"850.702463ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T14:30:11.157661Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:10.817526Z","time spent":"340.07361ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T14:30:11.157667Z","caller":"traceutil/trace.go:172","msg":"trace[114633560] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4870; }","duration":"850.820354ms","start":"2026-07-06T14:30:10.306822Z","end":"2026-07-06T14:30:11.157642Z","steps":["trace[114633560] 'agreement among raft nodes before linearized reading' (duration: 802.801953ms)","trace[114633560] 'range keys from in-memory index tree' (duration: 47.85287ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:11.157747Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"881.908543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-controller-3\" limit:1 ","response":"range_response_count:1 size:5967"} {"level":"info","ts":"2026-07-06T14:30:11.157877Z","caller":"traceutil/trace.go:172","msg":"trace[777289644] range","detail":"{range_begin:/registry/pods/kube-system/etcd-controller-3; range_end:; response_count:1; response_revision:4870; }","duration":"882.066774ms","start":"2026-07-06T14:30:10.275784Z","end":"2026-07-06T14:30:11.157851Z","steps":["trace[777289644] 'agreement among raft nodes before linearized reading' (duration: 833.871921ms)","trace[777289644] 'range keys from in-memory index tree' (duration: 47.84529ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:11.157933Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:10.275778Z","time spent":"882.142815ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":5990,"request content":"key:\"/registry/pods/kube-system/etcd-controller-3\" limit:1 "} {"level":"info","ts":"2026-07-06T14:30:11.158149Z","caller":"traceutil/trace.go:172","msg":"trace[970641476] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"339.169731ms","start":"2026-07-06T14:30:10.818962Z","end":"2026-07-06T14:30:11.158132Z","steps":["trace[970641476] 'process raft request' (duration: 338.476994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:11.158269Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:10.818953Z","time spent":"339.237022ms","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T14:30:11.300840Z","caller":"traceutil/trace.go:172","msg":"trace[449650443] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"131.621821ms","start":"2026-07-06T14:30:11.169061Z","end":"2026-07-06T14:30:11.300682Z","steps":["trace[449650443] 'process raft request' (duration: 106.062634ms)","trace[449650443] 'compare' (duration: 25.427325ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:30:11.415583Z","caller":"traceutil/trace.go:172","msg":"trace[1803176639] linearizableReadLoop","detail":"{readStateIndex:5605; appliedIndex:5605; }","duration":"244.141329ms","start":"2026-07-06T14:30:11.171416Z","end":"2026-07-06T14:30:11.415557Z","steps":["trace[1803176639] 'read index received' (duration: 244.132069ms)","trace[1803176639] 'applied index is now lower than readState.Index' (duration: 7.86µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:30:11.480786Z","caller":"traceutil/trace.go:172","msg":"trace[1498978681] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"309.666346ms","start":"2026-07-06T14:30:11.171089Z","end":"2026-07-06T14:30:11.480756Z","steps":["trace[1498978681] 'process raft request' (duration: 244.527843ms)","trace[1498978681] 'compare' (duration: 64.961991ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:11.481028Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:11.171064Z","time spent":"309.874318ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5780,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T14:30:11.481219Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"309.225622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:11.481458Z","caller":"traceutil/trace.go:172","msg":"trace[1483598455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4873; }","duration":"309.961479ms","start":"2026-07-06T14:30:11.171409Z","end":"2026-07-06T14:30:11.481370Z","steps":["trace[1483598455] 'agreement among raft nodes before linearized reading' (duration: 244.277531ms)","trace[1483598455] 'range keys from in-memory index tree' (duration: 64.91662ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:11.481875Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:11.171400Z","time spent":"310.166692ms","remote":"127.0.0.1:45282","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-07-06T14:30:11.484278Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"177.448791ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:11.484342Z","caller":"traceutil/trace.go:172","msg":"trace[504181702] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4874; }","duration":"177.514001ms","start":"2026-07-06T14:30:11.306812Z","end":"2026-07-06T14:30:11.484326Z","steps":["trace[504181702] 'agreement among raft nodes before linearized reading' (duration: 177.417149ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:11.484720Z","caller":"traceutil/trace.go:172","msg":"trace[1435863977] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"179.039626ms","start":"2026-07-06T14:30:11.305501Z","end":"2026-07-06T14:30:11.484541Z","steps":["trace[1435863977] 'process raft request' (duration: 178.868145ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:11.728601Z","caller":"traceutil/trace.go:172","msg":"trace[191458215] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"207.883696ms","start":"2026-07-06T14:30:11.520691Z","end":"2026-07-06T14:30:11.728575Z","steps":["trace[191458215] 'process raft request' (duration: 127.447898ms)","trace[191458215] 'compare' (duration: 80.303906ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:12.045647Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"185.469475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T14:30:12.134379Z","caller":"traceutil/trace.go:172","msg":"trace[40673577] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"323.45775ms","start":"2026-07-06T14:30:11.810779Z","end":"2026-07-06T14:30:12.134237Z","steps":["trace[40673577] 'process raft request' (duration: 322.919064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:12.134530Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:11.810727Z","time spent":"323.734644ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":732,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T14:30:12.190950Z","caller":"traceutil/trace.go:172","msg":"trace[850589752] linearizableReadLoop","detail":"{readStateIndex:5619; appliedIndex:5619; }","duration":"108.604532ms","start":"2026-07-06T14:30:12.082318Z","end":"2026-07-06T14:30:12.190922Z","steps":["trace[850589752] 'read index received' (duration: 108.596432ms)","trace[850589752] 'applied index is now lower than readState.Index' (duration: 6.49µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:12.228334Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"145.984051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.166\" limit:1 ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-06T14:30:12.228454Z","caller":"traceutil/trace.go:172","msg":"trace[28712571] range","detail":"{range_begin:/registry/masterleases/199.19.213.166; range_end:; response_count:1; response_revision:4887; }","duration":"146.127552ms","start":"2026-07-06T14:30:12.082301Z","end":"2026-07-06T14:30:12.228429Z","steps":["trace[28712571] 'agreement among raft nodes before linearized reading' (duration: 108.719323ms)","trace[28712571] 'range keys from in-memory index tree' (duration: 37.094786ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:30:12.480539Z","caller":"traceutil/trace.go:172","msg":"trace[1566628291] linearizableReadLoop","detail":"{readStateIndex:5621; appliedIndex:5621; }","duration":"166.922514ms","start":"2026-07-06T14:30:12.313579Z","end":"2026-07-06T14:30:12.480502Z","steps":["trace[1566628291] 'read index received' (duration: 166.908024ms)","trace[1566628291] 'applied index is now lower than readState.Index' (duration: 11.58µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:12.510462Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"204.228893ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:12.510556Z","caller":"traceutil/trace.go:172","msg":"trace[960767881] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4888; }","duration":"204.340154ms","start":"2026-07-06T14:30:12.306191Z","end":"2026-07-06T14:30:12.510531Z","steps":["trace[960767881] 'agreement among raft nodes before linearized reading' (duration: 174.546451ms)","trace[960767881] 'range keys from in-memory index tree' (duration: 29.648441ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:30:12.510881Z","caller":"traceutil/trace.go:172","msg":"trace[1830033500] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"197.688457ms","start":"2026-07-06T14:30:12.313153Z","end":"2026-07-06T14:30:12.510841Z","steps":["trace[1830033500] 'process raft request' (duration: 167.565661ms)","trace[1830033500] 'compare' (duration: 29.845763ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:30:12.633858Z","caller":"traceutil/trace.go:172","msg":"trace[1962707088] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"314.596102ms","start":"2026-07-06T14:30:12.319245Z","end":"2026-07-06T14:30:12.633841Z","steps":["trace[1962707088] 'process raft request' (duration: 314.3522ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:12.633895Z","caller":"traceutil/trace.go:172","msg":"trace[436833738] linearizableReadLoop","detail":"{readStateIndex:5628; appliedIndex:5626; }","duration":"117.577433ms","start":"2026-07-06T14:30:12.516299Z","end":"2026-07-06T14:30:12.633877Z","steps":["trace[436833738] 'read index received' (duration: 113.409271ms)","trace[436833738] 'applied index is now lower than readState.Index' (duration: 4.166872ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:12.633999Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:12.319220Z","time spent":"314.707364ms","remote":"127.0.0.1:45378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T14:30:12.635332Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"119.033938ms","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-07-06T14:30:12.635414Z","caller":"traceutil/trace.go:172","msg":"trace[1831861810] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4894; }","duration":"119.118438ms","start":"2026-07-06T14:30:12.516283Z","end":"2026-07-06T14:30:12.635401Z","steps":["trace[1831861810] 'agreement among raft nodes before linearized reading' (duration: 117.683664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:13.942141Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"121.852461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:13.942290Z","caller":"traceutil/trace.go:172","msg":"trace[1147738584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4900; }","duration":"122.010433ms","start":"2026-07-06T14:30:13.820246Z","end":"2026-07-06T14:30:13.942257Z","steps":["trace[1147738584] 'agreement among raft nodes before linearized reading' (duration: 72.242501ms)","trace[1147738584] 'range keys from in-memory index tree' (duration: 49.534609ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:14.762011Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"161.277145ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-06T14:30:15.346540Z","caller":"traceutil/trace.go:172","msg":"trace[71308339] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"139.607625ms","start":"2026-07-06T14:30:15.206895Z","end":"2026-07-06T14:30:15.346503Z","steps":["trace[71308339] 'process raft request' (duration: 139.453524ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:17.026536Z","caller":"traceutil/trace.go:172","msg":"trace[132266910] linearizableReadLoop","detail":"{readStateIndex:5648; appliedIndex:5647; }","duration":"200.214137ms","start":"2026-07-06T14:30:16.826289Z","end":"2026-07-06T14:30:17.026503Z","steps":["trace[132266910] 'read index received' (duration: 103.755512ms)","trace[132266910] 'applied index is now lower than readState.Index' (duration: 96.457445ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:17.026741Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"200.42548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:17.026853Z","caller":"traceutil/trace.go:172","msg":"trace[1163799768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4913; }","duration":"200.556571ms","start":"2026-07-06T14:30:16.826274Z","end":"2026-07-06T14:30:17.026831Z","steps":["trace[1163799768] 'agreement among raft nodes before linearized reading' (duration: 200.332459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:22.339143Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"182.761343ms","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-07-06T14:30:22.339251Z","caller":"traceutil/trace.go:172","msg":"trace[744583604] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4940; }","duration":"182.870224ms","start":"2026-07-06T14:30:22.156346Z","end":"2026-07-06T14:30:22.339216Z","steps":["trace[744583604] 'agreement among raft nodes before linearized reading' (duration: 25.938502ms)","trace[744583604] 'range keys from in-memory index tree' (duration: 156.666528ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:30:24.487026Z","caller":"traceutil/trace.go:172","msg":"trace[233711078] linearizableReadLoop","detail":"{readStateIndex:5690; appliedIndex:5690; }","duration":"251.620386ms","start":"2026-07-06T14:30:24.235377Z","end":"2026-07-06T14:30:24.486998Z","steps":["trace[233711078] 'read index received' (duration: 251.599096ms)","trace[233711078] 'applied index is now lower than readState.Index' (duration: 20.15µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:24.487269Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"251.844299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:24.487450Z","caller":"traceutil/trace.go:172","msg":"trace[78662441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4950; }","duration":"252.041851ms","start":"2026-07-06T14:30:24.235361Z","end":"2026-07-06T14:30:24.487403Z","steps":["trace[78662441] 'agreement among raft nodes before linearized reading' (duration: 251.737948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:24.489175Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"182.140776ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:24.489265Z","caller":"traceutil/trace.go:172","msg":"trace[1379870489] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4950; }","duration":"182.255647ms","start":"2026-07-06T14:30:24.306990Z","end":"2026-07-06T14:30:24.489246Z","steps":["trace[1379870489] 'agreement among raft nodes before linearized reading' (duration: 182.103956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:24.717964Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"142.193457ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-06T14:30:24.934921Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"115.469569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:24.935035Z","caller":"traceutil/trace.go:172","msg":"trace[1997869701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4951; }","duration":"115.60166ms","start":"2026-07-06T14:30:24.819408Z","end":"2026-07-06T14:30:24.935010Z","steps":["trace[1997869701] 'range keys from in-memory index tree' (duration: 113.273583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:25.684403Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"133.161108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-06T14:30:25.684477Z","caller":"traceutil/trace.go:172","msg":"trace[1073301141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4952; }","duration":"133.254369ms","start":"2026-07-06T14:30:25.551209Z","end":"2026-07-06T14:30:25.684463Z","steps":["trace[1073301141] 'range keys from in-memory index tree' (duration: 131.396057ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:25.858753Z","caller":"traceutil/trace.go:172","msg":"trace[1798427185] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"154.311722ms","start":"2026-07-06T14:30:25.704410Z","end":"2026-07-06T14:30:25.858721Z","steps":["trace[1798427185] 'process raft request' (duration: 115.711974ms)","trace[1798427185] 'compare' (duration: 38.416896ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:26.822046Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"142.664314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:443"} {"level":"info","ts":"2026-07-06T14:30:26.822136Z","caller":"traceutil/trace.go:172","msg":"trace[1353718593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4958; }","duration":"142.766555ms","start":"2026-07-06T14:30:26.679351Z","end":"2026-07-06T14:30:26.822118Z","steps":["trace[1353718593] 'range keys from in-memory index tree' (duration: 141.167606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:27.431913Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"124.429762ms","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-07-06T14:30:27.432157Z","caller":"traceutil/trace.go:172","msg":"trace[1431897965] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4959; }","duration":"124.604914ms","start":"2026-07-06T14:30:27.307418Z","end":"2026-07-06T14:30:27.432023Z","steps":["trace[1431897965] 'range keys from in-memory index tree' (duration: 124.370762ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:27.958335Z","caller":"traceutil/trace.go:172","msg":"trace[1940874975] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"249.338852ms","start":"2026-07-06T14:30:27.708969Z","end":"2026-07-06T14:30:27.958307Z","steps":["trace[1940874975] 'process raft request' (duration: 249.103269ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:28.134508Z","caller":"traceutil/trace.go:172","msg":"trace[471683249] linearizableReadLoop","detail":"{readStateIndex:5704; appliedIndex:5704; }","duration":"314.748391ms","start":"2026-07-06T14:30:27.819732Z","end":"2026-07-06T14:30:28.134480Z","steps":["trace[471683249] 'read index received' (duration: 314.739241ms)","trace[471683249] 'applied index is now lower than readState.Index' (duration: 7.86µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:28.137193Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"317.442173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:28.137253Z","caller":"traceutil/trace.go:172","msg":"trace[1643994585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4961; }","duration":"317.522704ms","start":"2026-07-06T14:30:27.819716Z","end":"2026-07-06T14:30:28.137239Z","steps":["trace[1643994585] 'agreement among raft nodes before linearized reading' (duration: 314.871502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:28.137344Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:30:27.819688Z","time spent":"317.644566ms","remote":"127.0.0.1:45282","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-07-06T14:30:28.137331Z","caller":"traceutil/trace.go:172","msg":"trace[1364200872] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"273.950159ms","start":"2026-07-06T14:30:27.863358Z","end":"2026-07-06T14:30:28.137309Z","steps":["trace[1364200872] 'process raft request' (duration: 271.176155ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:30:38.256423Z","caller":"traceutil/trace.go:172","msg":"trace[223737808] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"166.498037ms","start":"2026-07-06T14:30:38.089907Z","end":"2026-07-06T14:30:38.256405Z","steps":["trace[223737808] 'process raft request' (duration: 166.298645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:30:38.441887Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"135.520555ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:30:38.442014Z","caller":"traceutil/trace.go:172","msg":"trace[614877479] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5001; }","duration":"135.661486ms","start":"2026-07-06T14:30:38.306320Z","end":"2026-07-06T14:30:38.441982Z","steps":["trace[614877479] 'agreement among raft nodes before linearized reading' (duration: 93.147472ms)","trace[614877479] 'range keys from in-memory index tree' (duration: 42.276241ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:30:38.443320Z","caller":"traceutil/trace.go:172","msg":"trace[1661645666] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"243.50424ms","start":"2026-07-06T14:30:38.198339Z","end":"2026-07-06T14:30:38.441844Z","steps":["trace[1661645666] 'process raft request' (duration: 200.859594ms)","trace[1661645666] 'compare' (duration: 42.24075ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:30:38.743350Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"100.168404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T14:30:52.664906Z","caller":"traceutil/trace.go:172","msg":"trace[1883819181] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"145.411271ms","start":"2026-07-06T14:30:52.519463Z","end":"2026-07-06T14:30:52.664874Z","steps":["trace[1883819181] 'process raft request' (duration: 99.935764ms)","trace[1883819181] 'compare' (duration: 44.690776ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:31:00.486314Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"130.251914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T14:31:00.486436Z","caller":"traceutil/trace.go:172","msg":"trace[2003284685] linearizableReadLoop","detail":"{readStateIndex:5849; appliedIndex:5848; }","duration":"179.491253ms","start":"2026-07-06T14:31:00.306924Z","end":"2026-07-06T14:31:00.486415Z","steps":["trace[2003284685] 'read index received' (duration: 50.743583ms)","trace[2003284685] 'applied index is now lower than readState.Index' (duration: 128.74679ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:31:00.486716Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"179.783247ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:31:00.486751Z","caller":"traceutil/trace.go:172","msg":"trace[1363702386] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5087; }","duration":"179.820848ms","start":"2026-07-06T14:31:00.306917Z","end":"2026-07-06T14:31:00.486738Z","steps":["trace[1363702386] 'agreement among raft nodes before linearized reading' (duration: 179.759327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:31:00.488730Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"158.022346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:31:00.488781Z","caller":"traceutil/trace.go:172","msg":"trace[1594162192] range","detail":"{range_begin:/registry/roles; range_end:; response_count:0; response_revision:5087; }","duration":"158.076977ms","start":"2026-07-06T14:31:00.330692Z","end":"2026-07-06T14:31:00.488769Z","steps":["trace[1594162192] 'agreement among raft nodes before linearized reading' (duration: 157.949805ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:31:00.710729Z","caller":"traceutil/trace.go:172","msg":"trace[1291374989] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"158.846919ms","start":"2026-07-06T14:31:00.551850Z","end":"2026-07-06T14:31:00.710697Z","steps":["trace[1291374989] 'process raft request' (duration: 96.235276ms)","trace[1291374989] 'compare' (duration: 62.41099ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:31:02.232418Z","caller":"traceutil/trace.go:172","msg":"trace[445996068] linearizableReadLoop","detail":"{readStateIndex:5854; appliedIndex:5854; }","duration":"146.913445ms","start":"2026-07-06T14:31:02.085482Z","end":"2026-07-06T14:31:02.232396Z","steps":["trace[445996068] 'read index received' (duration: 146.903145ms)","trace[445996068] 'applied index is now lower than readState.Index' (duration: 4.48µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:31:02.232762Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"147.26952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.166\" limit:1 ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-06T14:31:02.233064Z","caller":"traceutil/trace.go:172","msg":"trace[1675676273] range","detail":"{range_begin:/registry/masterleases/199.19.213.166; range_end:; response_count:1; response_revision:5092; }","duration":"147.584935ms","start":"2026-07-06T14:31:02.085466Z","end":"2026-07-06T14:31:02.233051Z","steps":["trace[1675676273] 'agreement among raft nodes before linearized reading' (duration: 147.139288ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:31:02.334967Z","caller":"traceutil/trace.go:172","msg":"trace[1905782550] linearizableReadLoop","detail":"{readStateIndex:5854; appliedIndex:5854; }","duration":"100.622642ms","start":"2026-07-06T14:31:02.234324Z","end":"2026-07-06T14:31:02.334946Z","steps":["trace[1905782550] 'read index received' (duration: 100.615642ms)","trace[1905782550] 'applied index is now lower than readState.Index' (duration: 6.04µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:31:02.366068Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"131.707091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:31:02.366178Z","caller":"traceutil/trace.go:172","msg":"trace[1484292876] range","detail":"{range_begin:/registry/validatingwebhookconfigurations; range_end:; response_count:0; response_revision:5092; }","duration":"131.826023ms","start":"2026-07-06T14:31:02.234317Z","end":"2026-07-06T14:31:02.366143Z","steps":["trace[1484292876] 'agreement among raft nodes before linearized reading' (duration: 100.712403ms)","trace[1484292876] 'range keys from in-memory index tree' (duration: 30.964688ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:31:02.563574Z","caller":"traceutil/trace.go:172","msg":"trace[753209086] linearizableReadLoop","detail":"{readStateIndex:5857; appliedIndex:5857; }","duration":"197.519555ms","start":"2026-07-06T14:31:02.366024Z","end":"2026-07-06T14:31:02.563544Z","steps":["trace[753209086] 'read index received' (duration: 197.510415ms)","trace[753209086] 'applied index is now lower than readState.Index' (duration: 7.65µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:31:02.617088Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"251.02808ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:31:02.617175Z","caller":"traceutil/trace.go:172","msg":"trace[1492615725] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5094; }","duration":"251.142972ms","start":"2026-07-06T14:31:02.366013Z","end":"2026-07-06T14:31:02.617156Z","steps":["trace[1492615725] 'agreement among raft nodes before linearized reading' (duration: 197.645637ms)","trace[1492615725] 'range keys from in-memory index tree' (duration: 53.359623ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:31:02.617494Z","caller":"traceutil/trace.go:172","msg":"trace[520232269] transaction","detail":"{read_only:false; response_revision:5095; number_of_response:1; }","duration":"249.841374ms","start":"2026-07-06T14:31:02.367632Z","end":"2026-07-06T14:31:02.617474Z","steps":["trace[520232269] 'process raft request' (duration: 195.972642ms)","trace[520232269] 'compare' (duration: 53.497075ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:31:04.876949Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"143.394509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-06T14:31:04.877080Z","caller":"traceutil/trace.go:172","msg":"trace[137420705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5104; }","duration":"143.573162ms","start":"2026-07-06T14:31:04.733485Z","end":"2026-07-06T14:31:04.877058Z","steps":["trace[137420705] 'range keys from in-memory index tree' (duration: 141.504309ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:31:06.203729Z","caller":"traceutil/trace.go:172","msg":"trace[619600307] transaction","detail":"{read_only:false; response_revision:5108; number_of_response:1; }","duration":"121.3986ms","start":"2026-07-06T14:31:06.082313Z","end":"2026-07-06T14:31:06.203712Z","steps":["trace[619600307] 'process raft request' (duration: 121.172996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:31:07.102915Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"101.126447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T14:31:07.103037Z","caller":"traceutil/trace.go:172","msg":"trace[678694952] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"166.949587ms","start":"2026-07-06T14:31:06.936068Z","end":"2026-07-06T14:31:07.103018Z","steps":["trace[678694952] 'process raft request' (duration: 65.646897ms)","trace[678694952] 'compare' (duration: 101.002466ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T14:31:09.570321Z","caller":"traceutil/trace.go:172","msg":"trace[31135398] linearizableReadLoop","detail":"{readStateIndex:5892; appliedIndex:5892; }","duration":"121.506354ms","start":"2026-07-06T14:31:09.448793Z","end":"2026-07-06T14:31:09.570299Z","steps":["trace[31135398] 'read index received' (duration: 121.498784ms)","trace[31135398] 'applied index is now lower than readState.Index' (duration: 6.39µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:31:09.570417Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"121.603486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:31:09.570451Z","caller":"traceutil/trace.go:172","msg":"trace[2043271272] range","detail":"{range_begin:/registry/prioritylevelconfigurations; range_end:; response_count:0; response_revision:5124; }","duration":"121.655986ms","start":"2026-07-06T14:31:09.448787Z","end":"2026-07-06T14:31:09.570443Z","steps":["trace[2043271272] 'agreement among raft nodes before linearized reading' (duration: 121.582245ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:31:10.596676Z","caller":"traceutil/trace.go:172","msg":"trace[374628374] linearizableReadLoop","detail":"{readStateIndex:5894; appliedIndex:5894; }","duration":"128.712722ms","start":"2026-07-06T14:31:10.467933Z","end":"2026-07-06T14:31:10.596645Z","steps":["trace[374628374] 'read index received' (duration: 128.706152ms)","trace[374628374] 'applied index is now lower than readState.Index' (duration: 5.26µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:31:10.596817Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"128.838794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deviceclasses\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:31:10.596907Z","caller":"traceutil/trace.go:172","msg":"trace[1523618903] range","detail":"{range_begin:/registry/deviceclasses; range_end:; response_count:0; response_revision:5126; }","duration":"128.962506ms","start":"2026-07-06T14:31:10.467928Z","end":"2026-07-06T14:31:10.596891Z","steps":["trace[1523618903] 'agreement among raft nodes before linearized reading' (duration: 128.794583ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:31:12.573605Z","caller":"traceutil/trace.go:172","msg":"trace[2003476185] linearizableReadLoop","detail":"{readStateIndex:5901; appliedIndex:5901; }","duration":"266.343556ms","start":"2026-07-06T14:31:12.307237Z","end":"2026-07-06T14:31:12.573581Z","steps":["trace[2003476185] 'read index received' (duration: 266.335186ms)","trace[2003476185] 'applied index is now lower than readState.Index' (duration: 6.94µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:31:12.573737Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"266.477468ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:31:12.573880Z","caller":"traceutil/trace.go:172","msg":"trace[1432564428] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5132; }","duration":"266.62488ms","start":"2026-07-06T14:31:12.307232Z","end":"2026-07-06T14:31:12.573856Z","steps":["trace[1432564428] 'agreement among raft nodes before linearized reading' (duration: 266.432367ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:31:12.603730Z","caller":"traceutil/trace.go:172","msg":"trace[286297386] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"249.034414ms","start":"2026-07-06T14:31:12.354673Z","end":"2026-07-06T14:31:12.603707Z","steps":["trace[286297386] 'process raft request' (duration: 248.726259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:31:12.604058Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"242.648869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/servicecidrs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:31:12.604132Z","caller":"traceutil/trace.go:172","msg":"trace[1493152816] range","detail":"{range_begin:/registry/servicecidrs; range_end:; response_count:0; response_revision:5134; }","duration":"242.73865ms","start":"2026-07-06T14:31:12.361377Z","end":"2026-07-06T14:31:12.604115Z","steps":["trace[1493152816] 'agreement among raft nodes before linearized reading' (duration: 242.612597ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:31:12.604146Z","caller":"traceutil/trace.go:172","msg":"trace[211452988] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"169.915199ms","start":"2026-07-06T14:31:12.434199Z","end":"2026-07-06T14:31:12.604114Z","steps":["trace[211452988] 'process raft request' (duration: 169.420021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:31:15.417648Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"116.67904ms","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-07-06T14:31:15.417773Z","caller":"traceutil/trace.go:172","msg":"trace[1810952294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5145; }","duration":"116.825013ms","start":"2026-07-06T14:31:15.300927Z","end":"2026-07-06T14:31:15.417752Z","steps":["trace[1810952294] 'agreement among raft nodes before linearized reading' (duration: 60.841332ms)","trace[1810952294] 'range keys from in-memory index tree' (duration: 55.704266ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:31:15.418474Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"111.559615ms","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-07-06T14:31:15.418530Z","caller":"traceutil/trace.go:172","msg":"trace[576480567] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5145; }","duration":"111.653477ms","start":"2026-07-06T14:31:15.306864Z","end":"2026-07-06T14:31:15.418517Z","steps":["trace[576480567] 'range keys from in-memory index tree' (duration: 111.510094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:31:16.446637Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"131.359556ms","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-07-06T14:31:16.446719Z","caller":"traceutil/trace.go:172","msg":"trace[2035668290] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5147; }","duration":"131.456338ms","start":"2026-07-06T14:31:16.315246Z","end":"2026-07-06T14:31:16.446702Z","steps":["trace[2035668290] 'range keys from in-memory index tree' (duration: 131.300006ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:31:16.773760Z","caller":"traceutil/trace.go:172","msg":"trace[710613253] transaction","detail":"{read_only:false; response_revision:5149; number_of_response:1; }","duration":"279.562435ms","start":"2026-07-06T14:31:16.494159Z","end":"2026-07-06T14:31:16.773721Z","steps":["trace[710613253] 'process raft request' (duration: 279.2593ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:31:16.775600Z","caller":"traceutil/trace.go:172","msg":"trace[1482925917] linearizableReadLoop","detail":"{readStateIndex:5920; appliedIndex:5920; }","duration":"176.41512ms","start":"2026-07-06T14:31:16.599156Z","end":"2026-07-06T14:31:16.775571Z","steps":["trace[1482925917] 'read index received' (duration: 176.40544ms)","trace[1482925917] 'applied index is now lower than readState.Index' (duration: 8.11µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:31:16.776189Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"176.996739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:443"} {"level":"info","ts":"2026-07-06T14:31:16.776373Z","caller":"traceutil/trace.go:172","msg":"trace[1859895926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5149; }","duration":"177.207693ms","start":"2026-07-06T14:31:16.599148Z","end":"2026-07-06T14:31:16.776355Z","steps":["trace[1859895926] 'agreement among raft nodes before linearized reading' (duration: 176.513481ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:31:16.979294Z","caller":"traceutil/trace.go:172","msg":"trace[373936344] linearizableReadLoop","detail":"{readStateIndex:5921; appliedIndex:5920; }","duration":"157.969271ms","start":"2026-07-06T14:31:16.821302Z","end":"2026-07-06T14:31:16.979271Z","steps":["trace[373936344] 'read index received' (duration: 75.251449ms)","trace[373936344] 'applied index is now lower than readState.Index' (duration: 82.716922ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:31:16.979426Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"158.122523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:31:16.979454Z","caller":"traceutil/trace.go:172","msg":"trace[946412187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5150; }","duration":"158.170424ms","start":"2026-07-06T14:31:16.821275Z","end":"2026-07-06T14:31:16.979446Z","steps":["trace[946412187] 'agreement among raft nodes before linearized reading' (duration: 158.094723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:31:17.434455Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"127.967881ms","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-07-06T14:31:17.434519Z","caller":"traceutil/trace.go:172","msg":"trace[1191256191] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5151; }","duration":"128.042232ms","start":"2026-07-06T14:31:17.306465Z","end":"2026-07-06T14:31:17.434507Z","steps":["trace[1191256191] 'range keys from in-memory index tree' (duration: 127.931091ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:31:17.434736Z","caller":"traceutil/trace.go:172","msg":"trace[993791849] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"309.456433ms","start":"2026-07-06T14:31:17.125271Z","end":"2026-07-06T14:31:17.434728Z","steps":["trace[993791849] 'process raft request' (duration: 263.047233ms)","trace[993791849] 'compare' (duration: 45.627987ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:31:17.434854Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-07-06T14:31:17.125246Z","time spent":"309.509563ms","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":467,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T14:31:17.435753Z","caller":"traceutil/trace.go:172","msg":"trace[566796341] linearizableReadLoop","detail":"{readStateIndex:5925; appliedIndex:5925; }","duration":"249.149749ms","start":"2026-07-06T14:31:17.186587Z","end":"2026-07-06T14:31:17.435737Z","steps":["trace[566796341] 'read index received' (duration: 249.145289ms)","trace[566796341] 'applied index is now lower than readState.Index' (duration: 3.55µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:31:17.435879Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"249.297311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:31:17.435917Z","caller":"traceutil/trace.go:172","msg":"trace[720622884] range","detail":"{range_begin:/registry/resourcequotas; range_end:; response_count:0; response_revision:5153; }","duration":"249.340721ms","start":"2026-07-06T14:31:17.186567Z","end":"2026-07-06T14:31:17.435908Z","steps":["trace[720622884] 'agreement among raft nodes before linearized reading' (duration: 249.27027ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:31:19.700272Z","caller":"traceutil/trace.go:172","msg":"trace[1490425592] linearizableReadLoop","detail":"{readStateIndex:5935; appliedIndex:5935; }","duration":"131.569045ms","start":"2026-07-06T14:31:19.568678Z","end":"2026-07-06T14:31:19.700247Z","steps":["trace[1490425592] 'read index received' (duration: 131.563335ms)","trace[1490425592] 'applied index is now lower than readState.Index' (duration: 4.52µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:31:19.700505Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"131.80824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-06T14:31:19.700737Z","caller":"traceutil/trace.go:172","msg":"trace[1214859795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5161; }","duration":"132.049814ms","start":"2026-07-06T14:31:19.568664Z","end":"2026-07-06T14:31:19.700714Z","steps":["trace[1214859795] 'agreement among raft nodes before linearized reading' (duration: 131.670948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:31:22.658005Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"100.028875ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-06T14:31:25.243842Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"109.856196ms","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-07-06T14:31:25.243925Z","caller":"traceutil/trace.go:172","msg":"trace[1449677672] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5183; }","duration":"109.961508ms","start":"2026-07-06T14:31:25.133946Z","end":"2026-07-06T14:31:25.243908Z","steps":["trace[1449677672] 'range keys from in-memory index tree' (duration: 109.796745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T14:31:27.728359Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"110.844402ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-06T14:31:27.728795Z","caller":"traceutil/trace.go:172","msg":"trace[839988958] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"168.749897ms","start":"2026-07-06T14:31:27.560026Z","end":"2026-07-06T14:31:27.728775Z","steps":["trace[839988958] 'process raft request' (duration: 168.471261ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:32:07.443023Z","caller":"traceutil/trace.go:172","msg":"trace[1357122381] linearizableReadLoop","detail":"{readStateIndex:6148; appliedIndex:6148; }","duration":"136.230976ms","start":"2026-07-06T14:32:07.306774Z","end":"2026-07-06T14:32:07.443005Z","steps":["trace[1357122381] 'read index received' (duration: 136.225596ms)","trace[1357122381] 'applied index is now lower than readState.Index' (duration: 4.43µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T14:32:07.443152Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"136.389999ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T14:32:07.443170Z","caller":"traceutil/trace.go:172","msg":"trace[545052569] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5346; }","duration":"136.43337ms","start":"2026-07-06T14:32:07.306731Z","end":"2026-07-06T14:32:07.443165Z","steps":["trace[545052569] 'agreement among raft nodes before linearized reading' (duration: 136.356999ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T14:32:08.320875Z","caller":"traceutil/trace.go:172","msg":"trace[17480347] transaction","detail":"{read_only:false; response_revision:5349; number_of_response:1; }","duration":"126.777659ms","start":"2026-07-06T14:32:08.194074Z","end":"2026-07-06T14:32:08.320852Z","steps":["trace[17480347] 'process raft request' (duration: 126.628286ms)"],"step_count":1}