{"level":"warn","ts":"2026-04-17T00:55:55.914117Z","caller":"embed/config.go:1209","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"warn","ts":"2026-04-17T00:55:55.914991Z","caller":"etcdmain/config.go:270","msg":"--snapshot-count is deprecated in 3.6 and will be decommissioned in 3.7."} {"level":"info","ts":"2026-04-17T00:55:55.915021Z","caller":"etcdmain/etcd.go:64","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--feature-gates=InitialCorruptCheck=true","--initial-advertise-peer-urls=https://199.204.45.22:2380","--initial-cluster=controller-1=https://199.204.45.109:2380,controller-3=https://199.204.45.22:2380,controller-2=https://199.204.45.77:2380","--initial-cluster-state=existing","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22: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-04-17T00:55:55.915361Z","caller":"etcdmain/etcd.go:124","msg":"Initialize and start etcd server","data-dir":"/var/lib/etcd","dir-type":"empty"} {"level":"warn","ts":"2026-04-17T00:55:55.915403Z","caller":"embed/config.go:1209","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T00:55:55.915417Z","caller":"embed/etcd.go:138","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-17T00:55:55.915441Z","caller":"embed/etcd.go:544","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-17T00:55:55.915997Z","caller":"embed/etcd.go:146","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-04-17T00:55:55.916954Z","caller":"embed/etcd.go:323","msg":"starting an etcd server","etcd-version":"3.6.4","git-sha":"5400cdc","go-version":"go1.23.11","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"controller-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.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"experimental-local-address":"","cors":["*"],"host-whitelist":["*"],"initial-cluster":"controller-1=https://199.204.45.109:2380,controller-2=https://199.204.45.77:2380,controller-3=https://199.204.45.22:2380","initial-cluster-state":"existing","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"feature-gates":"InitialCorruptCheck=true","initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","discovery-token":"","discovery-endpoints":"","discovery-dial-timeout":"2s","discovery-request-timeout":"5s","discovery-keepalive-time":"2s","discovery-keepalive-timeout":"6s","discovery-insecure-transport":true,"discovery-insecure-skip-tls-verify":false,"discovery-cert":"","discovery-key":"","discovery-cacert":"","discovery-user":"","downgrade-check-interval":"5s","max-learners":1,"v2-deprecation":"write-only"} {"level":"info","ts":"2026-04-17T00:55:55.921163Z","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: 0xc0003f4558}"} {"level":"info","ts":"2026-04-17T00:55:55.925208Z","logger":"bbolt","caller":"bbolt@v1.4.2/db.go:321","msg":"Opening bbolt db (/var/lib/etcd/member/snap/db) successfully"} {"level":"info","ts":"2026-04-17T00:55:55.925250Z","caller":"storage/backend.go:80","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.183954ms"} {"level":"info","ts":"2026-04-17T00:55:55.925272Z","caller":"etcdserver/bootstrap.go:220","msg":"restore consistentIndex","index":0} {"level":"info","ts":"2026-04-17T00:55:55.925445Z","caller":"etcdserver/bootstrap.go:94","msg":"bootstrapping cluster"} {"level":"info","ts":"2026-04-17T00:55:55.936775Z","caller":"netutil/netutil.go:113","msg":"resolved URL Host","url":"https://199.204.45.22:2380","host":"199.204.45.22:2380","resolved-addr":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-17T00:55:55.936821Z","caller":"netutil/netutil.go:113","msg":"resolved URL Host","url":"https://199.204.45.109:2380","host":"199.204.45.109:2380","resolved-addr":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-17T00:55:55.936841Z","caller":"netutil/netutil.go:113","msg":"resolved URL Host","url":"https://199.204.45.22:2380","host":"199.204.45.22:2380","resolved-addr":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-17T00:55:55.936851Z","caller":"netutil/netutil.go:113","msg":"resolved URL Host","url":"https://199.204.45.77:2380","host":"199.204.45.77:2380","resolved-addr":"199.204.45.77:2380"} {"level":"info","ts":"2026-04-17T00:55:55.936867Z","caller":"netutil/netutil.go:113","msg":"resolved URL Host","url":"https://199.204.45.77:2380","host":"199.204.45.77:2380","resolved-addr":"199.204.45.77:2380"} {"level":"info","ts":"2026-04-17T00:55:55.936879Z","caller":"netutil/netutil.go:113","msg":"resolved URL Host","url":"https://199.204.45.109:2380","host":"199.204.45.109:2380","resolved-addr":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-17T00:55:55.953538Z","caller":"etcdserver/bootstrap.go:101","msg":"bootstrapping storage"} {"level":"info","ts":"2026-04-17T00:55:56.021574Z","caller":"etcdserver/bootstrap.go:109","msg":"bootstrapping raft"} {"level":"info","ts":"2026-04-17T00:55:56.021634Z","caller":"etcdserver/bootstrap.go:499","msg":"starting local member","local-member-id":"46e1a43e5ead920f","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-04-17T00:55:56.021681Z","caller":"etcdserver/server.go:312","msg":"bootstrap successfully"} {"level":"info","ts":"2026-04-17T00:55:56.021814Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"46e1a43e5ead920f switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T00:55:56.021894Z","logger":"raft","caller":"v3@v3.6.0/raft.go:897","msg":"46e1a43e5ead920f became follower at term 0"} {"level":"info","ts":"2026-04-17T00:55:56.021909Z","logger":"raft","caller":"v3@v3.6.0/raft.go:493","msg":"newRaft 46e1a43e5ead920f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"warn","ts":"2026-04-17T00:55:56.029298Z","caller":"auth/store.go:1135","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T00:55:56.037964Z","caller":"mvcc/kvstore.go:408","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T00:55:56.045647Z","caller":"storage/quota.go:93","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-17T00:55:56.046079Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.046096Z","caller":"rafthttp/transport.go:286","msg":"added new remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce","remote-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-17T00:55:56.046114Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.046119Z","caller":"rafthttp/transport.go:286","msg":"added new remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf","remote-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-17T00:55:56.046130Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.046143Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.046303Z","caller":"rafthttp/stream.go:168","msg":"started stream writer with remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.046269Z","caller":"rafthttp/stream.go:168","msg":"started stream writer with remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.053417Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.053448Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce","remote-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-17T00:55:56.053477Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.053490Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.053894Z","caller":"rafthttp/stream.go:394","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.053491Z","caller":"rafthttp/stream.go:394","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.054363Z","caller":"rafthttp/stream.go:168","msg":"started stream writer with remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.054860Z","caller":"rafthttp/stream.go:168","msg":"started stream writer with remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.057640Z","caller":"rafthttp/stream.go:394","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.057595Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.057686Z","caller":"rafthttp/stream.go:394","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.057738Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf","remote-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-17T00:55:56.057849Z","caller":"etcdserver/server.go:598","msg":"starting etcd server","local-member-id":"46e1a43e5ead920f","local-server-version":"3.6.4","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T00:55:56.057982Z","caller":"fileutil/purge.go:49","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T00:55:56.058051Z","caller":"fileutil/purge.go:49","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T00:55:56.058082Z","caller":"fileutil/purge.go:49","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T00:55:56.058120Z","caller":"etcdserver/server.go:499","msg":"starting initial election tick advance","election-ticks":10} {"level":"info","ts":"2026-04-17T00:55:56.058363Z","caller":"embed/etcd.go:766","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-17T00:55:56.059549Z","caller":"embed/etcd.go:640","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-17T00:55:56.059817Z","caller":"embed/etcd.go:611","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-17T00:55:56.059936Z","caller":"embed/etcd.go:292","msg":"now serving peer/client/metrics","local-member-id":"46e1a43e5ead920f","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T00:55:56.059978Z","caller":"embed/etcd.go:890","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T00:55:56.060537Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1116","msg":"46e1a43e5ead920f [term: 0] received a MsgHeartbeat message with higher term from e87e20b7f6d3ceaf [term: 3]"} {"level":"info","ts":"2026-04-17T00:55:56.060586Z","logger":"raft","caller":"v3@v3.6.0/raft.go:897","msg":"46e1a43e5ead920f became follower at term 3"} {"level":"info","ts":"2026-04-17T00:55:56.060608Z","logger":"raft","caller":"v3@v3.6.0/node.go:370","msg":"raft.node: 46e1a43e5ead920f elected leader e87e20b7f6d3ceaf at term 3"} {"level":"info","ts":"2026-04-17T00:55:56.060818Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"46e1a43e5ead920f","to":"e87e20b7f6d3ceaf","stream-type":"stream Message"} {"level":"info","ts":"2026-04-17T00:55:56.060869Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.061176Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.067962Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.067989Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.074184Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.074648Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"46e1a43e5ead920f","to":"d71a1778b31d4ce","stream-type":"stream Message"} {"level":"info","ts":"2026-04-17T00:55:56.074667Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.074698Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"46e1a43e5ead920f","to":"e87e20b7f6d3ceaf","stream-type":"stream MsgApp v2"} {"level":"info","ts":"2026-04-17T00:55:56.074715Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.077612Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.078816Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"46e1a43e5ead920f","to":"d71a1778b31d4ce","stream-type":"stream MsgApp v2"} {"level":"info","ts":"2026-04-17T00:55:56.078860Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.078972Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.082611Z","caller":"rafthttp/http.go:258","msg":"receiving database snapshot","local-member-id":"46e1a43e5ead920f","remote-snapshot-sender-id":"e87e20b7f6d3ceaf","incoming-snapshot-index":596,"incoming-snapshot-message-size-bytes":8738,"incoming-snapshot-message-size":"8.7 kB"} {"level":"info","ts":"2026-04-17T00:55:56.094766Z","caller":"snap/db.go:65","msg":"saved database snapshot to disk","path":"/var/lib/etcd/member/snap/0000000000000254.snap.db","bytes":999424,"size":"999 kB"} {"level":"info","ts":"2026-04-17T00:55:56.094827Z","caller":"rafthttp/http.go:287","msg":"received and saved database snapshot","local-member-id":"46e1a43e5ead920f","remote-snapshot-sender-id":"e87e20b7f6d3ceaf","incoming-snapshot-index":596,"incoming-snapshot-size-bytes":999424,"incoming-snapshot-size":"999 kB","download-took":"12.291879ms"} {"level":"info","ts":"2026-04-17T00:55:56.095063Z","logger":"raft","caller":"v3@v3.6.0/log.go:465","msg":"log [committed=0, applied=0, applying=0, unstable.offset=1, unstable.offsetInProgress=1, len(unstable.Entries)=0] starts to restore snapshot [index: 596, term: 3]"} {"level":"info","ts":"2026-04-17T00:55:56.095258Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"46e1a43e5ead920f switched to configuration voters=(968732929669846222 16752863638357003951) learners=(5107544040198214159)"} {"level":"info","ts":"2026-04-17T00:55:56.095397Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1935","msg":"46e1a43e5ead920f [commit: 596, lastindex: 596, lastterm: 3] restored snapshot [index: 596, term: 3]"} {"level":"info","ts":"2026-04-17T00:55:56.095437Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1844","msg":"46e1a43e5ead920f [commit: 596] restored snapshot [index: 596, term: 3]"} {"level":"info","ts":"2026-04-17T00:55:56.095620Z","caller":"etcdserver/server.go:1007","msg":"applying snapshot","current-snapshot-index":0,"current-applied-index":0,"incoming-leader-snapshot-index":596,"incoming-leader-snapshot-term":3} {"level":"info","ts":"2026-04-17T00:55:56.100767Z","caller":"etcdserver/raft.go:276","msg":"applied incoming Raft snapshot","snapshot-index":596} {"level":"info","ts":"2026-04-17T00:55:56.101069Z","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: 0xc000078498}"} {"level":"info","ts":"2026-04-17T00:55:56.101737Z","logger":"bbolt","caller":"bbolt@v1.4.2/db.go:321","msg":"Opening bbolt db (/var/lib/etcd/member/snap/db) successfully"} {"level":"info","ts":"2026-04-17T00:55:56.101779Z","caller":"storage/backend.go:80","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"786.397µs"} {"level":"info","ts":"2026-04-17T00:55:56.101896Z","caller":"etcdserver/server.go:1055","msg":"restoring lease store"} {"level":"info","ts":"2026-04-17T00:55:56.103506Z","caller":"etcdserver/server.go:1059","msg":"restored lease store"} {"level":"info","ts":"2026-04-17T00:55:56.103530Z","caller":"etcdserver/server.go:1062","msg":"restoring mvcc store"} {"level":"info","ts":"2026-04-17T00:55:56.108658Z","caller":"etcdserver/server.go:522","msg":"initialized peer connections; fast-forwarding election ticks","local-member-id":"46e1a43e5ead920f","forward-ticks":8,"forward-duration":"800ms","election-ticks":10,"election-timeout":"1s","active-remote-members":2} {"level":"info","ts":"2026-04-17T00:55:56.109672Z","caller":"mvcc/kvstore.go:408","msg":"kvstore restored","current-rev":558} {"level":"info","ts":"2026-04-17T00:55:56.109729Z","caller":"etcdserver/server.go:1070","msg":"restored mvcc store","consistent-index":596} {"level":"info","ts":"2026-04-17T00:55:56.109866Z","caller":"etcdserver/server.go:1090","msg":"restoring alarm store"} {"level":"info","ts":"2026-04-17T00:55:56.109906Z","caller":"etcdserver/server.go:1078","msg":"closing old backend file"} {"level":"info","ts":"2026-04-17T00:55:56.111203Z","caller":"etcdserver/server.go:1096","msg":"restored alarm store"} {"level":"info","ts":"2026-04-17T00:55:56.111235Z","caller":"etcdserver/server.go:1099","msg":"restoring auth store"} {"level":"info","ts":"2026-04-17T00:55:56.111271Z","caller":"etcdserver/server.go:1103","msg":"restored auth store"} {"level":"info","ts":"2026-04-17T00:55:56.111348Z","caller":"etcdserver/server.go:1106","msg":"restoring v2 store"} {"level":"info","ts":"2026-04-17T00:55:56.111751Z","caller":"etcdserver/server.go:1080","msg":"closed old backend file"} {"level":"info","ts":"2026-04-17T00:55:56.111954Z","caller":"etcdserver/server.go:1115","msg":"restored v2 store"} {"level":"info","ts":"2026-04-17T00:55:56.111988Z","caller":"etcdserver/server.go:1119","msg":"restoring cluster configuration"} {"level":"info","ts":"2026-04-17T00:55:56.112096Z","caller":"api/capability.go:76","msg":"enabled capabilities for version","cluster-version":"3.6"} {"level":"info","ts":"2026-04-17T00:55:56.112115Z","caller":"membership/cluster.go:297","msg":"recovered/added member from store","cluster-id":"aaca35652fabf9b2","local-member-id":"46e1a43e5ead920f","recovered-remote-peer-id":"46e1a43e5ead920f","recovered-remote-peer-urls":["https://199.204.45.22:2380"],"recovered-remote-peer-is-learner":true} {"level":"info","ts":"2026-04-17T00:55:56.112150Z","caller":"membership/cluster.go:297","msg":"recovered/added member from store","cluster-id":"aaca35652fabf9b2","local-member-id":"46e1a43e5ead920f","recovered-remote-peer-id":"d71a1778b31d4ce","recovered-remote-peer-urls":["https://199.204.45.109:2380"],"recovered-remote-peer-is-learner":false} {"level":"info","ts":"2026-04-17T00:55:56.112163Z","caller":"membership/cluster.go:297","msg":"recovered/added member from store","cluster-id":"aaca35652fabf9b2","local-member-id":"46e1a43e5ead920f","recovered-remote-peer-id":"e87e20b7f6d3ceaf","recovered-remote-peer-urls":["https://199.204.45.77:2380"],"recovered-remote-peer-is-learner":false} {"level":"info","ts":"2026-04-17T00:55:56.112172Z","caller":"membership/cluster.go:307","msg":"set cluster version from store","cluster-version":"3.6"} {"level":"info","ts":"2026-04-17T00:55:56.112188Z","caller":"etcdserver/server.go:1123","msg":"restored cluster configuration"} {"level":"info","ts":"2026-04-17T00:55:56.112196Z","caller":"etcdserver/server.go:1124","msg":"removing old peers from network"} {"level":"info","ts":"2026-04-17T00:55:56.112233Z","caller":"rafthttp/peer.go:316","msg":"stopping remote peer","remote-peer-id":"d71a1778b31d4ce"} {"level":"warn","ts":"2026-04-17T00:55:56.112316Z","caller":"rafthttp/stream.go:285","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.112341Z","caller":"rafthttp/stream.go:293","msg":"stopped TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","remote-peer-id":"d71a1778b31d4ce"} {"level":"warn","ts":"2026-04-17T00:55:56.112489Z","caller":"rafthttp/stream.go:285","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream Message","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.112512Z","caller":"rafthttp/stream.go:293","msg":"stopped TCP streaming connection with remote peer","stream-writer-type":"stream Message","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.112641Z","caller":"rafthttp/pipeline.go:85","msg":"stopped HTTP pipelining with remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"warn","ts":"2026-04-17T00:55:56.112888Z","caller":"rafthttp/stream.go:420","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce","error":"context canceled"} {"level":"warn","ts":"2026-04-17T00:55:56.112936Z","caller":"rafthttp/peer_status.go:66","msg":"peer became inactive (message send to peer failed)","peer-id":"d71a1778b31d4ce","error":"failed to read d71a1778b31d4ce on stream MsgApp v2 (context canceled)"} {"level":"info","ts":"2026-04-17T00:55:56.113066Z","caller":"rafthttp/stream.go:441","msg":"stopped stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"warn","ts":"2026-04-17T00:55:56.113247Z","caller":"rafthttp/stream.go:420","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce","error":"context canceled"} {"level":"info","ts":"2026-04-17T00:55:56.113347Z","caller":"rafthttp/stream.go:441","msg":"stopped stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.113403Z","caller":"rafthttp/peer.go:321","msg":"stopped remote peer","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.113426Z","caller":"rafthttp/transport.go:354","msg":"removed remote peer","local-member-id":"46e1a43e5ead920f","removed-remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.113436Z","caller":"rafthttp/peer.go:316","msg":"stopping remote peer","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"warn","ts":"2026-04-17T00:55:56.113558Z","caller":"rafthttp/stream.go:285","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.113583Z","caller":"rafthttp/stream.go:293","msg":"stopped TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"warn","ts":"2026-04-17T00:55:56.113784Z","caller":"rafthttp/stream.go:285","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream Message","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.113838Z","caller":"rafthttp/stream.go:293","msg":"stopped TCP streaming connection with remote peer","stream-writer-type":"stream Message","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.113926Z","caller":"rafthttp/pipeline.go:85","msg":"stopped HTTP pipelining with remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"warn","ts":"2026-04-17T00:55:56.114222Z","caller":"rafthttp/stream.go:420","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf","error":"context canceled"} {"level":"warn","ts":"2026-04-17T00:55:56.114247Z","caller":"rafthttp/peer_status.go:66","msg":"peer became inactive (message send to peer failed)","peer-id":"e87e20b7f6d3ceaf","error":"failed to read e87e20b7f6d3ceaf on stream MsgApp v2 (context canceled)"} {"level":"info","ts":"2026-04-17T00:55:56.114380Z","caller":"rafthttp/stream.go:441","msg":"stopped stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"warn","ts":"2026-04-17T00:55:56.114611Z","caller":"rafthttp/stream.go:420","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf","error":"context canceled"} {"level":"info","ts":"2026-04-17T00:55:56.114644Z","caller":"rafthttp/stream.go:441","msg":"stopped stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.114653Z","caller":"rafthttp/peer.go:321","msg":"stopped remote peer","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.114670Z","caller":"rafthttp/transport.go:354","msg":"removed remote peer","local-member-id":"46e1a43e5ead920f","removed-remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.114678Z","caller":"etcdserver/server.go:1129","msg":"removed old peers from network"} {"level":"info","ts":"2026-04-17T00:55:56.114687Z","caller":"etcdserver/server.go:1130","msg":"adding peers from new cluster configuration"} {"level":"info","ts":"2026-04-17T00:55:56.114707Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.114725Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.114847Z","caller":"rafthttp/stream.go:168","msg":"started stream writer with remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.114885Z","caller":"rafthttp/stream.go:168","msg":"started stream writer with remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.114863Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.114930Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce","remote-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-17T00:55:56.114898Z","caller":"rafthttp/stream.go:394","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.114944Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.114969Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.115092Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.115108Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf","remote-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-17T00:55:56.115118Z","caller":"etcdserver/server.go:1139","msg":"added peers from new cluster configuration"} {"level":"info","ts":"2026-04-17T00:55:56.115131Z","caller":"etcdserver/server.go:1015","msg":"applied snapshot","current-snapshot-index":596,"current-applied-index":596,"incoming-leader-snapshot-index":596,"incoming-leader-snapshot-term":3} {"level":"info","ts":"2026-04-17T00:55:56.115163Z","caller":"rafthttp/stream.go:394","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.115281Z","caller":"etcdserver/server.go:1804","msg":"published local member to cluster through raft","local-member-id":"46e1a43e5ead920f","local-member-attributes":"{Name:controller-3 ClientURLs:[https://199.204.45.22:2379]}","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T00:55:56.115456Z","caller":"rafthttp/stream.go:168","msg":"started stream writer with remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.115476Z","caller":"rafthttp/stream.go:168","msg":"started stream writer with remote peer","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.115495Z","caller":"rafthttp/stream.go:394","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.115519Z","caller":"rafthttp/stream.go:394","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.116473Z","caller":"embed/serve.go:138","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T00:55:56.116573Z","caller":"embed/serve.go:138","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T00:55:56.117526Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T00:55:56.117540Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T00:55:56.118620Z","caller":"v3rpc/health.go:63","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"warn","ts":"2026-04-17T00:55:56.119449Z","caller":"v3rpc/grpc.go:52","msg":"etcdserver: failed to register grpc metrics","error":"duplicate metrics collector registration attempted"} {"level":"info","ts":"2026-04-17T00:55:56.121433Z","caller":"v3rpc/health.go:63","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-04-17T00:55:56.125255Z","caller":"embed/serve.go:283","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T00:55:56.125567Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.125644Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.130822Z","caller":"embed/serve.go:283","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-04-17T00:55:56.131732Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"46e1a43e5ead920f","to":"e87e20b7f6d3ceaf","stream-type":"stream Message"} {"level":"info","ts":"2026-04-17T00:55:56.131917Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.131982Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.132430Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.133081Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"46e1a43e5ead920f","to":"d71a1778b31d4ce","stream-type":"stream MsgApp v2"} {"level":"info","ts":"2026-04-17T00:55:56.133117Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.134224Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.134914Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"46e1a43e5ead920f","to":"d71a1778b31d4ce","stream-type":"stream Message"} {"level":"info","ts":"2026-04-17T00:55:56.134978Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"46e1a43e5ead920f","remote-peer-id":"d71a1778b31d4ce"} {"level":"info","ts":"2026-04-17T00:55:56.135826Z","caller":"rafthttp/stream.go:248","msg":"set message encoder","from":"46e1a43e5ead920f","to":"e87e20b7f6d3ceaf","stream-type":"stream MsgApp v2"} {"level":"info","ts":"2026-04-17T00:55:56.136301Z","caller":"rafthttp/stream.go:273","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.136517Z","caller":"rafthttp/stream.go:411","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"46e1a43e5ead920f","remote-peer-id":"e87e20b7f6d3ceaf"} {"level":"info","ts":"2026-04-17T00:55:56.347571Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"46e1a43e5ead920f switched to configuration voters=(968732929669846222 5107544040198214159 16752863638357003951)"} {"level":"info","ts":"2026-04-17T00:55:56.347773Z","caller":"membership/cluster.go:550","msg":"promote member","cluster-id":"aaca35652fabf9b2","local-member-id":"46e1a43e5ead920f","promoted-member-id":"46e1a43e5ead920f"} {"level":"warn","ts":"2026-04-17T00:55:56.369645Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39354","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.369736Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39370","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.388330Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39390","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.408720Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39404","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.434787Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39442","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.452429Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39454","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.466016Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39468","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.478478Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39482","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.513574Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39510","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.521476Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39526","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.527595Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39550","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.534636Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39570","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.541662Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39586","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.549340Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39612","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.559473Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39628","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.568010Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39634","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.584267Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39674","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.593336Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39696","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.603461Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39724","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.629318Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39752","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.643808Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39768","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.654658Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39796","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.664050Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39818","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.674769Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39834","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.685324Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39860","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.704932Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39880","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.713584Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39902","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.721730Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39918","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.733099Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39936","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.761512Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39966","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.771049Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39990","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.778770Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:39992","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.787668Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40016","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.796394Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40054","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.808233Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40070","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.819046Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40104","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.834387Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40116","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.844071Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40136","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.854615Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40142","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.864788Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40168","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.874228Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40190","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.887384Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40202","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.894362Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40204","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.905921Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40234","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.915271Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40242","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.925727Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40274","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.936245Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40278","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.947498Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40286","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.957258Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40302","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.967564Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40332","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.977715Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40338","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:56.990678Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40344","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:57.001286Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40362","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:57.030023Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40386","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:57.036495Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40390","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:57.051136Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40412","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:57.060298Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40430","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:57.116054Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40470","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:55:58.904092Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"213.355033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:55:58.952853Z","caller":"traceutil/trace.go:172","msg":"trace[1128691307] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:606; }","duration":"305.214035ms","start":"2026-04-17T00:55:58.647617Z","end":"2026-04-17T00:55:58.952831Z","steps":["trace[1128691307] 'read index received' (duration: 305.205595ms)","trace[1128691307] 'applied index is now lower than readState.Index' (duration: 6.95µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:55:58.953094Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"305.42959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node\" limit:1 ","response":"range_response_count:1 size:1605"} {"level":"info","ts":"2026-04-17T00:55:58.953132Z","caller":"traceutil/trace.go:172","msg":"trace[676552186] range","detail":"{range_begin:/registry/clusterroles/system:node; range_end:; response_count:1; response_revision:566; }","duration":"305.512472ms","start":"2026-04-17T00:55:58.647612Z","end":"2026-04-17T00:55:58.953124Z","steps":["trace[676552186] 'agreement among raft nodes before linearized reading' (duration: 305.323678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:55:58.953157Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:55:58.647588Z","time spent":"305.563182ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":1628,"request content":"key:\"/registry/clusterroles/system:node\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:55:58.954453Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"231.752814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:55:58.954492Z","caller":"traceutil/trace.go:172","msg":"trace[1079242665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"231.794075ms","start":"2026-04-17T00:55:58.722690Z","end":"2026-04-17T00:55:58.954484Z","steps":["trace[1079242665] 'agreement among raft nodes before linearized reading' (duration: 231.735414ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:00.877596Z","caller":"traceutil/trace.go:172","msg":"trace[469347557] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:621; }","duration":"130.341769ms","start":"2026-04-17T00:56:00.747214Z","end":"2026-04-17T00:56:00.877556Z","steps":["trace[469347557] 'read index received' (duration: 130.331099ms)","trace[469347557] 'applied index is now lower than readState.Index' (duration: 8.62µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:00.878297Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"131.048094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/controller-3\" limit:1 ","response":"range_response_count:1 size:2910"} {"level":"info","ts":"2026-04-17T00:56:00.878362Z","caller":"traceutil/trace.go:172","msg":"trace[1501297178] range","detail":"{range_begin:/registry/minions/controller-3; range_end:; response_count:1; response_revision:578; }","duration":"131.134986ms","start":"2026-04-17T00:56:00.747210Z","end":"2026-04-17T00:56:00.878345Z","steps":["trace[1501297178] 'agreement among raft nodes before linearized reading' (duration: 130.460941ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:02.783595Z","caller":"traceutil/trace.go:172","msg":"trace[1469318215] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"146.20771ms","start":"2026-04-17T00:56:02.637363Z","end":"2026-04-17T00:56:02.783571Z","steps":["trace[1469318215] 'process raft request' (duration: 145.980405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:04.389572Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569526,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:56:04.582258Z","caller":"traceutil/trace.go:172","msg":"trace[1251200414] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"834.897797ms","start":"2026-04-17T00:56:03.747303Z","end":"2026-04-17T00:56:04.582200Z","steps":["trace[1251200414] 'process raft request' (duration: 834.54881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:04.582916Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:03.747276Z","time spent":"835.290505ms","remote":"127.0.0.1:39664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7983,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:56:04.889710Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569526,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:56:05.211009Z","caller":"traceutil/trace.go:172","msg":"trace[1230152800] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"1.322266333s","start":"2026-04-17T00:56:03.888720Z","end":"2026-04-17T00:56:05.210986Z","steps":["trace[1230152800] 'read index received' (duration: 1.239548503s)","trace[1230152800] 'applied index is now lower than readState.Index' (duration: 82.7164ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:05.211093Z","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":10524804134374569528,"received-request-id":10524804134374569526} {"level":"warn","ts":"2026-04-17T00:56:05.211471Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.322734702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" limit:1 ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-04-17T00:56:05.211517Z","caller":"traceutil/trace.go:172","msg":"trace[293963533] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:603; }","duration":"1.322779573s","start":"2026-04-17T00:56:03.888713Z","end":"2026-04-17T00:56:05.211493Z","steps":["trace[293963533] 'agreement among raft nodes before linearized reading' (duration: 1.322452886s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:05.211550Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:03.888692Z","time spent":"1.322849576s","remote":"127.0.0.1:39710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:05.213556Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"894.871512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T00:56:05.213591Z","caller":"traceutil/trace.go:172","msg":"trace[1079316991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"894.909232ms","start":"2026-04-17T00:56:04.318674Z","end":"2026-04-17T00:56:05.213583Z","steps":["trace[1079316991] 'agreement among raft nodes before linearized reading' (duration: 894.80711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:05.213610Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:04.318651Z","time spent":"894.954192ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:05.213667Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"626.924088ms","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:5698"} {"level":"info","ts":"2026-04-17T00:56:05.213786Z","caller":"traceutil/trace.go:172","msg":"trace[1469262620] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-controller-3; range_end:; response_count:1; response_revision:603; }","duration":"627.035971ms","start":"2026-04-17T00:56:04.586697Z","end":"2026-04-17T00:56:05.213733Z","steps":["trace[1469262620] 'agreement among raft nodes before linearized reading' (duration: 626.748506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:05.213832Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:04.586681Z","time spent":"627.138603ms","remote":"127.0.0.1:39664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":5721,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-controller-3\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:05.213570Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.101916308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-17T00:56:05.213967Z","caller":"traceutil/trace.go:172","msg":"trace[1891379992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"1.102316435s","start":"2026-04-17T00:56:04.111641Z","end":"2026-04-17T00:56:05.213958Z","steps":["trace[1891379992] 'agreement among raft nodes before linearized reading' (duration: 1.101776354s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:05.214358Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:04.111607Z","time spent":"1.102380057s","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:10.434970Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"630.651328ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:10.435083Z","caller":"traceutil/trace.go:172","msg":"trace[1429479815] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:631; }","duration":"630.77942ms","start":"2026-04-17T00:56:09.804280Z","end":"2026-04-17T00:56:10.435060Z","steps":["trace[1429479815] 'range keys from in-memory index tree' (duration: 630.586427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:10.435103Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"823.43168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:3 size:387"} {"level":"info","ts":"2026-04-17T00:56:10.435201Z","caller":"traceutil/trace.go:172","msg":"trace[1200701125] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:3; response_revision:631; }","duration":"823.491502ms","start":"2026-04-17T00:56:09.611653Z","end":"2026-04-17T00:56:10.435145Z","steps":["trace[1200701125] 'range keys from in-memory index tree' (duration: 822.225476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:10.435288Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:09.611642Z","time spent":"823.631304ms","remote":"127.0.0.1:39408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":410,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-04-17T00:56:15.586740Z","caller":"traceutil/trace.go:172","msg":"trace[441713648] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:701; }","duration":"366.48098ms","start":"2026-04-17T00:56:15.220241Z","end":"2026-04-17T00:56:15.586722Z","steps":["trace[441713648] 'read index received' (duration: 348.16485ms)","trace[441713648] 'applied index is now lower than readState.Index' (duration: 18.31546ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:15.586939Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"366.687844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-17T00:56:15.586993Z","caller":"traceutil/trace.go:172","msg":"trace[1728113974] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"366.751136ms","start":"2026-04-17T00:56:15.220228Z","end":"2026-04-17T00:56:15.586979Z","steps":["trace[1728113974] 'agreement among raft nodes before linearized reading' (duration: 366.607263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:15.587045Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:15.220206Z","time spent":"366.825737ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:15.588926Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"287.938175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T00:56:15.589012Z","caller":"traceutil/trace.go:172","msg":"trace[1041489421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"288.025586ms","start":"2026-04-17T00:56:15.300969Z","end":"2026-04-17T00:56:15.588995Z","steps":["trace[1041489421] 'agreement among raft nodes before linearized reading' (duration: 287.849493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:15.589418Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"102.100319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:15.589500Z","caller":"traceutil/trace.go:172","msg":"trace[459822982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"102.209141ms","start":"2026-04-17T00:56:15.487255Z","end":"2026-04-17T00:56:15.589464Z","steps":["trace[459822982] 'agreement among raft nodes before linearized reading' (duration: 102.074648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:15.589849Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"177.890121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:483"} {"level":"info","ts":"2026-04-17T00:56:15.589936Z","caller":"traceutil/trace.go:172","msg":"trace[1610792407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"177.950862ms","start":"2026-04-17T00:56:15.411941Z","end":"2026-04-17T00:56:15.589892Z","steps":["trace[1610792407] 'agreement among raft nodes before linearized reading' (duration: 177.807349ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:18.538982Z","caller":"traceutil/trace.go:172","msg":"trace[1695666409] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"100.244465ms","start":"2026-04-17T00:56:18.438724Z","end":"2026-04-17T00:56:18.538968Z","steps":["trace[1695666409] 'process raft request' (duration: 100.113032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:21.373337Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:56:21.873473Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:56:22.136293Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"331.780896ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:22.136372Z","caller":"traceutil/trace.go:172","msg":"trace[205580760] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:700; }","duration":"331.868757ms","start":"2026-04-17T00:56:21.804487Z","end":"2026-04-17T00:56:22.136355Z","steps":["trace[205580760] 'range keys from in-memory index tree' (duration: 331.745175ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:22.143522Z","caller":"traceutil/trace.go:172","msg":"trace[149919357] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:758; }","duration":"1.271038832s","start":"2026-04-17T00:56:20.872464Z","end":"2026-04-17T00:56:22.143503Z","steps":["trace[149919357] 'read index received' (duration: 1.270998811s)","trace[149919357] 'applied index is now lower than readState.Index' (duration: 39.411µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:56:22.143636Z","caller":"traceutil/trace.go:172","msg":"trace[267959380] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"1.307043426s","start":"2026-04-17T00:56:20.836582Z","end":"2026-04-17T00:56:22.143626Z","steps":["trace[267959380] 'process raft request' (duration: 1.306914684s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:22.143633Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.271182434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-17T00:56:22.143682Z","caller":"traceutil/trace.go:172","msg":"trace[609957875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:700; }","duration":"1.271240755s","start":"2026-04-17T00:56:20.872431Z","end":"2026-04-17T00:56:22.143672Z","steps":["trace[609957875] 'agreement among raft nodes before linearized reading' (duration: 1.271130143s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:22.143705Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:20.872406Z","time spent":"1.271293317s","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:22.143716Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:20.836555Z","time spent":"1.307114317s","remote":"127.0.0.1:39844","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-04-17T00:56:22.145415Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.043401403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-04-17T00:56:22.145515Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"658.111039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:22.145552Z","caller":"traceutil/trace.go:172","msg":"trace[363838615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:701; }","duration":"658.14937ms","start":"2026-04-17T00:56:21.487395Z","end":"2026-04-17T00:56:22.145544Z","steps":["trace[363838615] 'agreement among raft nodes before linearized reading' (duration: 658.085678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:22.145572Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:21.487370Z","time spent":"658.19794ms","remote":"127.0.0.1:39364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T00:56:22.145569Z","caller":"traceutil/trace.go:172","msg":"trace[531631345] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:701; }","duration":"1.043529745s","start":"2026-04-17T00:56:21.101965Z","end":"2026-04-17T00:56:22.145495Z","steps":["trace[531631345] 'agreement among raft nodes before linearized reading' (duration: 1.043331671s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:22.145805Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:21.101939Z","time spent":"1.043853042s","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:24.751919Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"264.370286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:24.752023Z","caller":"traceutil/trace.go:172","msg":"trace[561629536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:707; }","duration":"264.479818ms","start":"2026-04-17T00:56:24.487517Z","end":"2026-04-17T00:56:24.751997Z","steps":["trace[561629536] 'range keys from in-memory index tree' (duration: 262.766274ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:29.766439Z","caller":"traceutil/trace.go:172","msg":"trace[1599187706] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"336.469024ms","start":"2026-04-17T00:56:29.429907Z","end":"2026-04-17T00:56:29.766376Z","steps":["trace[1599187706] 'process raft request' (duration: 251.717943ms)","trace[1599187706] 'compare' (duration: 84.549507ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:29.766658Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:29.429898Z","time spent":"336.662407ms","remote":"127.0.0.1:39408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:56:30.842559Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"353.530157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:30.842656Z","caller":"traceutil/trace.go:172","msg":"trace[1593827996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"353.641229ms","start":"2026-04-17T00:56:30.488995Z","end":"2026-04-17T00:56:30.842636Z","steps":["trace[1593827996] 'range keys from in-memory index tree' (duration: 352.162929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:30.842705Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:30.488978Z","time spent":"353.71555ms","remote":"127.0.0.1:39364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:56:30.944982Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"159.176228ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T00:56:30.945531Z","caller":"traceutil/trace.go:172","msg":"trace[533950496] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"197.077601ms","start":"2026-04-17T00:56:30.748374Z","end":"2026-04-17T00:56:30.945452Z","steps":["trace[533950496] 'read index received' (duration: 38.650228ms)","trace[533950496] 'applied index is now lower than readState.Index' (duration: 158.424623ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:30.945714Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"197.311955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:483"} {"level":"info","ts":"2026-04-17T00:56:30.945808Z","caller":"traceutil/trace.go:172","msg":"trace[878865605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:727; }","duration":"197.421937ms","start":"2026-04-17T00:56:30.748369Z","end":"2026-04-17T00:56:30.945791Z","steps":["trace[878865605] 'agreement among raft nodes before linearized reading' (duration: 197.238874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:30.947430Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"104.918349ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:30.947474Z","caller":"traceutil/trace.go:172","msg":"trace[2018894879] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:727; }","duration":"104.98018ms","start":"2026-04-17T00:56:30.842481Z","end":"2026-04-17T00:56:30.947461Z","steps":["trace[2018894879] 'agreement among raft nodes before linearized reading' (duration: 104.901589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:30.947426Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"102.893849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:30.947575Z","caller":"traceutil/trace.go:172","msg":"trace[482792862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"103.044191ms","start":"2026-04-17T00:56:30.844516Z","end":"2026-04-17T00:56:30.947560Z","steps":["trace[482792862] 'agreement among raft nodes before linearized reading' (duration: 102.864769ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:31.698984Z","caller":"traceutil/trace.go:172","msg":"trace[2050681811] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"201.762259ms","start":"2026-04-17T00:56:31.497203Z","end":"2026-04-17T00:56:31.698965Z","steps":["trace[2050681811] 'process raft request' (duration: 201.623605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:32.153510Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"220.437165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-17T00:56:32.153618Z","caller":"traceutil/trace.go:172","msg":"trace[1509285806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:730; }","duration":"220.553138ms","start":"2026-04-17T00:56:31.933045Z","end":"2026-04-17T00:56:32.153598Z","steps":["trace[1509285806] 'range keys from in-memory index tree' (duration: 217.954857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:32.153998Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"366.597729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-17T00:56:32.154069Z","caller":"traceutil/trace.go:172","msg":"trace[1644922233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:730; }","duration":"366.733541ms","start":"2026-04-17T00:56:31.787318Z","end":"2026-04-17T00:56:32.154052Z","steps":["trace[1644922233] 'range keys from in-memory index tree' (duration: 364.243993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:32.154117Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:31.787269Z","time spent":"366.832443ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:32.154480Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"349.994688ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:32.154552Z","caller":"traceutil/trace.go:172","msg":"trace[208111550] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:730; }","duration":"350.07619ms","start":"2026-04-17T00:56:31.804460Z","end":"2026-04-17T00:56:32.154536Z","steps":["trace[208111550] 'range keys from in-memory index tree' (duration: 349.948536ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:36.616664Z","caller":"traceutil/trace.go:172","msg":"trace[1436040573] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:856; }","duration":"382.140356ms","start":"2026-04-17T00:56:36.234502Z","end":"2026-04-17T00:56:36.616643Z","steps":["trace[1436040573] 'read index received' (duration: 382.135446ms)","trace[1436040573] 'applied index is now lower than readState.Index' (duration: 3.95µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:36.616951Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"382.427862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-17T00:56:36.616976Z","caller":"traceutil/trace.go:172","msg":"trace[1455386391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"382.471612ms","start":"2026-04-17T00:56:36.234498Z","end":"2026-04-17T00:56:36.616970Z","steps":["trace[1455386391] 'agreement among raft nodes before linearized reading' (duration: 382.239998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:36.617001Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:36.234478Z","time spent":"382.517722ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:36.618922Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"132.266662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:56:36.618989Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"258.037171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-17T00:56:36.619004Z","caller":"traceutil/trace.go:172","msg":"trace[626324990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"132.356434ms","start":"2026-04-17T00:56:36.486631Z","end":"2026-04-17T00:56:36.618988Z","steps":["trace[626324990] 'agreement among raft nodes before linearized reading' (duration: 132.225972ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:36.619047Z","caller":"traceutil/trace.go:172","msg":"trace[838704275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"258.114722ms","start":"2026-04-17T00:56:36.360920Z","end":"2026-04-17T00:56:36.619035Z","steps":["trace[838704275] 'agreement among raft nodes before linearized reading' (duration: 257.932509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:37.890733Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"172.357542ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:37.890805Z","caller":"traceutil/trace.go:172","msg":"trace[487802265] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:789; }","duration":"172.435603ms","start":"2026-04-17T00:56:37.718354Z","end":"2026-04-17T00:56:37.890790Z","steps":["trace[487802265] 'range keys from in-memory index tree' (duration: 172.313442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:37.891093Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.086762266s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:37.892088Z","caller":"traceutil/trace.go:172","msg":"trace[220945518] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:789; }","duration":"1.087750535s","start":"2026-04-17T00:56:36.804323Z","end":"2026-04-17T00:56:37.892073Z","steps":["trace[220945518] 'range keys from in-memory index tree' (duration: 1.085151195s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:37.900130Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569688,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:56:38.118014Z","caller":"traceutil/trace.go:172","msg":"trace[542129003] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:859; }","duration":"718.573199ms","start":"2026-04-17T00:56:37.399412Z","end":"2026-04-17T00:56:38.117985Z","steps":["trace[542129003] 'read index received' (duration: 718.567959ms)","trace[542129003] 'applied index is now lower than readState.Index' (duration: 4.15µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:38.118736Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"719.303043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:483"} {"level":"info","ts":"2026-04-17T00:56:38.118943Z","caller":"traceutil/trace.go:172","msg":"trace[879647601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:791; }","duration":"719.515127ms","start":"2026-04-17T00:56:37.399409Z","end":"2026-04-17T00:56:38.118924Z","steps":["trace[879647601] 'agreement among raft nodes before linearized reading' (duration: 718.708791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:38.119109Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:37.399392Z","time spent":"719.65185ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:38.119499Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"224.799426ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:38.119562Z","caller":"traceutil/trace.go:172","msg":"trace[1220685214] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:791; }","duration":"224.866216ms","start":"2026-04-17T00:56:37.894681Z","end":"2026-04-17T00:56:38.119547Z","steps":["trace[1220685214] 'agreement among raft nodes before linearized reading' (duration: 224.772065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:38.119451Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"632.301972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:38.119815Z","caller":"traceutil/trace.go:172","msg":"trace[1960558182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"632.66665ms","start":"2026-04-17T00:56:37.487123Z","end":"2026-04-17T00:56:38.119789Z","steps":["trace[1960558182] 'agreement among raft nodes before linearized reading' (duration: 632.261442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:38.120073Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:37.487094Z","time spent":"632.892864ms","remote":"127.0.0.1:39364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T00:56:38.943378Z","caller":"traceutil/trace.go:172","msg":"trace[290073182] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:862; }","duration":"455.932007ms","start":"2026-04-17T00:56:38.487234Z","end":"2026-04-17T00:56:38.943166Z","steps":["trace[290073182] 'read index received' (duration: 455.893836ms)","trace[290073182] 'applied index is now lower than readState.Index' (duration: 36.571µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:38.943425Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"456.172981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:38.943511Z","caller":"traceutil/trace.go:172","msg":"trace[1717374661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"456.264593ms","start":"2026-04-17T00:56:38.487229Z","end":"2026-04-17T00:56:38.943494Z","steps":["trace[1717374661] 'agreement among raft nodes before linearized reading' (duration: 456.129621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:38.943549Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:38.487209Z","time spent":"456.332776ms","remote":"127.0.0.1:39364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T00:56:39.013916Z","caller":"traceutil/trace.go:172","msg":"trace[233269564] transaction","detail":"{read_only:false; number_of_response:0; response_revision:800; }","duration":"220.334567ms","start":"2026-04-17T00:56:38.793566Z","end":"2026-04-17T00:56:39.013901Z","steps":["trace[233269564] 'process raft request' (duration: 220.312987ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:39.014234Z","caller":"traceutil/trace.go:172","msg":"trace[251708297] transaction","detail":"{read_only:false; number_of_response:1; response_revision:800; }","duration":"220.991859ms","start":"2026-04-17T00:56:38.793191Z","end":"2026-04-17T00:56:39.014183Z","steps":["trace[251708297] 'process raft request' (duration: 220.654423ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:39.046994Z","caller":"traceutil/trace.go:172","msg":"trace[1117220151] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:870; }","duration":"103.504915ms","start":"2026-04-17T00:56:38.943468Z","end":"2026-04-17T00:56:39.046973Z","steps":["trace[1117220151] 'read index received' (duration: 103.498684ms)","trace[1117220151] 'applied index is now lower than readState.Index' (duration: 5.261µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:39.047181Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"242.95268ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:39.047221Z","caller":"traceutil/trace.go:172","msg":"trace[498136878] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:800; }","duration":"243.010051ms","start":"2026-04-17T00:56:38.804201Z","end":"2026-04-17T00:56:39.047211Z","steps":["trace[498136878] 'agreement among raft nodes before linearized reading' (duration: 242.912279ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:40.939820Z","caller":"traceutil/trace.go:172","msg":"trace[1222875441] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1026; }","duration":"138.103964ms","start":"2026-04-17T00:56:40.801688Z","end":"2026-04-17T00:56:40.939792Z","steps":["trace[1222875441] 'read index received' (duration: 138.096674ms)","trace[1222875441] 'applied index is now lower than readState.Index' (duration: 5.93µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:40.940306Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"138.603984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-17T00:56:40.941301Z","caller":"traceutil/trace.go:172","msg":"trace[735714738] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:878; }","duration":"139.617082ms","start":"2026-04-17T00:56:40.801672Z","end":"2026-04-17T00:56:40.941289Z","steps":["trace[735714738] 'agreement among raft nodes before linearized reading' (duration: 138.48702ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:40.940968Z","caller":"traceutil/trace.go:172","msg":"trace[39799444] transaction","detail":"{read_only:false; number_of_response:1; response_revision:878; }","duration":"335.298692ms","start":"2026-04-17T00:56:40.605646Z","end":"2026-04-17T00:56:40.940945Z","steps":["trace[39799444] 'process raft request' (duration: 335.16423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:40.941484Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:40.605616Z","time spent":"335.807741ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":99,"response count":0,"response size":208521,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:56:42.989023Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569761,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:56:43.194002Z","caller":"traceutil/trace.go:172","msg":"trace[2105957298] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1035; }","duration":"705.2545ms","start":"2026-04-17T00:56:42.488710Z","end":"2026-04-17T00:56:43.193964Z","steps":["trace[2105957298] 'read index received' (duration: 705.2446ms)","trace[2105957298] 'applied index is now lower than readState.Index' (duration: 8.23µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:56:43.194394Z","caller":"traceutil/trace.go:172","msg":"trace[1142468038] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"549.280617ms","start":"2026-04-17T00:56:42.645077Z","end":"2026-04-17T00:56:43.194357Z","steps":["trace[1142468038] 'process raft request' (duration: 548.966961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:43.194638Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:42.645051Z","time spent":"549.397091ms","remote":"127.0.0.1:39844","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-04-17T00:56:43.194814Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"706.050886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:43.195023Z","caller":"traceutil/trace.go:172","msg":"trace[296850448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"706.2491ms","start":"2026-04-17T00:56:42.488706Z","end":"2026-04-17T00:56:43.194955Z","steps":["trace[296850448] 'agreement among raft nodes before linearized reading' (duration: 705.405954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:43.195179Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:42.488690Z","time spent":"706.472504ms","remote":"127.0.0.1:39364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:56:43.199032Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"394.379447ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:43.199102Z","caller":"traceutil/trace.go:172","msg":"trace[1516319814] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:888; }","duration":"394.454109ms","start":"2026-04-17T00:56:42.804631Z","end":"2026-04-17T00:56:43.199085Z","steps":["trace[1516319814] 'agreement among raft nodes before linearized reading' (duration: 394.342697ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:43.937777Z","caller":"traceutil/trace.go:172","msg":"trace[530012361] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1040; }","duration":"236.356976ms","start":"2026-04-17T00:56:43.701393Z","end":"2026-04-17T00:56:43.937750Z","steps":["trace[530012361] 'read index received' (duration: 236.349496ms)","trace[530012361] 'applied index is now lower than readState.Index' (duration: 6.1µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:43.938818Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"237.420385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-17T00:56:43.938879Z","caller":"traceutil/trace.go:172","msg":"trace[1489511636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:891; }","duration":"237.498257ms","start":"2026-04-17T00:56:43.701364Z","end":"2026-04-17T00:56:43.938863Z","steps":["trace[1489511636] 'agreement among raft nodes before linearized reading' (duration: 236.518978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:43.939327Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"134.441168ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:43.939423Z","caller":"traceutil/trace.go:172","msg":"trace[715575858] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:891; }","duration":"134.54867ms","start":"2026-04-17T00:56:43.804856Z","end":"2026-04-17T00:56:43.939404Z","steps":["trace[715575858] 'agreement among raft nodes before linearized reading' (duration: 134.411408ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:44.737295Z","caller":"traceutil/trace.go:172","msg":"trace[62490415] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1042; }","duration":"277.793423ms","start":"2026-04-17T00:56:44.459464Z","end":"2026-04-17T00:56:44.737257Z","steps":["trace[62490415] 'read index received' (duration: 277.786833ms)","trace[62490415] 'applied index is now lower than readState.Index' (duration: 5.4µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:44.737901Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"278.410134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:44.737999Z","caller":"traceutil/trace.go:172","msg":"trace[745186599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"278.531016ms","start":"2026-04-17T00:56:44.459450Z","end":"2026-04-17T00:56:44.737981Z","steps":["trace[745186599] 'agreement among raft nodes before linearized reading' (duration: 278.375453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:44.752441Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"265.568109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:44.752504Z","caller":"traceutil/trace.go:172","msg":"trace[630632191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"265.640669ms","start":"2026-04-17T00:56:44.486851Z","end":"2026-04-17T00:56:44.752492Z","steps":["trace[630632191] 'agreement among raft nodes before linearized reading' (duration: 255.336873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:45.814967Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569777,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:56:46.161119Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.253841319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tm2f9\" limit:1 ","response":"range_response_count:1 size:18349"} {"level":"info","ts":"2026-04-17T00:56:46.161196Z","caller":"traceutil/trace.go:172","msg":"trace[632993847] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tm2f9; range_end:; response_count:1; response_revision:897; }","duration":"1.253933951s","start":"2026-04-17T00:56:44.907246Z","end":"2026-04-17T00:56:46.161180Z","steps":["trace[632993847] 'range keys from in-memory index tree' (duration: 1.251572586s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:46.161235Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:44.907227Z","time spent":"1.253998371s","remote":"127.0.0.1:39664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":18372,"request content":"key:\"/registry/pods/kube-system/cilium-tm2f9\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:46.315942Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569777,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:56:46.334574Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"455.517449ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-17T00:56:46.816304Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569777,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:56:47.035525Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.152647573s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T00:56:47.487544Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.000377834s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-17T00:56:47.487648Z","caller":"traceutil/trace.go:172","msg":"trace[272449307] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000499937s","start":"2026-04-17T00:56:45.487131Z","end":"2026-04-17T00:56:47.487631Z","steps":["trace[272449307] 'agreement among raft nodes before linearized reading' (duration: 2.000373284s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:47.487706Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:45.487112Z","time spent":"2.000586078s","remote":"127.0.0.1:39364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:56:48.898044Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.675522366s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T00:56:49.488442Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.000365367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-17T00:56:49.488525Z","caller":"traceutil/trace.go:172","msg":"trace[1814326583] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000463718s","start":"2026-04-17T00:56:47.488042Z","end":"2026-04-17T00:56:49.488505Z","steps":["trace[1814326583] 'agreement among raft nodes before linearized reading' (duration: 2.000360846s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:49.488578Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:47.488024Z","time spent":"2.000543629s","remote":"127.0.0.1:39366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/17 00:56:49 WARNING: [core] [Server #6]grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-17T00:56:49.554128Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.835398904s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:49.554222Z","caller":"traceutil/trace.go:172","msg":"trace[292841838] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:905; }","duration":"1.835504296s","start":"2026-04-17T00:56:47.718697Z","end":"2026-04-17T00:56:49.554201Z","steps":["trace[292841838] 'range keys from in-memory index tree' (duration: 1.835347863s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:49.556444Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.334782111s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:56:49.557559Z","caller":"traceutil/trace.go:172","msg":"trace[1901735261] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1057; }","duration":"4.243556027s","start":"2026-04-17T00:56:45.313986Z","end":"2026-04-17T00:56:49.557542Z","steps":["trace[1901735261] 'read index received' (duration: 1.907163995s)","trace[1901735261] 'applied index is now lower than readState.Index' (duration: 2.336391222s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:49.557631Z","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":10524804134374569781,"received-request-id":10524804134374569777} {"level":"warn","ts":"2026-04-17T00:56:49.558147Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"4.244146578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-17T00:56:49.558193Z","caller":"traceutil/trace.go:172","msg":"trace[1617827913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:915; }","duration":"4.244201439s","start":"2026-04-17T00:56:45.313980Z","end":"2026-04-17T00:56:49.558182Z","steps":["trace[1617827913] 'agreement among raft nodes before linearized reading' (duration: 4.243682639s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:49.558228Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:45.313962Z","time spent":"4.24425594s","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 "} {"level":"info","ts":"2026-04-17T00:56:49.712201Z","caller":"traceutil/trace.go:172","msg":"trace[1432337643] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"3.536339634s","start":"2026-04-17T00:56:46.175841Z","end":"2026-04-17T00:56:49.712180Z","steps":["trace[1432337643] 'process raft request' (duration: 3.535268263s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:49.712332Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:46.175815Z","time spent":"3.536451016s","remote":"127.0.0.1:39664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18506,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:56:49.960658Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1101","msg":"46e1a43e5ead920f [logterm: 3, index: 1080, vote: 0] ignored MsgPreVote from d71a1778b31d4ce [logterm: 3, index: 1080] at term 3: lease is not expired (remaining ticks: 6)"} {"level":"warn","ts":"2026-04-17T00:56:50.058705Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569781,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:56:50.559620Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569781,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:56:51.060382Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569781,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:56:51.496328Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.000447416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-17T00:56:51.496417Z","caller":"traceutil/trace.go:172","msg":"trace[1187610536] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000561818s","start":"2026-04-17T00:56:49.495837Z","end":"2026-04-17T00:56:51.496398Z","steps":["trace[1187610536] 'agreement among raft nodes before linearized reading' (duration: 2.000441646s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:51.496471Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:49.495824Z","time spent":"2.00063782s","remote":"127.0.0.1:39364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/17 00:56:51 WARNING: [core] [Server #6]grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-17T00:56:51.560512Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569781,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:56:51.576121Z","caller":"traceutil/trace.go:172","msg":"trace[1273379426] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1080; }","duration":"2.018476576s","start":"2026-04-17T00:56:49.557612Z","end":"2026-04-17T00:56:51.576089Z","steps":["trace[1273379426] 'read index received' (duration: 2.018469376s)","trace[1273379426] 'applied index is now lower than readState.Index' (duration: 5.87µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:51.576323Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"3.958243057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-17T00:56:51.576355Z","caller":"traceutil/trace.go:172","msg":"trace[1613009374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:928; }","duration":"3.958285608s","start":"2026-04-17T00:56:47.618059Z","end":"2026-04-17T00:56:51.576344Z","steps":["trace[1613009374] 'agreement among raft nodes before linearized reading' (duration: 3.958120984s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:51.576508Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:47.618038Z","time spent":"3.95839846s","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:51.576886Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"4.219551992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-17T00:56:51.576928Z","caller":"traceutil/trace.go:172","msg":"trace[365456963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:931; }","duration":"4.219596922s","start":"2026-04-17T00:56:47.357322Z","end":"2026-04-17T00:56:51.576919Z","steps":["trace[365456963] 'agreement among raft nodes before linearized reading' (duration: 4.219516521s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:51.576955Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:47.357299Z","time spent":"4.219649054s","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-04-17T00:56:51.577464Z","caller":"traceutil/trace.go:172","msg":"trace[510471433] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"2.08546918s","start":"2026-04-17T00:56:49.491972Z","end":"2026-04-17T00:56:51.577441Z","steps":["trace[510471433] 'process raft request' (duration: 2.085344468s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:51.577587Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:49.491949Z","time spent":"2.085584792s","remote":"127.0.0.1:39476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:56:51.577877Z","caller":"traceutil/trace.go:172","msg":"trace[1332457613] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"2.479763597s","start":"2026-04-17T00:56:49.098101Z","end":"2026-04-17T00:56:51.577865Z","steps":["trace[1332457613] 'process raft request' (duration: 2.479183195s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:51.577967Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:49.098076Z","time spent":"2.479852178s","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":676,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:56:51.578577Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"5.200517687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-17T00:56:51.578553Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"4.277537335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" limit:1 ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-17T00:56:51.578622Z","caller":"traceutil/trace.go:172","msg":"trace[1840056924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:935; }","duration":"5.200564489s","start":"2026-04-17T00:56:46.378047Z","end":"2026-04-17T00:56:51.578612Z","steps":["trace[1840056924] 'agreement among raft nodes before linearized reading' (duration: 5.200450467s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:51.578645Z","caller":"traceutil/trace.go:172","msg":"trace[1387984307] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:935; }","duration":"4.277629907s","start":"2026-04-17T00:56:47.300999Z","end":"2026-04-17T00:56:51.578629Z","steps":["trace[1387984307] 'agreement among raft nodes before linearized reading' (duration: 4.277140067s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:51.578690Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:47.300975Z","time spent":"4.277700989s","remote":"127.0.0.1:39522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:51.578676Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"5.774859933s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:51.578747Z","caller":"traceutil/trace.go:172","msg":"trace[365877773] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:935; }","duration":"5.774940255s","start":"2026-04-17T00:56:45.803791Z","end":"2026-04-17T00:56:51.578731Z","steps":["trace[365877773] 'agreement among raft nodes before linearized reading' (duration: 5.774833543s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:51.578652Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:46.378028Z","time spent":"5.200616779s","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:51.576362Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.150880574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" limit:1 ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-17T00:56:51.578857Z","caller":"traceutil/trace.go:172","msg":"trace[758481754] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:928; }","duration":"2.153403652s","start":"2026-04-17T00:56:49.425444Z","end":"2026-04-17T00:56:51.578848Z","steps":["trace[758481754] 'agreement among raft nodes before linearized reading' (duration: 2.150694521s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:51.578901Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:49.425422Z","time spent":"2.153465313s","remote":"127.0.0.1:39408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:51.608736Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"111.885404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:51.608800Z","caller":"traceutil/trace.go:172","msg":"trace[1975118473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"111.953985ms","start":"2026-04-17T00:56:51.496832Z","end":"2026-04-17T00:56:51.608786Z","steps":["trace[1975118473] 'agreement among raft nodes before linearized reading' (duration: 111.846943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:51.609689Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.686445065s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tm2f9\" limit:1 ","response":"range_response_count:1 size:18521"} {"level":"info","ts":"2026-04-17T00:56:51.609782Z","caller":"traceutil/trace.go:172","msg":"trace[811386872] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tm2f9; range_end:; response_count:1; response_revision:935; }","duration":"1.686550247s","start":"2026-04-17T00:56:49.923212Z","end":"2026-04-17T00:56:51.609762Z","steps":["trace[811386872] 'agreement among raft nodes before linearized reading' (duration: 1.685476067s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:51.609848Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:49.923194Z","time spent":"1.686640759s","remote":"127.0.0.1:39664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":18544,"request content":"key:\"/registry/pods/kube-system/cilium-tm2f9\" limit:1 "} {"level":"info","ts":"2026-04-17T00:56:51.688591Z","caller":"traceutil/trace.go:172","msg":"trace[291741874] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"105.893831ms","start":"2026-04-17T00:56:51.582674Z","end":"2026-04-17T00:56:51.688568Z","steps":["trace[291741874] 'process raft request' (duration: 105.782959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:51.698757Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"118.136813ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:56:51.698840Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"112.401244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" limit:1 ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-04-17T00:56:51.698894Z","caller":"traceutil/trace.go:172","msg":"trace[1814214312] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:945; }","duration":"112.454014ms","start":"2026-04-17T00:56:51.586427Z","end":"2026-04-17T00:56:51.698881Z","steps":["trace[1814214312] 'agreement among raft nodes before linearized reading' (duration: 112.306542ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:51.698864Z","caller":"traceutil/trace.go:172","msg":"trace[559889329] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:945; }","duration":"118.246705ms","start":"2026-04-17T00:56:51.580602Z","end":"2026-04-17T00:56:51.698849Z","steps":["trace[559889329] 'agreement among raft nodes before linearized reading' (duration: 118.110443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:51.698813Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"113.74783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:51.699091Z","caller":"traceutil/trace.go:172","msg":"trace[469288394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"114.033835ms","start":"2026-04-17T00:56:51.585046Z","end":"2026-04-17T00:56:51.699080Z","steps":["trace[469288394] 'agreement among raft nodes before linearized reading' (duration: 113.715449ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:52.045233Z","caller":"traceutil/trace.go:172","msg":"trace[1963657824] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"179.476345ms","start":"2026-04-17T00:56:51.865738Z","end":"2026-04-17T00:56:52.045215Z","steps":["trace[1963657824] 'process raft request' (duration: 179.339293ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:52.045852Z","caller":"traceutil/trace.go:172","msg":"trace[2091693449] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1112; }","duration":"109.221292ms","start":"2026-04-17T00:56:51.936610Z","end":"2026-04-17T00:56:52.045831Z","steps":["trace[2091693449] 'read index received' (duration: 109.215352ms)","trace[2091693449] 'applied index is now lower than readState.Index' (duration: 4.86µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:52.046051Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"109.427356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tm2f9\" limit:1 ","response":"range_response_count:1 size:18885"} {"level":"info","ts":"2026-04-17T00:56:52.046106Z","caller":"traceutil/trace.go:172","msg":"trace[1148843793] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tm2f9; range_end:; response_count:1; response_revision:957; }","duration":"109.498637ms","start":"2026-04-17T00:56:51.936598Z","end":"2026-04-17T00:56:52.046096Z","steps":["trace[1148843793] 'agreement among raft nodes before linearized reading' (duration: 109.307324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:52.359742Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"151.457952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:56:52.359908Z","caller":"traceutil/trace.go:172","msg":"trace[503054246] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"216.282941ms","start":"2026-04-17T00:56:52.143614Z","end":"2026-04-17T00:56:52.359897Z","steps":["trace[503054246] 'process raft request' (duration: 216.219091ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:52.360153Z","caller":"traceutil/trace.go:172","msg":"trace[195348923] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"300.030361ms","start":"2026-04-17T00:56:52.060114Z","end":"2026-04-17T00:56:52.360144Z","steps":["trace[195348923] 'process raft request' (duration: 148.111959ms)","trace[195348923] 'compare' (duration: 151.370981ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:52.360773Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:52.060103Z","time spent":"300.642652ms","remote":"127.0.0.1:39664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:56:52.738623Z","caller":"traceutil/trace.go:172","msg":"trace[162475748] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1115; }","duration":"110.943174ms","start":"2026-04-17T00:56:52.627650Z","end":"2026-04-17T00:56:52.738594Z","steps":["trace[162475748] 'read index received' (duration: 110.913603ms)","trace[162475748] 'applied index is now lower than readState.Index' (duration: 27.591µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:52.738790Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"111.128068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:52.738802Z","caller":"traceutil/trace.go:172","msg":"trace[913188056] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"360.625679ms","start":"2026-04-17T00:56:52.378154Z","end":"2026-04-17T00:56:52.738779Z","steps":["trace[913188056] 'process raft request' (duration: 360.463126ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:52.738866Z","caller":"traceutil/trace.go:172","msg":"trace[2102465686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"111.214579ms","start":"2026-04-17T00:56:52.627634Z","end":"2026-04-17T00:56:52.738848Z","steps":["trace[2102465686] 'agreement among raft nodes before linearized reading' (duration: 111.072937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:52.738920Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:52.378130Z","time spent":"360.736271ms","remote":"127.0.0.1:39476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":708,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:56:53.162013Z","caller":"traceutil/trace.go:172","msg":"trace[148307195] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1118; }","duration":"422.030183ms","start":"2026-04-17T00:56:52.739962Z","end":"2026-04-17T00:56:53.161992Z","steps":["trace[148307195] 'read index received' (duration: 422.024073ms)","trace[148307195] 'applied index is now lower than readState.Index' (duration: 4.86µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:53.229240Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"489.264787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:53.229321Z","caller":"traceutil/trace.go:172","msg":"trace[1412707023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"489.355998ms","start":"2026-04-17T00:56:52.739946Z","end":"2026-04-17T00:56:53.229302Z","steps":["trace[1412707023] 'agreement among raft nodes before linearized reading' (duration: 422.161395ms)","trace[1412707023] 'range keys from in-memory index tree' (duration: 67.073461ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:53.229360Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:52.739804Z","time spent":"489.548362ms","remote":"127.0.0.1:39366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:56:53.229473Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"406.414736ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:53.229551Z","caller":"traceutil/trace.go:172","msg":"trace[859562421] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:964; }","duration":"406.501627ms","start":"2026-04-17T00:56:52.823035Z","end":"2026-04-17T00:56:53.229536Z","steps":["trace[859562421] 'agreement among raft nodes before linearized reading' (duration: 406.392875ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:53.229470Z","caller":"traceutil/trace.go:172","msg":"trace[732206100] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"280.204151ms","start":"2026-04-17T00:56:52.949229Z","end":"2026-04-17T00:56:53.229433Z","steps":["trace[732206100] 'process raft request' (duration: 212.773463ms)","trace[732206100] 'compare' (duration: 67.246115ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:53.229856Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"282.095567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tm2f9\" limit:1 ","response":"range_response_count:1 size:19140"} {"level":"info","ts":"2026-04-17T00:56:53.229939Z","caller":"traceutil/trace.go:172","msg":"trace[1148544215] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tm2f9; range_end:; response_count:1; response_revision:964; }","duration":"282.192699ms","start":"2026-04-17T00:56:52.947730Z","end":"2026-04-17T00:56:53.229922Z","steps":["trace[1148544215] 'agreement among raft nodes before linearized reading' (duration: 281.817153ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:54.092588Z","caller":"traceutil/trace.go:172","msg":"trace[1688894154] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"131.959388ms","start":"2026-04-17T00:56:53.960603Z","end":"2026-04-17T00:56:54.092562Z","steps":["trace[1688894154] 'process raft request' (duration: 131.802856ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:54.526388Z","caller":"traceutil/trace.go:172","msg":"trace[1671730976] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"551.967463ms","start":"2026-04-17T00:56:53.974407Z","end":"2026-04-17T00:56:54.526374Z","steps":["trace[1671730976] 'process raft request' (duration: 551.741079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:54.526516Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:53.974389Z","time spent":"552.075915ms","remote":"127.0.0.1:39664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19650,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:56:54.706088Z","caller":"traceutil/trace.go:172","msg":"trace[1403916378] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"608.168227ms","start":"2026-04-17T00:56:54.097903Z","end":"2026-04-17T00:56:54.706071Z","steps":["trace[1403916378] 'process raft request' (duration: 608.024605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:54.706211Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:54.097876Z","time spent":"608.279441ms","remote":"127.0.0.1:39476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":725,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:56:54.712277Z","caller":"traceutil/trace.go:172","msg":"trace[527989333] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1128; }","duration":"252.485921ms","start":"2026-04-17T00:56:54.459771Z","end":"2026-04-17T00:56:54.712257Z","steps":["trace[527989333] 'read index received' (duration: 252.480241ms)","trace[527989333] 'applied index is now lower than readState.Index' (duration: 4.88µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:54.712397Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"252.614053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:54.712447Z","caller":"traceutil/trace.go:172","msg":"trace[1634736695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"252.669794ms","start":"2026-04-17T00:56:54.459767Z","end":"2026-04-17T00:56:54.712437Z","steps":["trace[1634736695] 'agreement among raft nodes before linearized reading' (duration: 252.581433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:55.213103Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569833,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:56:55.507362Z","caller":"traceutil/trace.go:172","msg":"trace[871732635] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1128; }","duration":"795.024319ms","start":"2026-04-17T00:56:54.712322Z","end":"2026-04-17T00:56:55.507346Z","steps":["trace[871732635] 'read index received' (duration: 795.019789ms)","trace[871732635] 'applied index is now lower than readState.Index' (duration: 3.66µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:55.508234Z","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":10524804134374569834,"received-request-id":10524804134374569833} {"level":"warn","ts":"2026-04-17T00:56:55.517707Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"889.310025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:55.517747Z","caller":"traceutil/trace.go:172","msg":"trace[507077938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"889.372457ms","start":"2026-04-17T00:56:54.628363Z","end":"2026-04-17T00:56:55.517736Z","steps":["trace[507077938] 'agreement among raft nodes before linearized reading' (duration: 879.079951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:55.517779Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:54.628344Z","time spent":"889.428127ms","remote":"127.0.0.1:39366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:56:55.517840Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"713.041847ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:55.517903Z","caller":"traceutil/trace.go:172","msg":"trace[1336272736] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:975; }","duration":"713.119418ms","start":"2026-04-17T00:56:54.804771Z","end":"2026-04-17T00:56:55.517891Z","steps":["trace[1336272736] 'agreement among raft nodes before linearized reading' (duration: 713.024416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:55.518003Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"383.564089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:483"} {"level":"warn","ts":"2026-04-17T00:56:55.518039Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"390.348917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-17T00:56:55.518087Z","caller":"traceutil/trace.go:172","msg":"trace[279355597] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"808.194459ms","start":"2026-04-17T00:56:54.709882Z","end":"2026-04-17T00:56:55.518076Z","steps":["trace[279355597] 'process raft request' (duration: 807.849003ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:55.518120Z","caller":"traceutil/trace.go:172","msg":"trace[624485226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:975; }","duration":"390.435698ms","start":"2026-04-17T00:56:55.127665Z","end":"2026-04-17T00:56:55.518101Z","steps":["trace[624485226] 'agreement among raft nodes before linearized reading' (duration: 390.223844ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:55.518076Z","caller":"traceutil/trace.go:172","msg":"trace[1441217522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:975; }","duration":"383.643051ms","start":"2026-04-17T00:56:55.134422Z","end":"2026-04-17T00:56:55.518065Z","steps":["trace[1441217522] 'agreement among raft nodes before linearized reading' (duration: 383.403207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:55.518174Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:54.709861Z","time spent":"808.25877ms","remote":"127.0.0.1:39476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":724,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:56:55.518166Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:55.127634Z","time spent":"390.521091ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:55.518212Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:55.134411Z","time spent":"383.789323ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:55.518899Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"546.623026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tm2f9\" limit:1 ","response":"range_response_count:1 size:19665"} {"level":"info","ts":"2026-04-17T00:56:55.519712Z","caller":"traceutil/trace.go:172","msg":"trace[461278038] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tm2f9; range_end:; response_count:1; response_revision:975; }","duration":"546.81816ms","start":"2026-04-17T00:56:54.972239Z","end":"2026-04-17T00:56:55.519057Z","steps":["trace[461278038] 'agreement among raft nodes before linearized reading' (duration: 545.877061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:55.520326Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:54.972220Z","time spent":"548.084894ms","remote":"127.0.0.1:39664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":19688,"request content":"key:\"/registry/pods/kube-system/cilium-tm2f9\" limit:1 "} {"level":"info","ts":"2026-04-17T00:56:55.984523Z","caller":"traceutil/trace.go:172","msg":"trace[216319003] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"462.36258ms","start":"2026-04-17T00:56:55.522144Z","end":"2026-04-17T00:56:55.984507Z","steps":["trace[216319003] 'process raft request' (duration: 462.236198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:55.984633Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:55.522112Z","time spent":"462.465813ms","remote":"127.0.0.1:39476","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-04-17T00:56:56.125507Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569835,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:56:56.134436Z","caller":"traceutil/trace.go:172","msg":"trace[671348217] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1132; }","duration":"508.94848ms","start":"2026-04-17T00:56:55.625468Z","end":"2026-04-17T00:56:56.134417Z","steps":["trace[671348217] 'read index received' (duration: 508.94363ms)","trace[671348217] 'applied index is now lower than readState.Index' (duration: 3.55µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:56.134777Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"509.290496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:56.134878Z","caller":"traceutil/trace.go:172","msg":"trace[731112123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:976; }","duration":"509.360398ms","start":"2026-04-17T00:56:55.625463Z","end":"2026-04-17T00:56:56.134823Z","steps":["trace[731112123] 'agreement among raft nodes before linearized reading' (duration: 509.241116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:56.134992Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:55.625443Z","time spent":"509.47495ms","remote":"127.0.0.1:39364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T00:56:56.135242Z","caller":"traceutil/trace.go:172","msg":"trace[1828339946] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"599.028475ms","start":"2026-04-17T00:56:55.536188Z","end":"2026-04-17T00:56:56.135217Z","steps":["trace[1828339946] 'process raft request' (duration: 598.558316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:56.135380Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:55.536159Z","time spent":"599.145957ms","remote":"127.0.0.1:39664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19884,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:56:56.169666Z","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":10524804134374569839,"received-request-id":10524804134374569835} {"level":"info","ts":"2026-04-17T00:56:56.170125Z","caller":"traceutil/trace.go:172","msg":"trace[780676071] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"182.573783ms","start":"2026-04-17T00:56:55.987534Z","end":"2026-04-17T00:56:56.170108Z","steps":["trace[780676071] 'process raft request' (duration: 182.176096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:56.176232Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"371.891345ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:56.176345Z","caller":"traceutil/trace.go:172","msg":"trace[1030707721] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:978; }","duration":"372.010887ms","start":"2026-04-17T00:56:55.804318Z","end":"2026-04-17T00:56:56.176329Z","steps":["trace[1030707721] 'agreement among raft nodes before linearized reading' (duration: 371.860535ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:56.676113Z","caller":"traceutil/trace.go:172","msg":"trace[1148708338] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"305.360695ms","start":"2026-04-17T00:56:56.370731Z","end":"2026-04-17T00:56:56.676091Z","steps":["trace[1148708338] 'process raft request' (duration: 305.09801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:56.676445Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:56.370705Z","time spent":"305.503458ms","remote":"127.0.0.1:39476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:56:56.945578Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569842,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:56:56.996882Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"191.9362ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:56.997074Z","caller":"traceutil/trace.go:172","msg":"trace[118750217] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:992; }","duration":"192.137244ms","start":"2026-04-17T00:56:56.804922Z","end":"2026-04-17T00:56:56.997059Z","steps":["trace[118750217] 'range keys from in-memory index tree' (duration: 191.828028ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:57.186669Z","caller":"traceutil/trace.go:172","msg":"trace[1103717752] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1151; }","duration":"741.412188ms","start":"2026-04-17T00:56:56.445235Z","end":"2026-04-17T00:56:57.186647Z","steps":["trace[1103717752] 'read index received' (duration: 741.404988ms)","trace[1103717752] 'applied index is now lower than readState.Index' (duration: 5.94µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:57.186960Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"741.641314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-17T00:56:57.187204Z","caller":"traceutil/trace.go:172","msg":"trace[167012095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:993; }","duration":"741.95922ms","start":"2026-04-17T00:56:56.445230Z","end":"2026-04-17T00:56:57.187189Z","steps":["trace[167012095] 'agreement among raft nodes before linearized reading' (duration: 741.49554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:57.187337Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:56.445210Z","time spent":"742.111942ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:57.267684Z","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":10524804134374569846,"received-request-id":10524804134374569842} {"level":"warn","ts":"2026-04-17T00:56:57.466378Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"198.54393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:56:57.641815Z","caller":"traceutil/trace.go:172","msg":"trace[54498943] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"542.216647ms","start":"2026-04-17T00:56:57.099581Z","end":"2026-04-17T00:56:57.641798Z","steps":["trace[54498943] 'process raft request' (duration: 542.087634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:57.641939Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:57.099218Z","time spent":"542.659854ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4096,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:56:57.687446Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569846,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:56:57.858422Z","caller":"traceutil/trace.go:172","msg":"trace[1953000414] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1154; }","duration":"671.660523ms","start":"2026-04-17T00:56:57.186733Z","end":"2026-04-17T00:56:57.858394Z","steps":["trace[1953000414] 'read index received' (duration: 671.648773ms)","trace[1953000414] 'applied index is now lower than readState.Index' (duration: 9.74µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:57.858471Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"861.263125ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:56:57.858520Z","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":10524804134374569847,"received-request-id":10524804134374569846} {"level":"info","ts":"2026-04-17T00:56:57.858529Z","caller":"traceutil/trace.go:172","msg":"trace[776404141] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:996; }","duration":"861.334406ms","start":"2026-04-17T00:56:56.997177Z","end":"2026-04-17T00:56:57.858511Z","steps":["trace[776404141] 'agreement among raft nodes before linearized reading' (duration: 861.227904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:57.858558Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"949.554165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:56:57.858602Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.230668661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:57.858624Z","caller":"traceutil/trace.go:172","msg":"trace[1157181770] range","detail":"{range_begin:/registry/jobs; range_end:; response_count:0; response_revision:996; }","duration":"949.637316ms","start":"2026-04-17T00:56:56.908972Z","end":"2026-04-17T00:56:57.858609Z","steps":["trace[1157181770] 'agreement among raft nodes before linearized reading' (duration: 949.477244ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:57.858652Z","caller":"traceutil/trace.go:172","msg":"trace[399546549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"1.230720192s","start":"2026-04-17T00:56:56.627919Z","end":"2026-04-17T00:56:57.858639Z","steps":["trace[399546549] 'agreement among raft nodes before linearized reading' (duration: 1.230635601s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:57.858696Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:56.627898Z","time spent":"1.230788054s","remote":"127.0.0.1:39364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:56:57.858694Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:56.908951Z","time spent":"949.711018ms","remote":"127.0.0.1:39784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/jobs\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:57.858813Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"832.699535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T00:56:57.858877Z","caller":"traceutil/trace.go:172","msg":"trace[188482634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:996; }","duration":"832.761896ms","start":"2026-04-17T00:56:57.026099Z","end":"2026-04-17T00:56:57.858860Z","steps":["trace[188482634] 'agreement among raft nodes before linearized reading' (duration: 832.565733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:57.858919Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:57.026079Z","time spent":"832.826897ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:58.359346Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569847,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:56:58.413541Z","caller":"traceutil/trace.go:172","msg":"trace[959277552] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1156; }","duration":"555.015523ms","start":"2026-04-17T00:56:57.858497Z","end":"2026-04-17T00:56:58.413512Z","steps":["trace[959277552] 'read index received' (duration: 554.995152ms)","trace[959277552] 'applied index is now lower than readState.Index' (duration: 18.411µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:58.413817Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"818.602574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-17T00:56:58.413866Z","caller":"traceutil/trace.go:172","msg":"trace[1633036421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:999; }","duration":"818.663136ms","start":"2026-04-17T00:56:57.595184Z","end":"2026-04-17T00:56:58.413848Z","steps":["trace[1633036421] 'agreement among raft nodes before linearized reading' (duration: 818.466802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:58.413901Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:57.595161Z","time spent":"818.731356ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:58.414235Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.107455843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:58.415214Z","caller":"traceutil/trace.go:172","msg":"trace[58992344] range","detail":"{range_begin:/registry/storageclasses; range_end:; response_count:0; response_revision:1006; }","duration":"1.108502122s","start":"2026-04-17T00:56:57.306702Z","end":"2026-04-17T00:56:58.415204Z","steps":["trace[58992344] 'agreement among raft nodes before linearized reading' (duration: 1.107427821s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:58.415335Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:57.306677Z","time spent":"1.108647585s","remote":"127.0.0.1:40108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:58.914468Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569848,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:56:59.056326Z","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":10524804134374569848,"received-request-id":10524804134374569847} {"level":"info","ts":"2026-04-17T00:56:59.261998Z","caller":"traceutil/trace.go:172","msg":"trace[473164107] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1180; }","duration":"848.362819ms","start":"2026-04-17T00:56:58.413613Z","end":"2026-04-17T00:56:59.261976Z","steps":["trace[473164107] 'read index received' (duration: 848.355368ms)","trace[473164107] 'applied index is now lower than readState.Index' (duration: 6.121µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:59.262341Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.401928234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:59.262405Z","caller":"traceutil/trace.go:172","msg":"trace[2146956099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"1.401997746s","start":"2026-04-17T00:56:57.860389Z","end":"2026-04-17T00:56:59.262387Z","steps":["trace[2146956099] 'agreement among raft nodes before linearized reading' (duration: 1.4017183s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:59.262448Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:57.860274Z","time spent":"1.402164169s","remote":"127.0.0.1:39366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:56:59.262544Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.402054927s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:59.262757Z","caller":"traceutil/trace.go:172","msg":"trace[2135038923] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1022; }","duration":"1.402238071s","start":"2026-04-17T00:56:57.860359Z","end":"2026-04-17T00:56:59.262597Z","steps":["trace[2135038923] 'agreement among raft nodes before linearized reading' (duration: 1.401766381s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:59.262834Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"892.286008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-tm2f9.18a6fef188198b56\" limit:1 ","response":"range_response_count:1 size:803"} {"level":"warn","ts":"2026-04-17T00:56:59.262530Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.119179313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:59.262953Z","caller":"traceutil/trace.go:172","msg":"trace[1072767317] range","detail":"{range_begin:/registry/rolebindings; range_end:; response_count:0; response_revision:1022; }","duration":"1.119613771s","start":"2026-04-17T00:56:58.143325Z","end":"2026-04-17T00:56:59.262939Z","steps":["trace[1072767317] 'agreement among raft nodes before linearized reading' (duration: 1.118760825s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:59.263047Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:58.143308Z","time spent":"1.119723053s","remote":"127.0.0.1:40030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":26,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings\" limit:1 "} {"level":"info","ts":"2026-04-17T00:56:59.263690Z","caller":"traceutil/trace.go:172","msg":"trace[463419350] range","detail":"{range_begin:/registry/events/kube-system/cilium-tm2f9.18a6fef188198b56; range_end:; response_count:1; response_revision:1022; }","duration":"893.151905ms","start":"2026-04-17T00:56:58.370474Z","end":"2026-04-17T00:56:59.262893Z","steps":["trace[463419350] 'agreement among raft nodes before linearized reading' (duration: 891.637426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:59.263857Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:58.370453Z","time spent":"893.385309ms","remote":"127.0.0.1:39476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":826,"request content":"key:\"/registry/events/kube-system/cilium-tm2f9.18a6fef188198b56\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:59.328330Z","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":10524804134374569849,"received-request-id":10524804134374569848} {"level":"info","ts":"2026-04-17T00:56:59.537114Z","caller":"traceutil/trace.go:172","msg":"trace[1712034245] linearizableReadLoop","detail":"{readStateIndex:1186; appliedIndex:1186; }","duration":"275.01627ms","start":"2026-04-17T00:56:59.262079Z","end":"2026-04-17T00:56:59.537095Z","steps":["trace[1712034245] 'read index received' (duration: 275.00822ms)","trace[1712034245] 'applied index is now lower than readState.Index' (duration: 7.19µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:59.558378Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"498.562289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:56:59.558417Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.011463595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-66bc5c9577-kz5s6\" limit:1 ","response":"range_response_count:1 size:4152"} {"level":"info","ts":"2026-04-17T00:56:59.558467Z","caller":"traceutil/trace.go:172","msg":"trace[1145798448] range","detail":"{range_begin:/registry/certificatesigningrequests; range_end:; response_count:0; response_revision:1028; }","duration":"498.658961ms","start":"2026-04-17T00:56:59.059784Z","end":"2026-04-17T00:56:59.558443Z","steps":["trace[1145798448] 'agreement among raft nodes before linearized reading' (duration: 477.443131ms)","trace[1145798448] 'range keys from in-memory index tree' (duration: 21.098988ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:56:59.558499Z","caller":"traceutil/trace.go:172","msg":"trace[633966225] range","detail":"{range_begin:/registry/pods/kube-system/coredns-66bc5c9577-kz5s6; range_end:; response_count:1; response_revision:1028; }","duration":"1.011555897s","start":"2026-04-17T00:56:58.546919Z","end":"2026-04-17T00:56:59.558475Z","steps":["trace[633966225] 'agreement among raft nodes before linearized reading' (duration: 990.324807ms)","trace[633966225] 'range keys from in-memory index tree' (duration: 20.972095ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:59.558517Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:59.059770Z","time spent":"498.732803ms","remote":"127.0.0.1:39808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":0,"response size":28,"request content":"key:\"/registry/certificatesigningrequests\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:59.558537Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"750.390256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:59.558592Z","caller":"traceutil/trace.go:172","msg":"trace[708306757] range","detail":"{range_begin:/registry/services/endpoints; range_end:; response_count:0; response_revision:1028; }","duration":"750.428516ms","start":"2026-04-17T00:56:58.808140Z","end":"2026-04-17T00:56:59.558569Z","steps":["trace[708306757] 'agreement among raft nodes before linearized reading' (duration: 729.093134ms)","trace[708306757] 'range keys from in-memory index tree' (duration: 21.289922ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:59.558622Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"889.849989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" limit:1 ","response":"range_response_count:1 size:179"} {"level":"warn","ts":"2026-04-17T00:56:59.558630Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:58.808108Z","time spent":"750.513698ms","remote":"127.0.0.1:39630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":32,"response count":0,"response size":28,"request content":"key:\"/registry/services/endpoints\" limit:1 "} {"level":"info","ts":"2026-04-17T00:56:59.558650Z","caller":"traceutil/trace.go:172","msg":"trace[2065189407] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:1028; }","duration":"889.874459ms","start":"2026-04-17T00:56:58.668766Z","end":"2026-04-17T00:56:59.558640Z","steps":["trace[2065189407] 'agreement among raft nodes before linearized reading' (duration: 868.504385ms)","trace[2065189407] 'range keys from in-memory index tree' (duration: 21.299502ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:59.558674Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:58.668745Z","time spent":"889.92349ms","remote":"127.0.0.1:39710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":202,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:59.558544Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:58.546893Z","time spent":"1.011641269s","remote":"127.0.0.1:39664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":4175,"request content":"key:\"/registry/pods/kube-system/coredns-66bc5c9577-kz5s6\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:59.558490Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"793.887897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:59.558987Z","caller":"traceutil/trace.go:172","msg":"trace[2050188163] range","detail":"{range_begin:/registry/roles; range_end:; response_count:0; response_revision:1028; }","duration":"794.395357ms","start":"2026-04-17T00:56:58.764578Z","end":"2026-04-17T00:56:59.558973Z","steps":["trace[2050188163] 'agreement among raft nodes before linearized reading' (duration: 772.661887ms)","trace[2050188163] 'range keys from in-memory index tree' (duration: 21.19688ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:59.559024Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:58.764562Z","time spent":"794.453287ms","remote":"127.0.0.1:40004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":19,"response count":0,"response size":28,"request content":"key:\"/registry/roles\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:56:59.559273Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"301.101473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-17T00:56:59.559311Z","caller":"traceutil/trace.go:172","msg":"trace[1691557574] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1028; }","duration":"301.138193ms","start":"2026-04-17T00:56:59.258162Z","end":"2026-04-17T00:56:59.559300Z","steps":["trace[1691557574] 'agreement among raft nodes before linearized reading' (duration: 279.045106ms)","trace[1691557574] 'range keys from in-memory index tree' (duration: 21.998265ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:59.559325Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"295.181611ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:56:59.559342Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:59.258141Z","time spent":"301.186764ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 "} {"level":"info","ts":"2026-04-17T00:56:59.559361Z","caller":"traceutil/trace.go:172","msg":"trace[575602358] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1029; }","duration":"295.219812ms","start":"2026-04-17T00:56:59.264131Z","end":"2026-04-17T00:56:59.559351Z","steps":["trace[575602358] 'agreement among raft nodes before linearized reading' (duration: 295.133771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:59.559391Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"133.217485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" limit:1 ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-17T00:56:59.559431Z","caller":"traceutil/trace.go:172","msg":"trace[1015818239] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:1029; }","duration":"133.259135ms","start":"2026-04-17T00:56:59.426161Z","end":"2026-04-17T00:56:59.559420Z","steps":["trace[1015818239] 'agreement among raft nodes before linearized reading' (duration: 133.110983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:56:59.559627Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"288.376363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:56:59.559658Z","caller":"traceutil/trace.go:172","msg":"trace[525731451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"288.406333ms","start":"2026-04-17T00:56:59.271243Z","end":"2026-04-17T00:56:59.559649Z","steps":["trace[525731451] 'agreement among raft nodes before linearized reading' (duration: 288.353363ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:56:59.559678Z","caller":"traceutil/trace.go:172","msg":"trace[178273101] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"292.5182ms","start":"2026-04-17T00:56:59.267014Z","end":"2026-04-17T00:56:59.559532Z","steps":["trace[178273101] 'process raft request' (duration: 270.25199ms)","trace[178273101] 'compare' (duration: 21.22319ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:59.559746Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"487.649483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" limit:1 ","response":"range_response_count:1 size:833"} {"level":"info","ts":"2026-04-17T00:56:59.559903Z","caller":"traceutil/trace.go:172","msg":"trace[385987479] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1028; }","duration":"487.935598ms","start":"2026-04-17T00:56:59.071956Z","end":"2026-04-17T00:56:59.559891Z","steps":["trace[385987479] 'agreement among raft nodes before linearized reading' (duration: 465.266081ms)","trace[385987479] 'range keys from in-memory index tree' (duration: 21.72205ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:56:59.559964Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:56:59.071937Z","time spent":"488.01469ms","remote":"127.0.0.1:39710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":856,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" limit:1 "} {"level":"info","ts":"2026-04-17T00:56:59.675335Z","caller":"traceutil/trace.go:172","msg":"trace[891878351] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"108.532678ms","start":"2026-04-17T00:56:59.566779Z","end":"2026-04-17T00:56:59.675312Z","steps":["trace[891878351] 'process raft request' (duration: 96.151595ms)","trace[891878351] 'compare' (duration: 12.194789ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:56:59.812503Z","caller":"traceutil/trace.go:172","msg":"trace[1234032521] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"125.444167ms","start":"2026-04-17T00:56:59.687039Z","end":"2026-04-17T00:56:59.812483Z","steps":["trace[1234032521] 'process raft request' (duration: 125.334175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:00.231215Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"287.735367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:57:00.341071Z","caller":"traceutil/trace.go:172","msg":"trace[1214151726] linearizableReadLoop","detail":"{readStateIndex:1201; appliedIndex:1201; }","duration":"233.678577ms","start":"2026-04-17T00:57:00.107365Z","end":"2026-04-17T00:57:00.341044Z","steps":["trace[1214151726] 'read index received' (duration: 233.668357ms)","trace[1214151726] 'applied index is now lower than readState.Index' (duration: 8.77µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:00.415545Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"308.169511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-17T00:57:00.415633Z","caller":"traceutil/trace.go:172","msg":"trace[13663312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1041; }","duration":"308.262233ms","start":"2026-04-17T00:57:00.107351Z","end":"2026-04-17T00:57:00.415613Z","steps":["trace[13663312] 'agreement among raft nodes before linearized reading' (duration: 233.800629ms)","trace[13663312] 'range keys from in-memory index tree' (duration: 74.263891ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:00.415675Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:00.107328Z","time spent":"308.335154ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-04-17T00:57:00.487547Z","caller":"traceutil/trace.go:172","msg":"trace[830017832] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1206; }","duration":"117.202741ms","start":"2026-04-17T00:57:00.370324Z","end":"2026-04-17T00:57:00.487526Z","steps":["trace[830017832] 'read index received' (duration: 117.197501ms)","trace[830017832] 'applied index is now lower than readState.Index' (duration: 4.08µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:00.487757Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"117.410475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-tm2f9.18a6fef188198b56\" limit:1 ","response":"range_response_count:1 size:803"} {"level":"info","ts":"2026-04-17T00:57:00.487835Z","caller":"traceutil/trace.go:172","msg":"trace[381601909] range","detail":"{range_begin:/registry/events/kube-system/cilium-tm2f9.18a6fef188198b56; range_end:; response_count:1; response_revision:1046; }","duration":"117.507447ms","start":"2026-04-17T00:57:00.370312Z","end":"2026-04-17T00:57:00.487819Z","steps":["trace[381601909] 'agreement among raft nodes before linearized reading' (duration: 117.284732ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:04.976093Z","caller":"traceutil/trace.go:172","msg":"trace[162560240] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1303; }","duration":"151.676314ms","start":"2026-04-17T00:57:04.824395Z","end":"2026-04-17T00:57:04.976071Z","steps":["trace[162560240] 'read index received' (duration: 151.668714ms)","trace[162560240] 'applied index is now lower than readState.Index' (duration: 6.25µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:04.976271Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"151.868056ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:04.976332Z","caller":"traceutil/trace.go:172","msg":"trace[1234017420] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1116; }","duration":"151.939498ms","start":"2026-04-17T00:57:04.824383Z","end":"2026-04-17T00:57:04.976322Z","steps":["trace[1234017420] 'agreement among raft nodes before linearized reading' (duration: 151.765744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:04.977601Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"116.93994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:04.977672Z","caller":"traceutil/trace.go:172","msg":"trace[1508879235] range","detail":"{range_begin:/registry/ingressclasses; range_end:; response_count:0; response_revision:1116; }","duration":"117.017782ms","start":"2026-04-17T00:57:04.860646Z","end":"2026-04-17T00:57:04.977664Z","steps":["trace[1508879235] 'agreement among raft nodes before linearized reading' (duration: 116.90693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:04.977831Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"115.013954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/servicecidrs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:04.977911Z","caller":"traceutil/trace.go:172","msg":"trace[215382888] range","detail":"{range_begin:/registry/servicecidrs; range_end:; response_count:0; response_revision:1116; }","duration":"115.108416ms","start":"2026-04-17T00:57:04.862786Z","end":"2026-04-17T00:57:04.977894Z","steps":["trace[215382888] 'agreement among raft nodes before linearized reading' (duration: 114.931442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:06.243500Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"358.245971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:57:06.244452Z","caller":"traceutil/trace.go:172","msg":"trace[454604150] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1307; }","duration":"440.572369ms","start":"2026-04-17T00:57:05.803864Z","end":"2026-04-17T00:57:06.244436Z","steps":["trace[454604150] 'read index received' (duration: 438.687984ms)","trace[454604150] 'applied index is now lower than readState.Index' (duration: 1.883475ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:06.244558Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"440.679481ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:06.244593Z","caller":"traceutil/trace.go:172","msg":"trace[2015259551] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1123; }","duration":"440.727313ms","start":"2026-04-17T00:57:05.803856Z","end":"2026-04-17T00:57:06.244583Z","steps":["trace[2015259551] 'agreement among raft nodes before linearized reading' (duration: 440.659771ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:06.388164Z","caller":"traceutil/trace.go:172","msg":"trace[1654535288] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1127; }","duration":"552.89984ms","start":"2026-04-17T00:57:05.835239Z","end":"2026-04-17T00:57:06.388138Z","steps":["trace[1654535288] 'process raft request' (duration: 537.768265ms)","trace[1654535288] 'compare' (duration: 14.89151ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:06.388288Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:05.835220Z","time spent":"553.009822ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":82,"response count":0,"response size":24280,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:57:06.422248Z","caller":"traceutil/trace.go:172","msg":"trace[1688390879] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1324; }","duration":"177.727489ms","start":"2026-04-17T00:57:06.244502Z","end":"2026-04-17T00:57:06.422229Z","steps":["trace[1688390879] 'read index received' (duration: 177.721549ms)","trace[1688390879] 'applied index is now lower than readState.Index' (duration: 4.85µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:06.422373Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"376.398022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deviceclasses\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:06.422397Z","caller":"traceutil/trace.go:172","msg":"trace[1177426431] range","detail":"{range_begin:/registry/deviceclasses; range_end:; response_count:0; response_revision:1131; }","duration":"376.438493ms","start":"2026-04-17T00:57:06.045952Z","end":"2026-04-17T00:57:06.422390Z","steps":["trace[1177426431] 'agreement among raft nodes before linearized reading' (duration: 376.366602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:06.422431Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:06.045907Z","time spent":"376.518174ms","remote":"127.0.0.1:40370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":27,"response count":0,"response size":28,"request content":"key:\"/registry/deviceclasses\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:06.455031Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:41448","server-name":"","error":"EOF"} {"level":"info","ts":"2026-04-17T00:57:06.638643Z","caller":"traceutil/trace.go:172","msg":"trace[903045524] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1329; }","duration":"216.311054ms","start":"2026-04-17T00:57:06.422297Z","end":"2026-04-17T00:57:06.638608Z","steps":["trace[903045524] 'read index received' (duration: 216.304794ms)","trace[903045524] 'applied index is now lower than readState.Index' (duration: 5.13µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:06.760240Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"390.624159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-tm2f9.18a6fef188198b56\" limit:1 ","response":"range_response_count:1 size:803"} {"level":"info","ts":"2026-04-17T00:57:06.760300Z","caller":"traceutil/trace.go:172","msg":"trace[522325207] range","detail":"{range_begin:/registry/events/kube-system/cilium-tm2f9.18a6fef188198b56; range_end:; response_count:1; response_revision:1135; }","duration":"390.68824ms","start":"2026-04-17T00:57:06.369596Z","end":"2026-04-17T00:57:06.760284Z","steps":["trace[522325207] 'agreement among raft nodes before linearized reading' (duration: 269.137737ms)","trace[522325207] 'range keys from in-memory index tree' (duration: 121.405321ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:06.760334Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:06.369579Z","time spent":"390.745901ms","remote":"127.0.0.1:39476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":826,"request content":"key:\"/registry/events/kube-system/cilium-tm2f9.18a6fef188198b56\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:06.760333Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"121.33407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-04-17T00:57:06.760567Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"503.867895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" limit:1 ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-04-17T00:57:06.761599Z","caller":"traceutil/trace.go:172","msg":"trace[275773680] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:1135; }","duration":"505.035957ms","start":"2026-04-17T00:57:06.256537Z","end":"2026-04-17T00:57:06.761573Z","steps":["trace[275773680] 'agreement among raft nodes before linearized reading' (duration: 382.21391ms)","trace[275773680] 'range keys from in-memory index tree' (duration: 121.528343ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:06.761662Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:06.256499Z","time spent":"505.144551ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" limit:1 "} {"level":"info","ts":"2026-04-17T00:57:06.777978Z","caller":"traceutil/trace.go:172","msg":"trace[867834002] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1137; }","duration":"171.250357ms","start":"2026-04-17T00:57:06.606710Z","end":"2026-04-17T00:57:06.777960Z","steps":["trace[867834002] 'process raft request' (duration: 171.033112ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:06.940620Z","caller":"traceutil/trace.go:172","msg":"trace[1329134539] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1333; }","duration":"301.881221ms","start":"2026-04-17T00:57:06.638708Z","end":"2026-04-17T00:57:06.940589Z","steps":["trace[1329134539] 'read index received' (duration: 301.670647ms)","trace[1329134539] 'applied index is now lower than readState.Index' (duration: 208.264µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:07.368701Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"740.805932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:07.368786Z","caller":"traceutil/trace.go:172","msg":"trace[442498376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1137; }","duration":"740.895044ms","start":"2026-04-17T00:57:06.627869Z","end":"2026-04-17T00:57:07.368764Z","steps":["trace[442498376] 'agreement among raft nodes before linearized reading' (duration: 312.784536ms)","trace[442498376] 'range keys from in-memory index tree' (duration: 427.875194ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:07.368825Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:06.627849Z","time spent":"740.968366ms","remote":"127.0.0.1:39364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:57:07.368904Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"900.030733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:07.368981Z","caller":"traceutil/trace.go:172","msg":"trace[1535204062] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1137; }","duration":"900.231457ms","start":"2026-04-17T00:57:06.468722Z","end":"2026-04-17T00:57:07.368953Z","steps":["trace[1535204062] 'agreement among raft nodes before linearized reading' (duration: 472.065408ms)","trace[1535204062] 'range keys from in-memory index tree' (duration: 427.793032ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:07.369028Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:06.468703Z","time spent":"900.311249ms","remote":"127.0.0.1:41450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-04-17T00:57:07.369118Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"776.499303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:07.369200Z","caller":"traceutil/trace.go:172","msg":"trace[831635295] range","detail":"{range_begin:/registry/validatingwebhookconfigurations; range_end:; response_count:0; response_revision:1137; }","duration":"776.583834ms","start":"2026-04-17T00:57:06.592593Z","end":"2026-04-17T00:57:07.369177Z","steps":["trace[831635295] 'agreement among raft nodes before linearized reading' (duration: 348.036468ms)","trace[831635295] 'range keys from in-memory index tree' (duration: 428.420103ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:07.369242Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:06.592571Z","time spent":"776.661016ms","remote":"127.0.0.1:40306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:07.369041Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"900.200017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:07.369927Z","caller":"traceutil/trace.go:172","msg":"trace[1885372634] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes; range_end:; response_count:0; response_revision:1137; }","duration":"901.084713ms","start":"2026-04-17T00:57:06.468812Z","end":"2026-04-17T00:57:07.369897Z","steps":["trace[1885372634] 'agreement among raft nodes before linearized reading' (duration: 471.864474ms)","trace[1885372634] 'range keys from in-memory index tree' (duration: 427.957325ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:07.370161Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:06.468785Z","time spent":"901.35917ms","remote":"127.0.0.1:41450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:07.370418Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"428.101248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-04-17T00:57:07.441415Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569937,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:57:07.941808Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569937,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:57:08.135196Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"417.161111ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:08.135288Z","caller":"traceutil/trace.go:172","msg":"trace[350923468] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1139; }","duration":"417.268553ms","start":"2026-04-17T00:57:07.718005Z","end":"2026-04-17T00:57:08.135273Z","steps":["trace[350923468] 'range keys from in-memory index tree' (duration: 417.107409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.135853Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"410.040217ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-04-17T00:57:08.136080Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:06.762586Z","time spent":"1.373490937s","remote":"127.0.0.1:39476","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-17T00:57:08.277282Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"140.959755ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T00:57:08.283963Z","caller":"traceutil/trace.go:172","msg":"trace[488674242] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1140; }","duration":"1.440080485s","start":"2026-04-17T00:57:06.843869Z","end":"2026-04-17T00:57:08.283950Z","steps":["trace[488674242] 'process raft request' (duration: 1.439723769s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.284061Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:06.843846Z","time spent":"1.440152887s","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":103,"response count":0,"response size":188531,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:57:08.284174Z","caller":"traceutil/trace.go:172","msg":"trace[174560532] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1340; }","duration":"1.343424619s","start":"2026-04-17T00:57:06.940734Z","end":"2026-04-17T00:57:08.284158Z","steps":["trace[174560532] 'read index received' (duration: 1.342201627s)","trace[174560532] 'applied index is now lower than readState.Index' (duration: 1.221732ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:08.284224Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.364479025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:08.284293Z","caller":"traceutil/trace.go:172","msg":"trace[811290348] range","detail":"{range_begin:/registry/controllerrevisions; range_end:; response_count:0; response_revision:1146; }","duration":"1.364546585s","start":"2026-04-17T00:57:06.919738Z","end":"2026-04-17T00:57:08.284285Z","steps":["trace[811290348] 'agreement among raft nodes before linearized reading' (duration: 1.364455564s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.284303Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.46216689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingadmissionpolicies\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:57:08.284313Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:06.919724Z","time spent":"1.364583557s","remote":"127.0.0.1:40288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":33,"response count":0,"response size":28,"request content":"key:\"/registry/controllerrevisions\" limit:1 "} {"level":"info","ts":"2026-04-17T00:57:08.284329Z","caller":"traceutil/trace.go:172","msg":"trace[686850372] range","detail":"{range_begin:/registry/validatingadmissionpolicies; range_end:; response_count:0; response_revision:1146; }","duration":"1.462202982s","start":"2026-04-17T00:57:06.822118Z","end":"2026-04-17T00:57:08.284321Z","steps":["trace[686850372] 'agreement among raft nodes before linearized reading' (duration: 1.46213775s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.284352Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:06.822101Z","time spent":"1.462244992s","remote":"127.0.0.1:40340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/validatingadmissionpolicies\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:08.284741Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.480344112s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:08.284761Z","caller":"traceutil/trace.go:172","msg":"trace[348407931] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1146; }","duration":"1.480365152s","start":"2026-04-17T00:57:06.804390Z","end":"2026-04-17T00:57:08.284755Z","steps":["trace[348407931] 'agreement among raft nodes before linearized reading' (duration: 1.480335941s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.376354Z","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":10524804134374569939,"received-request-id":10524804134374569937} {"level":"info","ts":"2026-04-17T00:57:08.508735Z","caller":"traceutil/trace.go:172","msg":"trace[1543123236] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"371.45551ms","start":"2026-04-17T00:57:08.137258Z","end":"2026-04-17T00:57:08.508713Z","steps":["trace[1543123236] 'process raft request' (duration: 276.898566ms)","trace[1543123236] 'compare' (duration: 94.402422ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:08.508878Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:08.137236Z","time spent":"371.570662ms","remote":"127.0.0.1:39476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:57:08.544168Z","caller":"traceutil/trace.go:172","msg":"trace[1966912641] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1352; }","duration":"259.916487ms","start":"2026-04-17T00:57:08.284222Z","end":"2026-04-17T00:57:08.544139Z","steps":["trace[1966912641] 'read index received' (duration: 259.896377ms)","trace[1966912641] 'applied index is now lower than readState.Index' (duration: 18.49µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:08.544327Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"696.332949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-17T00:57:08.544356Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.406208445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:08.544382Z","caller":"traceutil/trace.go:172","msg":"trace[957739157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1150; }","duration":"696.393479ms","start":"2026-04-17T00:57:07.847977Z","end":"2026-04-17T00:57:08.544371Z","steps":["trace[957739157] 'agreement among raft nodes before linearized reading' (duration: 696.237797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.544422Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:07.847955Z","time spent":"696.459542ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:08.544409Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.304626629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-04-17T00:57:08.544409Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.284648084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:483"} {"level":"info","ts":"2026-04-17T00:57:08.544483Z","caller":"traceutil/trace.go:172","msg":"trace[778318685] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1150; }","duration":"1.304706909s","start":"2026-04-17T00:57:07.239758Z","end":"2026-04-17T00:57:08.544465Z","steps":["trace[778318685] 'agreement among raft nodes before linearized reading' (duration: 1.304483145s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:08.544490Z","caller":"traceutil/trace.go:172","msg":"trace[813440161] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1150; }","duration":"1.284741064s","start":"2026-04-17T00:57:07.259737Z","end":"2026-04-17T00:57:08.544478Z","steps":["trace[813440161] 'agreement among raft nodes before linearized reading' (duration: 1.284553122s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.544534Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:07.259725Z","time spent":"1.284800116s","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:08.544534Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:07.239741Z","time spent":"1.304784382s","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:08.544619Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"439.021508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-04-17T00:57:08.544693Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.547154391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:57:08.544734Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"365.717473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-04-17T00:57:08.544771Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.106501848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" limit:1 ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-04-17T00:57:08.544845Z","caller":"traceutil/trace.go:172","msg":"trace[921629622] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:1151; }","duration":"1.10657508s","start":"2026-04-17T00:57:07.438256Z","end":"2026-04-17T00:57:08.544831Z","steps":["trace[921629622] 'agreement among raft nodes before linearized reading' (duration: 1.106207332s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.544877Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"341.077301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:57:08.544886Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:07.438238Z","time spent":"1.106638821s","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":188275,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" limit:1 "} {"level":"info","ts":"2026-04-17T00:57:08.544902Z","caller":"traceutil/trace.go:172","msg":"trace[2050956613] range","detail":"{range_begin:/registry/priorityclasses; range_end:; response_count:0; response_revision:1151; }","duration":"341.100581ms","start":"2026-04-17T00:57:08.203793Z","end":"2026-04-17T00:57:08.544894Z","steps":["trace[2050956613] 'agreement among raft nodes before linearized reading' (duration: 340.717105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.544930Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:08.203759Z","time spent":"341.164293ms","remote":"127.0.0.1:40090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses\" limit:1 "} {"level":"info","ts":"2026-04-17T00:57:08.544765Z","caller":"traceutil/trace.go:172","msg":"trace[883781499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1150; }","duration":"439.378916ms","start":"2026-04-17T00:57:08.105333Z","end":"2026-04-17T00:57:08.544711Z","steps":["trace[883781499] 'agreement among raft nodes before linearized reading' (duration: 438.828195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.545019Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:08.105311Z","time spent":"439.69282ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 "} {"level":"info","ts":"2026-04-17T00:57:08.544797Z","caller":"traceutil/trace.go:172","msg":"trace[1590240322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1151; }","duration":"365.775113ms","start":"2026-04-17T00:57:08.179003Z","end":"2026-04-17T00:57:08.544778Z","steps":["trace[1590240322] 'agreement among raft nodes before linearized reading' (duration: 365.519679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.545107Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:08.178982Z","time spent":"366.11112ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:08.544665Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.560768847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattributesclasses\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:57:08.544829Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.303663371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:08.544890Z","caller":"traceutil/trace.go:172","msg":"trace[890587814] range","detail":"{range_begin:/registry/clusterroles; range_end:; response_count:0; response_revision:1151; }","duration":"1.547187463s","start":"2026-04-17T00:57:06.997534Z","end":"2026-04-17T00:57:08.544721Z","steps":["trace[890587814] 'agreement among raft nodes before linearized reading' (duration: 1.546946218s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:08.545206Z","caller":"traceutil/trace.go:172","msg":"trace[377297034] range","detail":"{range_begin:/registry/volumeattributesclasses; range_end:; response_count:0; response_revision:1151; }","duration":"1.561288917s","start":"2026-04-17T00:57:06.983888Z","end":"2026-04-17T00:57:08.545176Z","steps":["trace[377297034] 'agreement among raft nodes before linearized reading' (duration: 1.560602855s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:08.545217Z","caller":"traceutil/trace.go:172","msg":"trace[323300241] range","detail":"{range_begin:/registry/volumeattachments; range_end:; response_count:0; response_revision:1151; }","duration":"1.304048077s","start":"2026-04-17T00:57:07.241158Z","end":"2026-04-17T00:57:08.545206Z","steps":["trace[323300241] 'agreement among raft nodes before linearized reading' (duration: 1.303342495s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.545239Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:06.997519Z","time spent":"1.547710101s","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":26,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:08.545246Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:06.983869Z","time spent":"1.56136615s","remote":"127.0.0.1:40126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattributesclasses\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:08.545253Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:07.241151Z","time spent":"1.304089749s","remote":"127.0.0.1:40110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":31,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments\" limit:1 "} {"level":"info","ts":"2026-04-17T00:57:08.544391Z","caller":"traceutil/trace.go:172","msg":"trace[1028566080] range","detail":"{range_begin:/registry/networkpolicies; range_end:; response_count:0; response_revision:1150; }","duration":"1.406268336s","start":"2026-04-17T00:57:07.138113Z","end":"2026-04-17T00:57:08.544382Z","steps":["trace[1028566080] 'agreement among raft nodes before linearized reading' (duration: 1.406158784s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.545442Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:07.138101Z","time spent":"1.407330066s","remote":"127.0.0.1:39874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:08.545468Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.41552706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" keys_only:true ","response":"range_response_count:139 size:11577"} {"level":"info","ts":"2026-04-17T00:57:08.545515Z","caller":"traceutil/trace.go:172","msg":"trace[64049928] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:139; response_revision:1150; }","duration":"1.4155802s","start":"2026-04-17T00:57:07.129924Z","end":"2026-04-17T00:57:08.545504Z","steps":["trace[64049928] 'agreement among raft nodes before linearized reading' (duration: 1.41448565s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.545554Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:07.129907Z","time spent":"1.415634232s","remote":"127.0.0.1:39476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":139,"response size":11600,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" keys_only:true "} {"level":"warn","ts":"2026-04-17T00:57:08.545559Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.174945223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:08.545618Z","caller":"traceutil/trace.go:172","msg":"trace[1382667673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1150; }","duration":"1.175005925s","start":"2026-04-17T00:57:07.370596Z","end":"2026-04-17T00:57:08.545602Z","steps":["trace[1382667673] 'agreement among raft nodes before linearized reading' (duration: 1.173561668s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.545659Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:07.370438Z","time spent":"1.175210128s","remote":"127.0.0.1:39366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T00:57:08.689829Z","caller":"traceutil/trace.go:172","msg":"trace[1636838126] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1357; }","duration":"145.564252ms","start":"2026-04-17T00:57:08.544238Z","end":"2026-04-17T00:57:08.689802Z","steps":["trace[1636838126] 'read index received' (duration: 145.555522ms)","trace[1636838126] 'applied index is now lower than readState.Index' (duration: 7.33µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:08.689963Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"177.604623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-tm2f9.18a6fef188198b56\" limit:1 ","response":"range_response_count:1 size:803"} {"level":"warn","ts":"2026-04-17T00:57:08.689965Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"403.647355ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:08.690010Z","caller":"traceutil/trace.go:172","msg":"trace[20815232] range","detail":"{range_begin:/registry/events/kube-system/cilium-tm2f9.18a6fef188198b56; range_end:; response_count:1; response_revision:1155; }","duration":"177.681075ms","start":"2026-04-17T00:57:08.512316Z","end":"2026-04-17T00:57:08.689998Z","steps":["trace[20815232] 'agreement among raft nodes before linearized reading' (duration: 177.528441ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:08.690024Z","caller":"traceutil/trace.go:172","msg":"trace[184585782] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1155; }","duration":"403.710477ms","start":"2026-04-17T00:57:08.286298Z","end":"2026-04-17T00:57:08.690008Z","steps":["trace[184585782] 'agreement among raft nodes before linearized reading' (duration: 403.614505ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:08.690040Z","caller":"traceutil/trace.go:172","msg":"trace[977686944] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"139.327105ms","start":"2026-04-17T00:57:08.550695Z","end":"2026-04-17T00:57:08.690022Z","steps":["trace[977686944] 'process raft request' (duration: 139.155091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.742381Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"187.644541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:08.742439Z","caller":"traceutil/trace.go:172","msg":"trace[1216305605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1157; }","duration":"187.711253ms","start":"2026-04-17T00:57:08.554715Z","end":"2026-04-17T00:57:08.742426Z","steps":["trace[1216305605] 'agreement among raft nodes before linearized reading' (duration: 187.610391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:08.936192Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"131.915106ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:08.936286Z","caller":"traceutil/trace.go:172","msg":"trace[972912508] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1158; }","duration":"132.010167ms","start":"2026-04-17T00:57:08.804246Z","end":"2026-04-17T00:57:08.936257Z","steps":["trace[972912508] 'range keys from in-memory index tree' (duration: 131.846284ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:09.145585Z","caller":"traceutil/trace.go:172","msg":"trace[1705155912] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1362; }","duration":"175.577143ms","start":"2026-04-17T00:57:08.969987Z","end":"2026-04-17T00:57:09.145564Z","steps":["trace[1705155912] 'read index received' (duration: 175.571413ms)","trace[1705155912] 'applied index is now lower than readState.Index' (duration: 4.47µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:09.145843Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"175.834357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/controller-2\" limit:1 ","response":"range_response_count:1 size:1508"} {"level":"info","ts":"2026-04-17T00:57:09.145899Z","caller":"traceutil/trace.go:172","msg":"trace[644120886] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/controller-2; range_end:; response_count:1; response_revision:1159; }","duration":"175.900609ms","start":"2026-04-17T00:57:08.969982Z","end":"2026-04-17T00:57:09.145883Z","steps":["trace[644120886] 'agreement among raft nodes before linearized reading' (duration: 175.700255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:09.742586Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"149.577895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:57:09.742749Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"114.840594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:09.742758Z","caller":"traceutil/trace.go:172","msg":"trace[1634844183] range","detail":"{range_begin:/registry/statefulsets; range_end:; response_count:0; response_revision:1164; }","duration":"149.759859ms","start":"2026-04-17T00:57:09.592978Z","end":"2026-04-17T00:57:09.742738Z","steps":["trace[1634844183] 'range keys from in-memory index tree' (duration: 147.785711ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:09.742801Z","caller":"traceutil/trace.go:172","msg":"trace[746819123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1164; }","duration":"114.893685ms","start":"2026-04-17T00:57:09.627893Z","end":"2026-04-17T00:57:09.742787Z","steps":["trace[746819123] 'range keys from in-memory index tree' (duration: 112.895098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:09.779832Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:41460","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:57:09.792774Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:41468","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:57:09.802723Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:41480","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:57:09.827736Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:41490","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:57:09.880618Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:41514","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-17T00:57:10.990175Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"184.872746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:57:10.991719Z","caller":"traceutil/trace.go:172","msg":"trace[1725695277] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1384; }","duration":"187.034157ms","start":"2026-04-17T00:57:10.804645Z","end":"2026-04-17T00:57:10.991679Z","steps":["trace[1725695277] 'read index received' (duration: 187.026077ms)","trace[1725695277] 'applied index is now lower than readState.Index' (duration: 6.34µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:10.991877Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"187.238331ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:10.991925Z","caller":"traceutil/trace.go:172","msg":"trace[1808272659] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1178; }","duration":"187.302921ms","start":"2026-04-17T00:57:10.804607Z","end":"2026-04-17T00:57:10.991910Z","steps":["trace[1808272659] 'agreement among raft nodes before linearized reading' (duration: 187.206459ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:12.161524Z","caller":"traceutil/trace.go:172","msg":"trace[868647295] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1386; }","duration":"356.59789ms","start":"2026-04-17T00:57:11.804903Z","end":"2026-04-17T00:57:12.161501Z","steps":["trace[868647295] 'read index received' (duration: 356.59082ms)","trace[868647295] 'applied index is now lower than readState.Index' (duration: 6.01µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:12.162883Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"357.961505ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:12.162976Z","caller":"traceutil/trace.go:172","msg":"trace[181578587] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1179; }","duration":"358.069257ms","start":"2026-04-17T00:57:11.804886Z","end":"2026-04-17T00:57:12.162956Z","steps":["trace[181578587] 'agreement among raft nodes before linearized reading' (duration: 356.701141ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:12.163168Z","caller":"traceutil/trace.go:172","msg":"trace[334948468] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"177.502683ms","start":"2026-04-17T00:57:11.985645Z","end":"2026-04-17T00:57:12.163147Z","steps":["trace[334948468] 'process raft request' (duration: 177.453222ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:12.669885Z","caller":"traceutil/trace.go:172","msg":"trace[750426831] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"295.453152ms","start":"2026-04-17T00:57:12.374368Z","end":"2026-04-17T00:57:12.669821Z","steps":["trace[750426831] 'process raft request' (duration: 295.203747ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:12.995201Z","caller":"traceutil/trace.go:172","msg":"trace[1993469607] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1394; }","duration":"500.955641ms","start":"2026-04-17T00:57:12.494222Z","end":"2026-04-17T00:57:12.995178Z","steps":["trace[1993469607] 'read index received' (duration: 500.94732ms)","trace[1993469607] 'applied index is now lower than readState.Index' (duration: 6.531µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:12.995671Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"501.415939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-17T00:57:12.995738Z","caller":"traceutil/trace.go:172","msg":"trace[332564253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1187; }","duration":"501.508671ms","start":"2026-04-17T00:57:12.494217Z","end":"2026-04-17T00:57:12.995726Z","steps":["trace[332564253] 'agreement among raft nodes before linearized reading' (duration: 501.084313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:12.995772Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:12.494198Z","time spent":"501.565961ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:13.495469Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374569991,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:57:13.680921Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"269.737448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:57:13.688282Z","caller":"traceutil/trace.go:172","msg":"trace[1016413884] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1402; }","duration":"692.986517ms","start":"2026-04-17T00:57:12.995274Z","end":"2026-04-17T00:57:13.688260Z","steps":["trace[1016413884] 'read index received' (duration: 692.950517ms)","trace[1016413884] 'applied index is now lower than readState.Index' (duration: 34.77µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:13.688450Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"884.392482ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:57:13.688468Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"915.287291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-04-17T00:57:13.688481Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"977.440694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:13.688509Z","caller":"traceutil/trace.go:172","msg":"trace[732358368] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1195; }","duration":"884.477893ms","start":"2026-04-17T00:57:12.804015Z","end":"2026-04-17T00:57:13.688493Z","steps":["trace[732358368] 'agreement among raft nodes before linearized reading' (duration: 884.356281ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:13.688528Z","caller":"traceutil/trace.go:172","msg":"trace[1179592722] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1195; }","duration":"915.352163ms","start":"2026-04-17T00:57:12.773156Z","end":"2026-04-17T00:57:13.688508Z","steps":["trace[1179592722] 'agreement among raft nodes before linearized reading' (duration: 915.129999ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:13.688530Z","caller":"traceutil/trace.go:172","msg":"trace[199618552] range","detail":"{range_begin:/registry/poddisruptionbudgets; range_end:; response_count:0; response_revision:1195; }","duration":"977.494616ms","start":"2026-04-17T00:57:12.711022Z","end":"2026-04-17T00:57:13.688516Z","steps":["trace[199618552] 'agreement among raft nodes before linearized reading' (duration: 977.408484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:13.688569Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:12.711002Z","time spent":"977.558586ms","remote":"127.0.0.1:39982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:13.688568Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:12.773143Z","time spent":"915.415093ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:13.688593Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.060376767s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:13.688646Z","caller":"traceutil/trace.go:172","msg":"trace[1221607257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1195; }","duration":"1.060469219s","start":"2026-04-17T00:57:12.628160Z","end":"2026-04-17T00:57:13.688629Z","steps":["trace[1221607257] 'agreement among raft nodes before linearized reading' (duration: 1.060342707s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:13.688741Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:12.628048Z","time spent":"1.060680903s","remote":"127.0.0.1:39364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:57:14.030893Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"226.07006ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:14.030978Z","caller":"traceutil/trace.go:172","msg":"trace[734555228] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1199; }","duration":"226.169012ms","start":"2026-04-17T00:57:13.804788Z","end":"2026-04-17T00:57:14.030957Z","steps":["trace[734555228] 'range keys from in-memory index tree' (duration: 226.016309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:14.031139Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"200.475222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-04-17T00:57:14.031937Z","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":10524804134374569993,"received-request-id":10524804134374569991} {"level":"info","ts":"2026-04-17T00:57:14.032426Z","caller":"traceutil/trace.go:172","msg":"trace[1592013275] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"409.189706ms","start":"2026-04-17T00:57:13.623217Z","end":"2026-04-17T00:57:14.032407Z","steps":["trace[1592013275] 'process raft request' (duration: 409.081884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:14.032583Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:13.623193Z","time spent":"409.314549ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:57:14.036506Z","caller":"traceutil/trace.go:172","msg":"trace[1517323304] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1410; }","duration":"348.057333ms","start":"2026-04-17T00:57:13.688433Z","end":"2026-04-17T00:57:14.036491Z","steps":["trace[1517323304] 'read index received' (duration: 348.052453ms)","trace[1517323304] 'applied index is now lower than readState.Index' (duration: 3.79µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:14.036654Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"431.110227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:483"} {"level":"warn","ts":"2026-04-17T00:57:14.036693Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"558.479991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-17T00:57:14.036714Z","caller":"traceutil/trace.go:172","msg":"trace[979302375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1203; }","duration":"431.172699ms","start":"2026-04-17T00:57:13.605524Z","end":"2026-04-17T00:57:14.036697Z","steps":["trace[979302375] 'agreement among raft nodes before linearized reading' (duration: 430.988445ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:14.036735Z","caller":"traceutil/trace.go:172","msg":"trace[1794911656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1203; }","duration":"558.527261ms","start":"2026-04-17T00:57:13.478196Z","end":"2026-04-17T00:57:14.036723Z","steps":["trace[1794911656] 'agreement among raft nodes before linearized reading' (duration: 558.376569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:14.036759Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:13.605500Z","time spent":"431.247569ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:14.036771Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:13.478175Z","time spent":"558.586602ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:14.036912Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"879.0852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:14.036959Z","caller":"traceutil/trace.go:172","msg":"trace[992851754] range","detail":"{range_begin:/registry/podtemplates; range_end:; response_count:0; response_revision:1203; }","duration":"879.13388ms","start":"2026-04-17T00:57:13.157816Z","end":"2026-04-17T00:57:14.036950Z","steps":["trace[992851754] 'agreement among raft nodes before linearized reading' (duration: 879.060919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:14.036987Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:13.157795Z","time spent":"879.183861ms","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":26,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates\" limit:1 "} {"level":"info","ts":"2026-04-17T00:57:14.037421Z","caller":"traceutil/trace.go:172","msg":"trace[1964531668] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"343.322484ms","start":"2026-04-17T00:57:13.694047Z","end":"2026-04-17T00:57:14.037369Z","steps":["trace[1964531668] 'process raft request' (duration: 343.11103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:14.037569Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:13.694023Z","time spent":"343.468316ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:57:14.038893Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"347.983161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:14.038978Z","caller":"traceutil/trace.go:172","msg":"trace[1473157476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1204; }","duration":"348.069343ms","start":"2026-04-17T00:57:13.690897Z","end":"2026-04-17T00:57:14.038966Z","steps":["trace[1473157476] 'agreement among raft nodes before linearized reading' (duration: 347.90913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:14.039021Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:13.690877Z","time spent":"348.131864ms","remote":"127.0.0.1:39366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T00:57:22.340677Z","caller":"traceutil/trace.go:172","msg":"trace[940726071] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"245.928462ms","start":"2026-04-17T00:57:22.094729Z","end":"2026-04-17T00:57:22.340658Z","steps":["trace[940726071] 'process raft request' (duration: 245.591536ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:22.359750Z","caller":"traceutil/trace.go:172","msg":"trace[434666453] linearizableReadLoop","detail":"{readStateIndex:1488; appliedIndex:1488; }","duration":"233.302716ms","start":"2026-04-17T00:57:22.126430Z","end":"2026-04-17T00:57:22.359732Z","steps":["trace[434666453] 'read index received' (duration: 233.297216ms)","trace[434666453] 'applied index is now lower than readState.Index' (duration: 4.49µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:22.359876Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"233.426918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:22.359903Z","caller":"traceutil/trace.go:172","msg":"trace[771931095] range","detail":"{range_begin:/registry/services/endpoints; range_end:; response_count:0; response_revision:1274; }","duration":"233.46913ms","start":"2026-04-17T00:57:22.126424Z","end":"2026-04-17T00:57:22.359894Z","steps":["trace[771931095] 'agreement among raft nodes before linearized reading' (duration: 233.394898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:25.838031Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374570113,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:57:26.338856Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10524804134374570113,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:57:26.408394Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.51283434s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T00:57:26.568031Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"158.855214ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T00:57:26.577109Z","caller":"traceutil/trace.go:172","msg":"trace[597521872] linearizableReadLoop","detail":"{readStateIndex:1514; appliedIndex:1514; }","duration":"1.239198086s","start":"2026-04-17T00:57:25.337890Z","end":"2026-04-17T00:57:26.577088Z","steps":["trace[597521872] 'read index received' (duration: 1.239191006s)","trace[597521872] 'applied index is now lower than readState.Index' (duration: 5.68µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:26.577289Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.239369619s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:26.577480Z","caller":"traceutil/trace.go:172","msg":"trace[1452008461] range","detail":"{range_begin:/registry/endpointslices; range_end:; response_count:0; response_revision:1297; }","duration":"1.239514522s","start":"2026-04-17T00:57:25.337884Z","end":"2026-04-17T00:57:26.577399Z","steps":["trace[1452008461] 'agreement among raft nodes before linearized reading' (duration: 1.239289487s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:26.577537Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:25.337849Z","time spent":"1.239677824s","remote":"127.0.0.1:39866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":28,"request content":"key:\"/registry/endpointslices\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:26.578974Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"950.521087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:57:26.579025Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"789.901087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:26.579086Z","caller":"traceutil/trace.go:172","msg":"trace[723314300] range","detail":"{range_begin:/registry/deployments; range_end:; response_count:0; response_revision:1297; }","duration":"789.987469ms","start":"2026-04-17T00:57:25.789085Z","end":"2026-04-17T00:57:26.579072Z","steps":["trace[723314300] 'agreement among raft nodes before linearized reading' (duration: 789.873817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:26.579122Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"220.095003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-04-17T00:57:26.579141Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:25.789067Z","time spent":"790.068011ms","remote":"127.0.0.1:40222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":25,"response count":0,"response size":28,"request content":"key:\"/registry/deployments\" limit:1 "} {"level":"info","ts":"2026-04-17T00:57:26.579156Z","caller":"traceutil/trace.go:172","msg":"trace[440003257] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1297; }","duration":"220.213005ms","start":"2026-04-17T00:57:26.358934Z","end":"2026-04-17T00:57:26.579147Z","steps":["trace[440003257] 'agreement among raft nodes before linearized reading' (duration: 219.982361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:26.579133Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"774.092624ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:26.579233Z","caller":"traceutil/trace.go:172","msg":"trace[1536407608] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1297; }","duration":"774.291077ms","start":"2026-04-17T00:57:25.804929Z","end":"2026-04-17T00:57:26.579220Z","steps":["trace[1536407608] 'agreement among raft nodes before linearized reading' (duration: 774.020523ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:26.579853Z","caller":"traceutil/trace.go:172","msg":"trace[1615047275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1297; }","duration":"950.606878ms","start":"2026-04-17T00:57:25.628440Z","end":"2026-04-17T00:57:26.579046Z","steps":["trace[1615047275] 'agreement among raft nodes before linearized reading' (duration: 950.468916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:26.579908Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:25.628422Z","time spent":"951.473834ms","remote":"127.0.0.1:39364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:57:26.580094Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"469.446929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:26.580255Z","caller":"traceutil/trace.go:172","msg":"trace[572706855] range","detail":"{range_begin:/registry/secrets; range_end:; response_count:0; response_revision:1297; }","duration":"470.417877ms","start":"2026-04-17T00:57:26.109822Z","end":"2026-04-17T00:57:26.580240Z","steps":["trace[572706855] 'agreement among raft nodes before linearized reading' (duration: 469.363497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:26.580326Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:26.109802Z","time spent":"470.510208ms","remote":"127.0.0.1:39492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":21,"response count":0,"response size":28,"request content":"key:\"/registry/secrets\" limit:1 "} {"level":"warn","ts":"2026-04-17T00:57:32.807724Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"124.564209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:57:32.807865Z","caller":"traceutil/trace.go:172","msg":"trace[1536144954] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"152.270844ms","start":"2026-04-17T00:57:32.655544Z","end":"2026-04-17T00:57:32.807815Z","steps":["trace[1536144954] 'process raft request' (duration: 27.47618ms)","trace[1536144954] 'compare' (duration: 124.408057ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:57:37.030038Z","caller":"traceutil/trace.go:172","msg":"trace[827236620] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"123.765451ms","start":"2026-04-17T00:57:36.906250Z","end":"2026-04-17T00:57:37.030015Z","steps":["trace[827236620] 'process raft request' (duration: 123.615608ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:37.737759Z","caller":"traceutil/trace.go:172","msg":"trace[2139648329] linearizableReadLoop","detail":"{readStateIndex:1600; appliedIndex:1600; }","duration":"108.633591ms","start":"2026-04-17T00:57:37.629098Z","end":"2026-04-17T00:57:37.737732Z","steps":["trace[2139648329] 'read index received' (duration: 108.626981ms)","trace[2139648329] 'applied index is now lower than readState.Index' (duration: 5.06µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:37.737898Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"108.794044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:37.737938Z","caller":"traceutil/trace.go:172","msg":"trace[1947266563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1377; }","duration":"108.844595ms","start":"2026-04-17T00:57:37.629083Z","end":"2026-04-17T00:57:37.737928Z","steps":["trace[1947266563] 'agreement among raft nodes before linearized reading' (duration: 108.746903ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:56.001556Z","caller":"traceutil/trace.go:172","msg":"trace[853237132] linearizableReadLoop","detail":"{readStateIndex:1695; appliedIndex:1695; }","duration":"373.772448ms","start":"2026-04-17T00:57:55.627760Z","end":"2026-04-17T00:57:56.001532Z","steps":["trace[853237132] 'read index received' (duration: 373.765938ms)","trace[853237132] 'applied index is now lower than readState.Index' (duration: 5.12µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:57:56.001743Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"373.91537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:57:56.001818Z","caller":"traceutil/trace.go:172","msg":"trace[626156769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1459; }","duration":"374.056372ms","start":"2026-04-17T00:57:55.627749Z","end":"2026-04-17T00:57:56.001805Z","steps":["trace[626156769] 'agreement among raft nodes before linearized reading' (duration: 373.862318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:57:56.001855Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-17T00:57:55.627732Z","time spent":"374.112523ms","remote":"127.0.0.1:39364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:57:56.003838Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"199.608613ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:57:56.003893Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"280.42461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-17T00:57:56.003914Z","caller":"traceutil/trace.go:172","msg":"trace[1357564977] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1459; }","duration":"199.701145ms","start":"2026-04-17T00:57:55.804196Z","end":"2026-04-17T00:57:56.003897Z","steps":["trace[1357564977] 'agreement among raft nodes before linearized reading' (duration: 199.580363ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:57:56.003928Z","caller":"traceutil/trace.go:172","msg":"trace[1868655745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1459; }","duration":"280.46261ms","start":"2026-04-17T00:57:55.723457Z","end":"2026-04-17T00:57:56.003919Z","steps":["trace[1868655745] 'agreement among raft nodes before linearized reading' (duration: 280.350358ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:58:07.799895Z","caller":"traceutil/trace.go:172","msg":"trace[43134967] linearizableReadLoop","detail":"{readStateIndex:1751; appliedIndex:1751; }","duration":"171.353693ms","start":"2026-04-17T00:58:07.628517Z","end":"2026-04-17T00:58:07.799870Z","steps":["trace[43134967] 'read index received' (duration: 171.341333ms)","trace[43134967] 'applied index is now lower than readState.Index' (duration: 4.7µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:58:07.807874Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"154.191908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourceclaimtemplates\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:58:07.807913Z","caller":"traceutil/trace.go:172","msg":"trace[118263469] range","detail":"{range_begin:/registry/resourceclaimtemplates; range_end:; response_count:0; response_revision:1509; }","duration":"154.242889ms","start":"2026-04-17T00:58:07.653661Z","end":"2026-04-17T00:58:07.807904Z","steps":["trace[118263469] 'agreement among raft nodes before linearized reading' (duration: 147.438064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:58:07.807920Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"169.977479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T00:58:07.807969Z","caller":"traceutil/trace.go:172","msg":"trace[2140769948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1509; }","duration":"170.0434ms","start":"2026-04-17T00:58:07.637916Z","end":"2026-04-17T00:58:07.807959Z","steps":["trace[2140769948] 'agreement among raft nodes before linearized reading' (duration: 163.172043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:58:07.808057Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"179.544793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:58:07.808088Z","caller":"traceutil/trace.go:172","msg":"trace[292747547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1509; }","duration":"179.577164ms","start":"2026-04-17T00:58:07.628502Z","end":"2026-04-17T00:58:07.808080Z","steps":["trace[292747547] 'agreement among raft nodes before linearized reading' (duration: 171.448705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:58:57.838953Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"104.350499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:483"} {"level":"info","ts":"2026-04-17T00:58:57.839033Z","caller":"traceutil/trace.go:172","msg":"trace[1623088224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1702; }","duration":"104.43667ms","start":"2026-04-17T00:58:57.734576Z","end":"2026-04-17T00:58:57.839012Z","steps":["trace[1623088224] 'agreement among raft nodes before linearized reading' (duration: 35.673479ms)","trace[1623088224] 'range keys from in-memory index tree' (duration: 68.610418ms)"],"step_count":2}