{"level":"warn","ts":"2026-07-14T23:08:51.835729Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T23:08:51.835855Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.86:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.86:2380","--initial-cluster=instance=https://199.19.213.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.86:2380","--name=instance","--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"]} {"level":"warn","ts":"2026-07-14T23:08:51.835939Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T23:08:51.835954Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-07-14T23:08:51.835979Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-14T23:08:51.836683Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"]} {"level":"info","ts":"2026-07-14T23:08:51.836775Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.86:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-14T23:08:51.841546Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.545337ms"} {"level":"info","ts":"2026-07-14T23:08:51.847678Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"849a6ffb5e81c109","cluster-id":"d4d86b1dd8829199"} {"level":"info","ts":"2026-07-14T23:08:51.849504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T23:08:51.849559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 0"} {"level":"info","ts":"2026-07-14T23:08:51.849583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 849a6ffb5e81c109 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T23:08:51.849599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 1"} {"level":"info","ts":"2026-07-14T23:08:51.849647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"warn","ts":"2026-07-14T23:08:51.858474Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T23:08:51.859955Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T23:08:51.861268Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-14T23:08:51.863445Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"849a6ffb5e81c109","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T23:08:51.864107Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T23:08:51.864194Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T23:08:51.864206Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T23:08:51.865524Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"849a6ffb5e81c109","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T23:08:51.868627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"info","ts":"2026-07-14T23:08:51.868853Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","added-peer-id":"849a6ffb5e81c109","added-peer-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-07-14T23:08:51.874891Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-14T23:08:51.875132Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-07-14T23:08:51.875175Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-07-14T23:08:51.878322Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"849a6ffb5e81c109","initial-advertise-peer-urls":["https://199.19.213.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T23:08:51.878454Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T23:08:51.950309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T23:08:51.95039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T23:08:51.95043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgPreVoteResp from 849a6ffb5e81c109 at term 1"} {"level":"info","ts":"2026-07-14T23:08:51.950457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became candidate at term 2"} {"level":"info","ts":"2026-07-14T23:08:51.950475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgVoteResp from 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-07-14T23:08:51.950497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became leader at term 2"} {"level":"info","ts":"2026-07-14T23:08:51.950518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 849a6ffb5e81c109 elected leader 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-07-14T23:08:51.951574Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:08:51.952634Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T23:08:51.952635Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"849a6ffb5e81c109","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.86:2379]}","request-path":"/0/members/849a6ffb5e81c109/attributes","cluster-id":"d4d86b1dd8829199","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T23:08:51.952684Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T23:08:51.95307Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:08:51.953188Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:08:51.953226Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:08:51.95331Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T23:08:51.953353Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T23:08:51.955481Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T23:08:51.957022Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.86:2379"} {"level":"info","ts":"2026-07-14T23:09:26.934445Z","caller":"traceutil/trace.go:171","msg":"trace[687883464] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"236.521798ms","start":"2026-07-14T23:09:26.697892Z","end":"2026-07-14T23:09:26.934413Z","steps":["trace[687883464] 'process raft request' (duration: 232.035962ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:26.934632Z","caller":"traceutil/trace.go:171","msg":"trace[871729834] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"216.774007ms","start":"2026-07-14T23:09:26.717815Z","end":"2026-07-14T23:09:26.934589Z","steps":["trace[871729834] 'process raft request' (duration: 216.583084ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:26.934597Z","caller":"traceutil/trace.go:171","msg":"trace[1348596266] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"216.918291ms","start":"2026-07-14T23:09:26.717603Z","end":"2026-07-14T23:09:26.934521Z","steps":["trace[1348596266] 'process raft request' (duration: 216.742538ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:26.935206Z","caller":"traceutil/trace.go:171","msg":"trace[1488354659] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"216.879521ms","start":"2026-07-14T23:09:26.718238Z","end":"2026-07-14T23:09:26.935118Z","steps":["trace[1488354659] 'process raft request' (duration: 216.209787ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:26.935949Z","caller":"traceutil/trace.go:171","msg":"trace[914760332] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"181.817987ms","start":"2026-07-14T23:09:26.754103Z","end":"2026-07-14T23:09:26.935921Z","steps":["trace[914760332] 'process raft request' (duration: 181.157464ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.690144Z","caller":"traceutil/trace.go:171","msg":"trace[544422823] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"218.546992ms","start":"2026-07-14T23:09:28.471567Z","end":"2026-07-14T23:09:28.690114Z","steps":["trace[544422823] 'process raft request' (duration: 218.41506ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.696794Z","caller":"traceutil/trace.go:171","msg":"trace[620274516] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"214.457783ms","start":"2026-07-14T23:09:28.482302Z","end":"2026-07-14T23:09:28.69676Z","steps":["trace[620274516] 'process raft request' (duration: 213.699758ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.696859Z","caller":"traceutil/trace.go:171","msg":"trace[2136252332] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"220.694493ms","start":"2026-07-14T23:09:28.476157Z","end":"2026-07-14T23:09:28.696852Z","steps":["trace[2136252332] 'process raft request' (duration: 219.688593ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.696877Z","caller":"traceutil/trace.go:171","msg":"trace[1069634671] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"214.778529ms","start":"2026-07-14T23:09:28.48208Z","end":"2026-07-14T23:09:28.696859Z","steps":["trace[1069634671] 'process raft request' (duration: 213.83719ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.696804Z","caller":"traceutil/trace.go:171","msg":"trace[1084397352] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"220.675052ms","start":"2026-07-14T23:09:28.476099Z","end":"2026-07-14T23:09:28.696774Z","steps":["trace[1084397352] 'process raft request' (duration: 219.586221ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.697003Z","caller":"traceutil/trace.go:171","msg":"trace[1223793898] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"202.642306ms","start":"2026-07-14T23:09:28.49435Z","end":"2026-07-14T23:09:28.696992Z","steps":["trace[1223793898] 'process raft request' (duration: 201.727558ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.697019Z","caller":"traceutil/trace.go:171","msg":"trace[1508263207] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"176.280659ms","start":"2026-07-14T23:09:28.520715Z","end":"2026-07-14T23:09:28.696996Z","steps":["trace[1508263207] 'process raft request' (duration: 175.78283ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.697025Z","caller":"traceutil/trace.go:171","msg":"trace[1884188978] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"176.89712ms","start":"2026-07-14T23:09:28.520098Z","end":"2026-07-14T23:09:28.696995Z","steps":["trace[1884188978] 'process raft request' (duration: 176.269179ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.697095Z","caller":"traceutil/trace.go:171","msg":"trace[2061809762] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"165.862208ms","start":"2026-07-14T23:09:28.531226Z","end":"2026-07-14T23:09:28.697088Z","steps":["trace[2061809762] 'process raft request' (duration: 165.372559ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.697117Z","caller":"traceutil/trace.go:171","msg":"trace[1666588342] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"145.807853ms","start":"2026-07-14T23:09:28.551298Z","end":"2026-07-14T23:09:28.697106Z","steps":["trace[1666588342] 'process raft request' (duration: 145.365315ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.697059Z","caller":"traceutil/trace.go:171","msg":"trace[1561488923] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"181.127531ms","start":"2026-07-14T23:09:28.515923Z","end":"2026-07-14T23:09:28.69705Z","steps":["trace[1561488923] 'process raft request' (duration: 180.231655ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.920524Z","caller":"traceutil/trace.go:171","msg":"trace[235783548] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"223.804703ms","start":"2026-07-14T23:09:28.6967Z","end":"2026-07-14T23:09:28.920505Z","steps":["trace[235783548] 'process raft request' (duration: 218.383679ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.92065Z","caller":"traceutil/trace.go:171","msg":"trace[734530925] transaction","detail":"{read_only:false; number_of_response:1; response_revision:544; }","duration":"223.565089ms","start":"2026-07-14T23:09:28.697078Z","end":"2026-07-14T23:09:28.920643Z","steps":["trace[734530925] 'process raft request' (duration: 223.427246ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.920708Z","caller":"traceutil/trace.go:171","msg":"trace[684109287] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"224.001887ms","start":"2026-07-14T23:09:28.696699Z","end":"2026-07-14T23:09:28.920701Z","steps":["trace[684109287] 'process raft request' (duration: 223.727232ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.920989Z","caller":"traceutil/trace.go:171","msg":"trace[907455494] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"205.167515ms","start":"2026-07-14T23:09:28.715689Z","end":"2026-07-14T23:09:28.920857Z","steps":["trace[907455494] 'process raft request' (duration: 204.845289ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.921119Z","caller":"traceutil/trace.go:171","msg":"trace[1878016688] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"205.369367ms","start":"2026-07-14T23:09:28.715743Z","end":"2026-07-14T23:09:28.921112Z","steps":["trace[1878016688] 'process raft request' (duration: 205.010391ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.921171Z","caller":"traceutil/trace.go:171","msg":"trace[906195476] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"204.762596ms","start":"2026-07-14T23:09:28.716404Z","end":"2026-07-14T23:09:28.921166Z","steps":["trace[906195476] 'process raft request' (duration: 204.378369ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.921304Z","caller":"traceutil/trace.go:171","msg":"trace[102677066] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"196.361456ms","start":"2026-07-14T23:09:28.724932Z","end":"2026-07-14T23:09:28.921293Z","steps":["trace[102677066] 'process raft request' (duration: 196.010129ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.921396Z","caller":"traceutil/trace.go:171","msg":"trace[676384816] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"204.708656ms","start":"2026-07-14T23:09:28.716679Z","end":"2026-07-14T23:09:28.921388Z","steps":["trace[676384816] 'process raft request' (duration: 204.161285ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.921487Z","caller":"traceutil/trace.go:171","msg":"trace[340443639] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"197.991146ms","start":"2026-07-14T23:09:28.723487Z","end":"2026-07-14T23:09:28.921478Z","steps":["trace[340443639] 'process raft request' (duration: 197.379845ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.921561Z","caller":"traceutil/trace.go:171","msg":"trace[1505042907] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"196.929416ms","start":"2026-07-14T23:09:28.724622Z","end":"2026-07-14T23:09:28.921551Z","steps":["trace[1505042907] 'process raft request' (duration: 196.283984ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.921964Z","caller":"traceutil/trace.go:171","msg":"trace[1458622415] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"162.414133ms","start":"2026-07-14T23:09:28.759539Z","end":"2026-07-14T23:09:28.921953Z","steps":["trace[1458622415] 'process raft request' (duration: 161.440914ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.921293Z","caller":"traceutil/trace.go:171","msg":"trace[1203717750] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"204.708086ms","start":"2026-07-14T23:09:28.716579Z","end":"2026-07-14T23:09:28.921287Z","steps":["trace[1203717750] 'process raft request' (duration: 204.229817ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:34.813041Z","caller":"traceutil/trace.go:171","msg":"trace[1532638244] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"102.357548ms","start":"2026-07-14T23:09:34.710652Z","end":"2026-07-14T23:09:34.81301Z","steps":["trace[1532638244] 'process raft request' (duration: 101.865569ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:35.148274Z","caller":"traceutil/trace.go:171","msg":"trace[85665551] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"232.702204ms","start":"2026-07-14T23:09:34.915546Z","end":"2026-07-14T23:09:35.148248Z","steps":["trace[85665551] 'read index received' (duration: 215.757988ms)","trace[85665551] 'applied index is now lower than readState.Index' (duration: 16.942966ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:35.148315Z","caller":"traceutil/trace.go:171","msg":"trace[597530770] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"282.311678ms","start":"2026-07-14T23:09:34.865961Z","end":"2026-07-14T23:09:35.148272Z","steps":["trace[597530770] 'process raft request' (duration: 265.315341ms)","trace[597530770] 'compare' (duration: 16.704572ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:09:35.148465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.890899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:09:35.148547Z","caller":"traceutil/trace.go:171","msg":"trace[894130075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"233.044801ms","start":"2026-07-14T23:09:34.915483Z","end":"2026-07-14T23:09:35.148528Z","steps":["trace[894130075] 'agreement among raft nodes before linearized reading' (duration: 232.856908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:36.241721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824171189931894,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:09:36.382977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043788238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-14T23:09:36.383058Z","caller":"traceutil/trace.go:171","msg":"trace[1231764981] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:637; }","duration":"1.043897551s","start":"2026-07-14T23:09:35.339142Z","end":"2026-07-14T23:09:36.383039Z","steps":["trace[1231764981] 'range keys from in-memory index tree' (duration: 1.043711007s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:36.383097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:35.339122Z","time spent":"1.043966942s","remote":"127.0.0.1:49472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.86\" "} {"level":"info","ts":"2026-07-14T23:09:36.383264Z","caller":"traceutil/trace.go:171","msg":"trace[2131204403] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"642.026594ms","start":"2026-07-14T23:09:35.74121Z","end":"2026-07-14T23:09:36.383237Z","steps":["trace[2131204403] 'read index received' (duration: 561.128509ms)","trace[2131204403] 'applied index is now lower than readState.Index' (duration: 80.896945ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:09:36.38328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028127117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vffwr\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-14T23:09:36.383436Z","caller":"traceutil/trace.go:171","msg":"trace[947810567] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vffwr; range_end:; response_count:1; response_revision:637; }","duration":"1.028302741s","start":"2026-07-14T23:09:35.355115Z","end":"2026-07-14T23:09:36.383418Z","steps":["trace[947810567] 'range keys from in-memory index tree' (duration: 1.02781652s)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:36.383457Z","caller":"traceutil/trace.go:171","msg":"trace[1441269329] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"809.618515ms","start":"2026-07-14T23:09:35.573824Z","end":"2026-07-14T23:09:36.383443Z","steps":["trace[1441269329] 'process raft request' (duration: 728.857972ms)","trace[1441269329] 'compare' (duration: 80.182412ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:09:36.383489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.289969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T23:09:36.383527Z","caller":"traceutil/trace.go:171","msg":"trace[907433336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"642.330279ms","start":"2026-07-14T23:09:35.741186Z","end":"2026-07-14T23:09:36.383517Z","steps":["trace[907433336] 'agreement among raft nodes before linearized reading' (duration: 642.187757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:36.383554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:35.741096Z","time spent":"642.452852ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T23:09:36.383566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:35.355045Z","time spent":"1.028463353s","remote":"127.0.0.1:49636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-vffwr\" "} {"level":"warn","ts":"2026-07-14T23:09:36.383529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.854425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:09:36.383755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.785137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:09:36.383828Z","caller":"traceutil/trace.go:171","msg":"trace[637962715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"398.867429ms","start":"2026-07-14T23:09:35.984939Z","end":"2026-07-14T23:09:36.383807Z","steps":["trace[637962715] 'agreement among raft nodes before linearized reading' (duration: 398.691126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:36.383882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:35.984929Z","time spent":"398.9431ms","remote":"127.0.0.1:49710","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-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T23:09:36.383921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.295657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T23:09:36.38395Z","caller":"traceutil/trace.go:171","msg":"trace[1853974512] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"438.398128ms","start":"2026-07-14T23:09:35.945546Z","end":"2026-07-14T23:09:36.383944Z","steps":["trace[1853974512] 'agreement among raft nodes before linearized reading' (duration: 438.323207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:36.383974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:35.945497Z","time spent":"438.4717ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T23:09:36.383964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:35.573797Z","time spent":"809.704027ms","remote":"127.0.0.1:49540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:09:36.383768Z","caller":"traceutil/trace.go:171","msg":"trace[1432724198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"467.196143ms","start":"2026-07-14T23:09:35.916562Z","end":"2026-07-14T23:09:36.383759Z","steps":["trace[1432724198] 'agreement among raft nodes before linearized reading' (duration: 466.879607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:36.384064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:35.916527Z","time spent":"467.527818ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T23:09:36.384119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.712422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T23:09:36.384158Z","caller":"traceutil/trace.go:171","msg":"trace[648829341] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"453.788244ms","start":"2026-07-14T23:09:35.93036Z","end":"2026-07-14T23:09:36.384148Z","steps":["trace[648829341] 'agreement among raft nodes before linearized reading' (duration: 453.689632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:36.384192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:35.930334Z","time spent":"453.851746ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T23:09:38.039163Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824171189931898,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:09:38.289644Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.732004057s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-14T23:09:38.342228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.784524537s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-14T23:09:38.343127Z","caller":"traceutil/trace.go:171","msg":"trace[1216999696] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.954006746s","start":"2026-07-14T23:09:36.389096Z","end":"2026-07-14T23:09:38.343103Z","steps":["trace[1216999696] 'process raft request' (duration: 1.95374795s)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:38.343131Z","caller":"traceutil/trace.go:171","msg":"trace[1637862839] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:658; }","duration":"804.898205ms","start":"2026-07-14T23:09:37.538207Z","end":"2026-07-14T23:09:38.343106Z","steps":["trace[1637862839] 'read index received' (duration: 751.916086ms)","trace[1637862839] 'applied index is now lower than readState.Index' (duration: 52.981029ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:38.343197Z","caller":"traceutil/trace.go:171","msg":"trace[689615322] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.945625365s","start":"2026-07-14T23:09:36.39755Z","end":"2026-07-14T23:09:38.343175Z","steps":["trace[689615322] 'process raft request' (duration: 1.94539324s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:38.343234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:36.389091Z","time spent":"1.954080857s","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:09:38.343308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:36.397524Z","time spent":"1.945716386s","remote":"127.0.0.1:49636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:09:38.343373Z","caller":"traceutil/trace.go:171","msg":"trace[27296890] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.954484574s","start":"2026-07-14T23:09:36.388881Z","end":"2026-07-14T23:09:38.343365Z","steps":["trace[27296890] 'process raft request' (duration: 1.953462604s)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:38.34338Z","caller":"traceutil/trace.go:171","msg":"trace[1680190496] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.902804811s","start":"2026-07-14T23:09:36.440558Z","end":"2026-07-14T23:09:38.343363Z","steps":["trace[1680190496] 'process raft request' (duration: 1.902446274s)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:38.343361Z","caller":"traceutil/trace.go:171","msg":"trace[1000798141] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.953990806s","start":"2026-07-14T23:09:36.389333Z","end":"2026-07-14T23:09:38.343323Z","steps":["trace[1000798141] 'process raft request' (duration: 1.953561597s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:38.343429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:36.385945Z","time spent":"1.957482153s","remote":"127.0.0.1:49472","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-14T23:09:38.343438Z","caller":"traceutil/trace.go:171","msg":"trace[1880231047] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.954496435s","start":"2026-07-14T23:09:36.388934Z","end":"2026-07-14T23:09:38.343431Z","steps":["trace[1880231047] 'process raft request' (duration: 1.953849692s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:38.343471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:36.38893Z","time spent":"1.954525295s","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:09:38.343461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:36.440535Z","time spent":"1.902886522s","remote":"127.0.0.1:49684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:09:38.343528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:36.389327Z","time spent":"1.954112118s","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:09:38.343411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:36.388867Z","time spent":"1.954523756s","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:09:38.343662Z","caller":"traceutil/trace.go:171","msg":"trace[391365907] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"671.710244ms","start":"2026-07-14T23:09:37.671934Z","end":"2026-07-14T23:09:38.343644Z","steps":["trace[391365907] 'process raft request' (duration: 671.120853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:38.343672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.479585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:09:38.343706Z","caller":"traceutil/trace.go:171","msg":"trace[176668767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"805.561407ms","start":"2026-07-14T23:09:37.538136Z","end":"2026-07-14T23:09:38.343697Z","steps":["trace[176668767] 'agreement among raft nodes before linearized reading' (duration: 805.513866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:38.34371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.149572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:09:38.343725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:37.538108Z","time spent":"805.612888ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T23:09:38.343742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:37.671909Z","time spent":"671.790546ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:09:38.343751Z","caller":"traceutil/trace.go:171","msg":"trace[458642833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"427.221803ms","start":"2026-07-14T23:09:37.916515Z","end":"2026-07-14T23:09:38.343737Z","steps":["trace[458642833] 'agreement among raft nodes before linearized reading' (duration: 427.159652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:38.343786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:37.916452Z","time spent":"427.328585ms","remote":"127.0.0.1:49450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T23:09:42.647505Z","caller":"traceutil/trace.go:171","msg":"trace[1501707251] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"105.814265ms","start":"2026-07-14T23:09:42.54166Z","end":"2026-07-14T23:09:42.647474Z","steps":["trace[1501707251] 'process raft request' (duration: 105.648072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:53.111475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.451744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T23:09:53.111585Z","caller":"traceutil/trace.go:171","msg":"trace[697588853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"702.592566ms","start":"2026-07-14T23:09:52.408969Z","end":"2026-07-14T23:09:53.111562Z","steps":["trace[697588853] 'range keys from in-memory index tree' (duration: 702.295921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:53.111668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:52.408961Z","time spent":"702.693068ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T23:09:53.111687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.364512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-14T23:09:53.111698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.260037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T23:09:53.11173Z","caller":"traceutil/trace.go:171","msg":"trace[1762555743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"703.449584ms","start":"2026-07-14T23:09:52.408269Z","end":"2026-07-14T23:09:53.111718Z","steps":["trace[1762555743] 'range keys from in-memory index tree' (duration: 703.24759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:53.111764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:52.408207Z","time spent":"703.549826ms","remote":"127.0.0.1:49710","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-svcs-lock\" "} {"level":"info","ts":"2026-07-14T23:09:53.111769Z","caller":"traceutil/trace.go:171","msg":"trace[1016993908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:792; }","duration":"708.369188ms","start":"2026-07-14T23:09:52.403383Z","end":"2026-07-14T23:09:53.111752Z","steps":["trace[1016993908] 'range keys from in-memory index tree' (duration: 708.121063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:53.111808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.407014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-14T23:09:53.111818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:52.403364Z","time spent":"708.44057ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-14T23:09:53.111881Z","caller":"traceutil/trace.go:171","msg":"trace[954108942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:792; }","duration":"702.495956ms","start":"2026-07-14T23:09:52.409368Z","end":"2026-07-14T23:09:53.111864Z","steps":["trace[954108942] 'range keys from in-memory index tree' (duration: 702.313182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:53.111929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:52.409362Z","time spent":"702.553847ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T23:09:53.260102Z","caller":"traceutil/trace.go:171","msg":"trace[810355043] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"344.000224ms","start":"2026-07-14T23:09:52.916079Z","end":"2026-07-14T23:09:53.260079Z","steps":["trace[810355043] 'read index received' (duration: 343.86514ms)","trace[810355043] 'applied index is now lower than readState.Index' (duration: 134.004µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:53.260155Z","caller":"traceutil/trace.go:171","msg":"trace[1246841746] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"787.621433ms","start":"2026-07-14T23:09:52.472516Z","end":"2026-07-14T23:09:53.260138Z","steps":["trace[1246841746] 'process raft request' (duration: 787.424588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:53.260266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.144676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:09:53.260337Z","caller":"traceutil/trace.go:171","msg":"trace[556762941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"344.285239ms","start":"2026-07-14T23:09:52.916034Z","end":"2026-07-14T23:09:53.260319Z","steps":["trace[556762941] 'agreement among raft nodes before linearized reading' (duration: 344.144997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:53.260448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:52.916013Z","time spent":"344.36707ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T23:09:53.260295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:52.472492Z","time spent":"787.705964ms","remote":"127.0.0.1:49684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:09:53.261169Z","caller":"traceutil/trace.go:171","msg":"trace[484499809] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"144.235642ms","start":"2026-07-14T23:09:53.116911Z","end":"2026-07-14T23:09:53.261146Z","steps":["trace[484499809] 'process raft request' (duration: 144.192112ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:53.261533Z","caller":"traceutil/trace.go:171","msg":"trace[1647501490] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"144.828904ms","start":"2026-07-14T23:09:53.116689Z","end":"2026-07-14T23:09:53.261518Z","steps":["trace[1647501490] 'process raft request' (duration: 144.327444ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:53.261611Z","caller":"traceutil/trace.go:171","msg":"trace[1719368944] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"144.845535ms","start":"2026-07-14T23:09:53.116757Z","end":"2026-07-14T23:09:53.261603Z","steps":["trace[1719368944] 'process raft request' (duration: 144.303564ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:53.261263Z","caller":"traceutil/trace.go:171","msg":"trace[92893084] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"144.700142ms","start":"2026-07-14T23:09:53.11652Z","end":"2026-07-14T23:09:53.26122Z","steps":["trace[92893084] 'process raft request' (duration: 144.400826ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:27.597922Z","caller":"traceutil/trace.go:171","msg":"trace[2099752671] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1044; }","duration":"140.042662ms","start":"2026-07-14T23:10:27.457847Z","end":"2026-07-14T23:10:27.597889Z","steps":["trace[2099752671] 'read index received' (duration: 139.89669ms)","trace[2099752671] 'applied index is now lower than readState.Index' (duration: 145.182µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:10:27.598147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.984435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T23:10:27.598227Z","caller":"traceutil/trace.go:171","msg":"trace[2114394573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"131.088088ms","start":"2026-07-14T23:10:27.467127Z","end":"2026-07-14T23:10:27.598215Z","steps":["trace[2114394573] 'agreement among raft nodes before linearized reading' (duration: 130.940535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:27.598234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.756779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T23:10:27.5983Z","caller":"traceutil/trace.go:171","msg":"trace[419752531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"132.851972ms","start":"2026-07-14T23:10:27.465434Z","end":"2026-07-14T23:10:27.598286Z","steps":["trace[419752531] 'agreement among raft nodes before linearized reading' (duration: 132.612187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:27.598111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.250536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:10:27.598364Z","caller":"traceutil/trace.go:171","msg":"trace[27621156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1011; }","duration":"140.515991ms","start":"2026-07-14T23:10:27.457815Z","end":"2026-07-14T23:10:27.598331Z","steps":["trace[27621156] 'agreement among raft nodes before linearized reading' (duration: 140.185905ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:27.59848Z","caller":"traceutil/trace.go:171","msg":"trace[1631874466] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"141.097962ms","start":"2026-07-14T23:10:27.457367Z","end":"2026-07-14T23:10:27.598464Z","steps":["trace[1631874466] 'process raft request' (duration: 140.417738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:29.293474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012384674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-14T23:10:29.293552Z","caller":"traceutil/trace.go:171","msg":"trace[1913058003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1015; }","duration":"1.012507936s","start":"2026-07-14T23:10:28.281032Z","end":"2026-07-14T23:10:29.29354Z","steps":["trace[1913058003] 'range keys from in-memory index tree' (duration: 1.012258932s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:29.293582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:28.281015Z","time spent":"1.012559458s","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T23:10:29.293722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.747602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:10:29.29378Z","caller":"traceutil/trace.go:171","msg":"trace[1364732851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"376.844413ms","start":"2026-07-14T23:10:28.916922Z","end":"2026-07-14T23:10:29.293766Z","steps":["trace[1364732851] 'range keys from in-memory index tree' (duration: 376.64381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:29.293745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.575335ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:10:29.293807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:28.916908Z","time spent":"376.893244ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T23:10:29.293816Z","caller":"traceutil/trace.go:171","msg":"trace[2047108946] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1015; }","duration":"101.37612ms","start":"2026-07-14T23:10:29.192433Z","end":"2026-07-14T23:10:29.293809Z","steps":["trace[2047108946] 'range keys from in-memory index tree' (duration: 100.559475ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:29.489263Z","caller":"traceutil/trace.go:171","msg":"trace[141498898] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"190.959391ms","start":"2026-07-14T23:10:29.298279Z","end":"2026-07-14T23:10:29.489239Z","steps":["trace[141498898] 'process raft request' (duration: 190.794838ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:29.879567Z","caller":"traceutil/trace.go:171","msg":"trace[406240058] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"328.227919ms","start":"2026-07-14T23:10:29.551316Z","end":"2026-07-14T23:10:29.879544Z","steps":["trace[406240058] 'process raft request' (duration: 321.474637ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:29.879617Z","caller":"traceutil/trace.go:171","msg":"trace[1056665445] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"276.617786ms","start":"2026-07-14T23:10:29.602984Z","end":"2026-07-14T23:10:29.879601Z","steps":["trace[1056665445] 'read index received' (duration: 269.976997ms)","trace[1056665445] 'applied index is now lower than readState.Index' (duration: 6.639929ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:10:29.879716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:29.551297Z","time spent":"328.33227ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:10:29.879776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.788359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T23:10:29.879799Z","caller":"traceutil/trace.go:171","msg":"trace[89594069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1018; }","duration":"276.823299ms","start":"2026-07-14T23:10:29.602969Z","end":"2026-07-14T23:10:29.879792Z","steps":["trace[89594069] 'agreement among raft nodes before linearized reading' (duration: 276.723338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:29.879852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.549644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T23:10:29.879889Z","caller":"traceutil/trace.go:171","msg":"trace[66422609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1018; }","duration":"241.611706ms","start":"2026-07-14T23:10:29.638268Z","end":"2026-07-14T23:10:29.879879Z","steps":["trace[66422609] 'agreement among raft nodes before linearized reading' (duration: 241.503934ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:29.879619Z","caller":"traceutil/trace.go:171","msg":"trace[184794255] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"147.225721ms","start":"2026-07-14T23:10:29.732365Z","end":"2026-07-14T23:10:29.87959Z","steps":["trace[184794255] 'process raft request' (duration: 147.17548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:29.880002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.030725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:10:29.88003Z","caller":"traceutil/trace.go:171","msg":"trace[1228349427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1018; }","duration":"186.070566ms","start":"2026-07-14T23:10:29.693951Z","end":"2026-07-14T23:10:29.880022Z","steps":["trace[1228349427] 'agreement among raft nodes before linearized reading' (duration: 185.957464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:29.880055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.601635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T23:10:29.880076Z","caller":"traceutil/trace.go:171","msg":"trace[987288392] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1018; }","duration":"187.654367ms","start":"2026-07-14T23:10:29.692415Z","end":"2026-07-14T23:10:29.88007Z","steps":["trace[987288392] 'agreement among raft nodes before linearized reading' (duration: 187.584735ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:32.942262Z","caller":"traceutil/trace.go:171","msg":"trace[331180803] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"995.905804ms","start":"2026-07-14T23:10:31.946333Z","end":"2026-07-14T23:10:32.942238Z","steps":["trace[331180803] 'process raft request' (duration: 995.846653ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:32.942292Z","caller":"traceutil/trace.go:171","msg":"trace[92728413] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"996.488945ms","start":"2026-07-14T23:10:31.945771Z","end":"2026-07-14T23:10:32.94226Z","steps":["trace[92728413] 'process raft request' (duration: 996.23419ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:32.942363Z","caller":"traceutil/trace.go:171","msg":"trace[1601141937] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"995.992006ms","start":"2026-07-14T23:10:31.946333Z","end":"2026-07-14T23:10:32.942325Z","steps":["trace[1601141937] 'process raft request' (duration: 995.812183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:32.942402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:31.94631Z","time spent":"996.015796ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:10:32.942462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:31.945757Z","time spent":"996.606628ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:10:32.942534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:31.94631Z","time spent":"996.137618ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:10:45.172074Z","caller":"traceutil/trace.go:171","msg":"trace[1960616924] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"122.829218ms","start":"2026-07-14T23:10:45.049214Z","end":"2026-07-14T23:10:45.172043Z","steps":["trace[1960616924] 'process raft request' (duration: 79.74497ms)","trace[1960616924] 'compare' (duration: 42.965155ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:10:45.175664Z","caller":"traceutil/trace.go:171","msg":"trace[262922529] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"125.979988ms","start":"2026-07-14T23:10:45.049656Z","end":"2026-07-14T23:10:45.175636Z","steps":["trace[262922529] 'process raft request' (duration: 125.839814ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:51.350665Z","caller":"traceutil/trace.go:171","msg":"trace[1611349965] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"157.098313ms","start":"2026-07-14T23:10:51.193542Z","end":"2026-07-14T23:10:51.350641Z","steps":["trace[1611349965] 'read index received' (duration: 156.874149ms)","trace[1611349965] 'applied index is now lower than readState.Index' (duration: 223.224µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:10:51.350795Z","caller":"traceutil/trace.go:171","msg":"trace[777481381] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"277.521324ms","start":"2026-07-14T23:10:51.073225Z","end":"2026-07-14T23:10:51.350746Z","steps":["trace[777481381] 'process raft request' (duration: 277.260439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:51.350881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.315117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-14T23:10:51.350917Z","caller":"traceutil/trace.go:171","msg":"trace[5274733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1086; }","duration":"157.371078ms","start":"2026-07-14T23:10:51.193536Z","end":"2026-07-14T23:10:51.350907Z","steps":["trace[5274733] 'agreement among raft nodes before linearized reading' (duration: 157.222795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:51.350999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.508342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-14T23:10:51.351096Z","caller":"traceutil/trace.go:171","msg":"trace[654862963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1086; }","duration":"156.632145ms","start":"2026-07-14T23:10:51.194437Z","end":"2026-07-14T23:10:51.351069Z","steps":["trace[654862963] 'agreement among raft nodes before linearized reading' (duration: 156.38512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:52.307081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.44865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:10:52.307176Z","caller":"traceutil/trace.go:171","msg":"trace[420977369] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"952.216555ms","start":"2026-07-14T23:10:51.354945Z","end":"2026-07-14T23:10:52.307161Z","steps":["trace[420977369] 'process raft request' (duration: 633.620323ms)","trace[420977369] 'compare' (duration: 318.008391ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:10:52.307218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:51.354922Z","time spent":"952.276516ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:10:52.349545Z","caller":"traceutil/trace.go:171","msg":"trace[2074108714] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1125; }","duration":"737.266597ms","start":"2026-07-14T23:10:51.612255Z","end":"2026-07-14T23:10:52.349522Z","steps":["trace[2074108714] 'read index received' (duration: 376.401664ms)","trace[2074108714] 'applied index is now lower than readState.Index' (duration: 360.863783ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:10:52.349623Z","caller":"traceutil/trace.go:171","msg":"trace[295081675] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"994.447186ms","start":"2026-07-14T23:10:51.355149Z","end":"2026-07-14T23:10:52.349596Z","steps":["trace[295081675] 'process raft request' (duration: 994.261642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:52.349731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:51.355139Z","time spent":"994.530737ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:10:52.349783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.507372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-14T23:10:52.349844Z","caller":"traceutil/trace.go:171","msg":"trace[765458747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1088; }","duration":"737.630554ms","start":"2026-07-14T23:10:51.612198Z","end":"2026-07-14T23:10:52.349829Z","steps":["trace[765458747] 'agreement among raft nodes before linearized reading' (duration: 737.4318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:52.34988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:51.612185Z","time spent":"737.688565ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T23:10:53.427423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.299606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:10:53.427502Z","caller":"traceutil/trace.go:171","msg":"trace[1029190673] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"1.077884838s","start":"2026-07-14T23:10:52.349606Z","end":"2026-07-14T23:10:53.42749Z","steps":["trace[1029190673] 'read index received' (duration: 335.412139ms)","trace[1029190673] 'applied index is now lower than readState.Index' (duration: 742.471779ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:10:53.42757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130859787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:10:53.427627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.388001304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-14T23:10:53.427628Z","caller":"traceutil/trace.go:171","msg":"trace[1282090781] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1089; }","duration":"1.130954649s","start":"2026-07-14T23:10:52.296658Z","end":"2026-07-14T23:10:53.427613Z","steps":["trace[1282090781] 'agreement among raft nodes before linearized reading' (duration: 1.130841587s)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:53.427646Z","caller":"traceutil/trace.go:171","msg":"trace[314298827] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1089; }","duration":"1.388052225s","start":"2026-07-14T23:10:52.039588Z","end":"2026-07-14T23:10:53.42764Z","steps":["trace[314298827] 'agreement among raft nodes before linearized reading' (duration: 1.387962973s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:53.427665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:52.039575Z","time spent":"1.388084095s","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T23:10:53.427668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:52.296639Z","time spent":"1.13102054s","remote":"127.0.0.1:49730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-07-14T23:10:53.427684Z","caller":"traceutil/trace.go:171","msg":"trace[944412065] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"1.422654138s","start":"2026-07-14T23:10:52.004993Z","end":"2026-07-14T23:10:53.427647Z","steps":["trace[944412065] 'process raft request' (duration: 680.010465ms)","trace[944412065] 'compare' (duration: 742.110373ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:10:53.427794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:10:53.427816Z","caller":"traceutil/trace.go:171","msg":"trace[416672641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1089; }","duration":"1.511384612s","start":"2026-07-14T23:10:51.916425Z","end":"2026-07-14T23:10:53.42781Z","steps":["trace[416672641] 'agreement among raft nodes before linearized reading' (duration: 1.511353401s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:53.427831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:51.916404Z","time spent":"1.511423162s","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T23:10:53.427858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:52.004969Z","time spent":"1.422807861s","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:10:53.508271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.044008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:10:53.508349Z","caller":"traceutil/trace.go:171","msg":"trace[909652577] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1090; }","duration":"149.19922ms","start":"2026-07-14T23:10:53.359133Z","end":"2026-07-14T23:10:53.508332Z","steps":["trace[909652577] 'agreement among raft nodes before linearized reading' (duration: 148.974346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:53.508381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.199396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-14T23:10:53.50845Z","caller":"traceutil/trace.go:171","msg":"trace[195209442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"154.288778ms","start":"2026-07-14T23:10:53.354146Z","end":"2026-07-14T23:10:53.508434Z","steps":["trace[195209442] 'agreement among raft nodes before linearized reading' (duration: 154.108305ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:53.508637Z","caller":"traceutil/trace.go:171","msg":"trace[1515506061] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"1.152558679s","start":"2026-07-14T23:10:52.356051Z","end":"2026-07-14T23:10:53.50861Z","steps":["trace[1515506061] 'process raft request' (duration: 1.151858556s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:53.508639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.63019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T23:10:53.508736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:52.356026Z","time spent":"1.152650581s","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:10:53.508752Z","caller":"traceutil/trace.go:171","msg":"trace[101182274] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1090; }","duration":"464.783153ms","start":"2026-07-14T23:10:53.04394Z","end":"2026-07-14T23:10:53.508723Z","steps":["trace[101182274] 'agreement among raft nodes before linearized reading' (duration: 464.61323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:53.509411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:53.043919Z","time spent":"465.432166ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:10:53.645977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.561741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:10:53.646263Z","caller":"traceutil/trace.go:171","msg":"trace[1309817231] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"133.089806ms","start":"2026-07-14T23:10:53.513139Z","end":"2026-07-14T23:10:53.646229Z","steps":["trace[1309817231] 'process raft request' (duration: 27.186199ms)","trace[1309817231] 'compare' (duration: 105.425409ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:10:53.646132Z","caller":"traceutil/trace.go:171","msg":"trace[546172255] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"117.207367ms","start":"2026-07-14T23:10:53.528903Z","end":"2026-07-14T23:10:53.64611Z","steps":["trace[546172255] 'read index received' (duration: 11.428392ms)","trace[546172255] 'applied index is now lower than readState.Index' (duration: 105.777785ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:10:53.646366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.460622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:10:53.646403Z","caller":"traceutil/trace.go:171","msg":"trace[2095245678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1092; }","duration":"117.528694ms","start":"2026-07-14T23:10:53.528863Z","end":"2026-07-14T23:10:53.646392Z","steps":["trace[2095245678] 'agreement among raft nodes before linearized reading' (duration: 117.462392ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:54.611956Z","caller":"traceutil/trace.go:171","msg":"trace[2056513174] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"258.722118ms","start":"2026-07-14T23:10:54.353216Z","end":"2026-07-14T23:10:54.611938Z","steps":["trace[2056513174] 'read index received' (duration: 258.519314ms)","trace[2056513174] 'applied index is now lower than readState.Index' (duration: 202.134µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:10:54.612153Z","caller":"traceutil/trace.go:171","msg":"trace[1565538187] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"297.984601ms","start":"2026-07-14T23:10:54.314133Z","end":"2026-07-14T23:10:54.612118Z","steps":["trace[1565538187] 'process raft request' (duration: 297.675976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:54.612225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.990953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-14T23:10:54.612405Z","caller":"traceutil/trace.go:171","msg":"trace[1389954463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1093; }","duration":"259.218947ms","start":"2026-07-14T23:10:54.35316Z","end":"2026-07-14T23:10:54.612379Z","steps":["trace[1389954463] 'agreement among raft nodes before linearized reading' (duration: 258.949292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:55.867029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.490428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:10:55.8673Z","caller":"traceutil/trace.go:171","msg":"trace[2087844050] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"434.840462ms","start":"2026-07-14T23:10:55.432436Z","end":"2026-07-14T23:10:55.867276Z","steps":["trace[2087844050] 'process raft request' (duration: 434.73437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:55.867405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:55.432418Z","time spent":"434.939003ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:10:55.867699Z","caller":"traceutil/trace.go:171","msg":"trace[600918761] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"964.200169ms","start":"2026-07-14T23:10:54.903452Z","end":"2026-07-14T23:10:55.867652Z","steps":["trace[600918761] 'read index received' (duration: 200.857244ms)","trace[600918761] 'applied index is now lower than readState.Index' (duration: 763.339535ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:10:55.867957Z","caller":"traceutil/trace.go:171","msg":"trace[575509518] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"1.252310457s","start":"2026-07-14T23:10:54.615631Z","end":"2026-07-14T23:10:55.867941Z","steps":["trace[575509518] 'process raft request' (duration: 488.792109ms)","trace[575509518] 'compare' (duration: 762.234293ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:10:55.868159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.703148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T23:10:55.868164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.190206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-14T23:10:55.868176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.312514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-14T23:10:55.868213Z","caller":"traceutil/trace.go:171","msg":"trace[35244064] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1095; }","duration":"964.7987ms","start":"2026-07-14T23:10:54.9034Z","end":"2026-07-14T23:10:55.868198Z","steps":["trace[35244064] 'agreement among raft nodes before linearized reading' (duration: 964.710408ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:55.868223Z","caller":"traceutil/trace.go:171","msg":"trace[2115698493] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:1095; }","duration":"525.279498ms","start":"2026-07-14T23:10:55.342929Z","end":"2026-07-14T23:10:55.868208Z","steps":["trace[2115698493] 'agreement among raft nodes before linearized reading' (duration: 525.108235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:55.868282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:54.903381Z","time spent":"964.891431ms","remote":"127.0.0.1:49816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-07-14T23:10:55.86828Z","caller":"traceutil/trace.go:171","msg":"trace[1728117382] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1095; }","duration":"355.475578ms","start":"2026-07-14T23:10:55.512786Z","end":"2026-07-14T23:10:55.868262Z","steps":["trace[1728117382] 'agreement among raft nodes before linearized reading' (duration: 355.247904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:55.868323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.986486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-14T23:10:55.868334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:55.512764Z","time spent":"355.55826ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T23:10:55.868377Z","caller":"traceutil/trace.go:171","msg":"trace[910041916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1095; }","duration":"218.031717ms","start":"2026-07-14T23:10:55.650328Z","end":"2026-07-14T23:10:55.86836Z","steps":["trace[910041916] 'agreement among raft nodes before linearized reading' (duration: 217.862374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:55.868439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.771642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-14T23:10:55.868321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:55.342909Z","time spent":"525.39982ms","remote":"127.0.0.1:49472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.86\" "} {"level":"info","ts":"2026-07-14T23:10:55.86849Z","caller":"traceutil/trace.go:171","msg":"trace[558423033] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1095; }","duration":"341.862814ms","start":"2026-07-14T23:10:55.526615Z","end":"2026-07-14T23:10:55.868478Z","steps":["trace[558423033] 'agreement among raft nodes before linearized reading' (duration: 341.736771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:55.86853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:55.526594Z","time spent":"341.925276ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T23:10:55.868131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.798553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:10:55.868627Z","caller":"traceutil/trace.go:171","msg":"trace[395051250] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1095; }","duration":"306.339034ms","start":"2026-07-14T23:10:55.56227Z","end":"2026-07-14T23:10:55.868609Z","steps":["trace[395051250] 'agreement among raft nodes before linearized reading' (duration: 305.761572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:55.868693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:55.562259Z","time spent":"306.414705ms","remote":"127.0.0.1:50782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:10:55.868594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.105733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:10:55.869327Z","caller":"traceutil/trace.go:171","msg":"trace[898238242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"952.355108ms","start":"2026-07-14T23:10:54.916947Z","end":"2026-07-14T23:10:55.869302Z","steps":["trace[898238242] 'agreement among raft nodes before linearized reading' (duration: 951.033122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:55.869459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:54.916926Z","time spent":"952.516231ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T23:10:55.869901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:54.615611Z","time spent":"1.252387048s","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:10:56.37302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.147305ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-14T23:10:56.373117Z","caller":"traceutil/trace.go:171","msg":"trace[730441279] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"502.289131ms","start":"2026-07-14T23:10:55.87081Z","end":"2026-07-14T23:10:56.373099Z","steps":["trace[730441279] 'read index received' (duration: 329.064165ms)","trace[730441279] 'applied index is now lower than readState.Index' (duration: 173.223246ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:10:56.373204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:55.87028Z","time spent":"502.920083ms","remote":"127.0.0.1:49472","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-14T23:10:56.373342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.487205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:10:56.373456Z","caller":"traceutil/trace.go:171","msg":"trace[1125754056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"502.659359ms","start":"2026-07-14T23:10:55.870779Z","end":"2026-07-14T23:10:56.373438Z","steps":["trace[1125754056] 'agreement among raft nodes before linearized reading' (duration: 502.458565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:56.373493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:55.870764Z","time spent":"502.72278ms","remote":"127.0.0.1:49450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T23:10:56.465933Z","caller":"traceutil/trace.go:171","msg":"trace[167848324] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"592.704778ms","start":"2026-07-14T23:10:55.873206Z","end":"2026-07-14T23:10:56.465911Z","steps":["trace[167848324] 'process raft request' (duration: 592.661157ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:56.465978Z","caller":"traceutil/trace.go:171","msg":"trace[367511697] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"593.690118ms","start":"2026-07-14T23:10:55.872263Z","end":"2026-07-14T23:10:56.465953Z","steps":["trace[367511697] 'process raft request' (duration: 593.380862ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:56.466014Z","caller":"traceutil/trace.go:171","msg":"trace[1753962326] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"593.333891ms","start":"2026-07-14T23:10:55.872634Z","end":"2026-07-14T23:10:56.465968Z","steps":["trace[1753962326] 'process raft request' (duration: 593.182038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:56.466107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:55.873188Z","time spent":"592.835461ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:10:56.466138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:55.872247Z","time spent":"593.783779ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:10:56.46617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:55.872614Z","time spent":"593.469853ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:10:56.655584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.51567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:10:56.655759Z","caller":"traceutil/trace.go:171","msg":"trace[583321677] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"341.64504ms","start":"2026-07-14T23:10:56.314095Z","end":"2026-07-14T23:10:56.65574Z","steps":["trace[583321677] 'process raft request' (duration: 161.877975ms)","trace[583321677] 'compare' (duration: 179.344646ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:10:56.655853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:56.314072Z","time spent":"341.730111ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:10:56.656114Z","caller":"traceutil/trace.go:171","msg":"trace[514559655] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1136; }","duration":"282.934479ms","start":"2026-07-14T23:10:56.373143Z","end":"2026-07-14T23:10:56.656077Z","steps":["trace[514559655] 'read index received' (duration: 92.6422ms)","trace[514559655] 'applied index is now lower than readState.Index' (duration: 190.290009ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:10:56.656275Z","caller":"traceutil/trace.go:171","msg":"trace[623039691] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"281.775436ms","start":"2026-07-14T23:10:56.374325Z","end":"2026-07-14T23:10:56.6561Z","steps":["trace[623039691] 'process raft request' (duration: 281.363059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:56.65638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.746569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:10:56.656785Z","caller":"traceutil/trace.go:171","msg":"trace[203064441] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1100; }","duration":"558.196209ms","start":"2026-07-14T23:10:56.098559Z","end":"2026-07-14T23:10:56.656756Z","steps":["trace[203064441] 'agreement among raft nodes before linearized reading' (duration: 557.710438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:56.656883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:56.09853Z","time spent":"558.31643ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:10:56.656484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.312913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:10:56.657Z","caller":"traceutil/trace.go:171","msg":"trace[2072998596] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1100; }","duration":"128.847404ms","start":"2026-07-14T23:10:56.528126Z","end":"2026-07-14T23:10:56.656973Z","steps":["trace[2072998596] 'agreement among raft nodes before linearized reading' (duration: 128.308053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:56.656509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.79731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:10:56.657157Z","caller":"traceutil/trace.go:171","msg":"trace[399122183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1100; }","duration":"275.504244ms","start":"2026-07-14T23:10:56.381635Z","end":"2026-07-14T23:10:56.657139Z","steps":["trace[399122183] 'agreement among raft nodes before linearized reading' (duration: 274.773539ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:58.204643Z","caller":"traceutil/trace.go:171","msg":"trace[1081609627] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"330.998682ms","start":"2026-07-14T23:10:57.873617Z","end":"2026-07-14T23:10:58.204615Z","steps":["trace[1081609627] 'read index received' (duration: 330.821388ms)","trace[1081609627] 'applied index is now lower than readState.Index' (duration: 176.294µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:10:58.20473Z","caller":"traceutil/trace.go:171","msg":"trace[1703783048] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"331.643375ms","start":"2026-07-14T23:10:57.873023Z","end":"2026-07-14T23:10:58.204666Z","steps":["trace[1703783048] 'process raft request' (duration: 331.41717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:58.204814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.561548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:10:58.204847Z","caller":"traceutil/trace.go:171","msg":"trace[1536122129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1102; }","duration":"288.636179ms","start":"2026-07-14T23:10:57.9162Z","end":"2026-07-14T23:10:58.204836Z","steps":["trace[1536122129] 'agreement among raft nodes before linearized reading' (duration: 288.561497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:58.204893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.262357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-14T23:10:58.204904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:57.873001Z","time spent":"331.811938ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:10:58.204909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.252304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:10:58.20495Z","caller":"traceutil/trace.go:171","msg":"trace[2078188337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1102; }","duration":"331.359459ms","start":"2026-07-14T23:10:57.873574Z","end":"2026-07-14T23:10:58.204934Z","steps":["trace[2078188337] 'agreement among raft nodes before linearized reading' (duration: 331.164665ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:58.20498Z","caller":"traceutil/trace.go:171","msg":"trace[809482692] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1102; }","duration":"266.354216ms","start":"2026-07-14T23:10:57.938608Z","end":"2026-07-14T23:10:58.204962Z","steps":["trace[809482692] 'agreement among raft nodes before linearized reading' (duration: 266.232374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:58.204997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:57.873556Z","time spent":"331.42589ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-14T23:11:06.679457Z","caller":"traceutil/trace.go:171","msg":"trace[1233555134] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"168.667258ms","start":"2026-07-14T23:11:06.510743Z","end":"2026-07-14T23:11:06.67941Z","steps":["trace[1233555134] 'process raft request' (duration: 149.910493ms)","trace[1233555134] 'compare' (duration: 18.389788ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:06.679883Z","caller":"traceutil/trace.go:171","msg":"trace[208485059] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"116.446042ms","start":"2026-07-14T23:11:06.563402Z","end":"2026-07-14T23:11:06.679848Z","steps":["trace[208485059] 'process raft request' (duration: 116.34214ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:08.368595Z","caller":"traceutil/trace.go:171","msg":"trace[1166447595] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"113.294092ms","start":"2026-07-14T23:11:08.255223Z","end":"2026-07-14T23:11:08.368517Z","steps":["trace[1166447595] 'process raft request' (duration: 113.044567ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:08.529299Z","caller":"traceutil/trace.go:171","msg":"trace[529038070] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"155.541993ms","start":"2026-07-14T23:11:08.373721Z","end":"2026-07-14T23:11:08.529263Z","steps":["trace[529038070] 'process raft request' (duration: 85.332529ms)","trace[529038070] 'compare' (duration: 69.301946ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:08.742596Z","caller":"traceutil/trace.go:171","msg":"trace[1939963860] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"175.677354ms","start":"2026-07-14T23:11:08.566894Z","end":"2026-07-14T23:11:08.742571Z","steps":["trace[1939963860] 'process raft request' (duration: 175.090343ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:08.890569Z","caller":"traceutil/trace.go:171","msg":"trace[968367868] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"144.173532ms","start":"2026-07-14T23:11:08.746363Z","end":"2026-07-14T23:11:08.890537Z","steps":["trace[968367868] 'process raft request' (duration: 128.142871ms)","trace[968367868] 'compare' (duration: 15.800167ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:08.890986Z","caller":"traceutil/trace.go:171","msg":"trace[753340624] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"143.216814ms","start":"2026-07-14T23:11:08.747735Z","end":"2026-07-14T23:11:08.890952Z","steps":["trace[753340624] 'process raft request' (duration: 142.699754ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:08.891099Z","caller":"traceutil/trace.go:171","msg":"trace[791688638] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"143.224933ms","start":"2026-07-14T23:11:08.747848Z","end":"2026-07-14T23:11:08.891072Z","steps":["trace[791688638] 'process raft request' (duration: 142.954208ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:11.074417Z","caller":"traceutil/trace.go:171","msg":"trace[1197492643] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"174.306338ms","start":"2026-07-14T23:11:10.900086Z","end":"2026-07-14T23:11:11.074392Z","steps":["trace[1197492643] 'process raft request' (duration: 174.151785ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:11.078112Z","caller":"traceutil/trace.go:171","msg":"trace[70304768] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"177.837756ms","start":"2026-07-14T23:11:10.900253Z","end":"2026-07-14T23:11:11.078091Z","steps":["trace[70304768] 'process raft request' (duration: 177.653283ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:11.078271Z","caller":"traceutil/trace.go:171","msg":"trace[555631138] linearizableReadLoop","detail":"{readStateIndex:1189; appliedIndex:1186; }","duration":"162.899846ms","start":"2026-07-14T23:11:10.915352Z","end":"2026-07-14T23:11:11.078252Z","steps":["trace[555631138] 'read index received' (duration: 158.871848ms)","trace[555631138] 'applied index is now lower than readState.Index' (duration: 4.026218ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:11.078323Z","caller":"traceutil/trace.go:171","msg":"trace[67785325] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"177.100782ms","start":"2026-07-14T23:11:10.901192Z","end":"2026-07-14T23:11:11.078293Z","steps":["trace[67785325] 'process raft request' (duration: 176.856396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:11.078375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.992229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:11:11.07841Z","caller":"traceutil/trace.go:171","msg":"trace[918156527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1147; }","duration":"163.08264ms","start":"2026-07-14T23:11:10.915317Z","end":"2026-07-14T23:11:11.078399Z","steps":["trace[918156527] 'agreement among raft nodes before linearized reading' (duration: 162.996458ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:17.818083Z","caller":"traceutil/trace.go:171","msg":"trace[823374895] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"438.150105ms","start":"2026-07-14T23:11:17.379913Z","end":"2026-07-14T23:11:17.818063Z","steps":["trace[823374895] 'process raft request' (duration: 438.008372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:17.818223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:17.379889Z","time spent":"438.257607ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:11:19.070837Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824171189933161,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:11:19.571046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824171189933161,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:11:19.862134Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.454617679s","expected-duration":"1s"} {"level":"info","ts":"2026-07-14T23:11:19.862723Z","caller":"traceutil/trace.go:171","msg":"trace[1783739784] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1268; }","duration":"1.292657332s","start":"2026-07-14T23:11:18.57003Z","end":"2026-07-14T23:11:19.862688Z","steps":["trace[1783739784] 'read index received' (duration: 1.292235583s)","trace[1783739784] 'applied index is now lower than readState.Index' (duration: 419.779µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:19.86294Z","caller":"traceutil/trace.go:171","msg":"trace[734392181] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"1.455515317s","start":"2026-07-14T23:11:18.4074Z","end":"2026-07-14T23:11:19.862916Z","steps":["trace[734392181] 'process raft request' (duration: 1.455008366s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:19.863143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:18.407376Z","time spent":"1.455642799s","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:11:19.863179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293114921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-14T23:11:19.863177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.322622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-14T23:11:19.863264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.883311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-14T23:11:19.863252Z","caller":"traceutil/trace.go:171","msg":"trace[1201498803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1223; }","duration":"1.293239993s","start":"2026-07-14T23:11:18.569986Z","end":"2026-07-14T23:11:19.863226Z","steps":["trace[1201498803] 'agreement among raft nodes before linearized reading' (duration: 1.293014928s)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:19.863307Z","caller":"traceutil/trace.go:171","msg":"trace[620439766] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1223; }","duration":"699.936393ms","start":"2026-07-14T23:11:19.16336Z","end":"2026-07-14T23:11:19.863297Z","steps":["trace[620439766] 'agreement among raft nodes before linearized reading' (duration: 699.621417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:19.863335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.380699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-14T23:11:19.863324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:18.569968Z","time spent":"1.293343465s","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T23:11:19.863346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:19.163353Z","time spent":"699.984864ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T23:11:19.863364Z","caller":"traceutil/trace.go:171","msg":"trace[1443606639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1223; }","duration":"705.436109ms","start":"2026-07-14T23:11:19.157919Z","end":"2026-07-14T23:11:19.863355Z","steps":["trace[1443606639] 'agreement among raft nodes before linearized reading' (duration: 705.365448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:19.863398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:19.157902Z","time spent":"705.487711ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T23:11:19.863176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062745723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T23:11:19.863261Z","caller":"traceutil/trace.go:171","msg":"trace[1036777998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1223; }","duration":"697.434954ms","start":"2026-07-14T23:11:19.165801Z","end":"2026-07-14T23:11:19.863236Z","steps":["trace[1036777998] 'agreement among raft nodes before linearized reading' (duration: 697.2358ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:19.863473Z","caller":"traceutil/trace.go:171","msg":"trace[1083435084] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1223; }","duration":"1.063075399s","start":"2026-07-14T23:11:18.80039Z","end":"2026-07-14T23:11:19.863466Z","steps":["trace[1083435084] 'agreement among raft nodes before linearized reading' (duration: 1.06259275s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:19.863493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.512193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:11:19.863504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:18.800329Z","time spent":"1.063168491s","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T23:11:19.863519Z","caller":"traceutil/trace.go:171","msg":"trace[631854671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1223; }","duration":"949.536593ms","start":"2026-07-14T23:11:18.913973Z","end":"2026-07-14T23:11:19.86351Z","steps":["trace[631854671] 'agreement among raft nodes before linearized reading' (duration: 949.491332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:19.863689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:18.913927Z","time spent":"949.740577ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T23:11:19.863513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:19.165796Z","time spent":"697.703769ms","remote":"127.0.0.1:49710","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-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T23:11:20.480946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.523625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:11:20.481256Z","caller":"traceutil/trace.go:171","msg":"trace[570272714] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1269; }","duration":"566.270784ms","start":"2026-07-14T23:11:19.914931Z","end":"2026-07-14T23:11:20.481201Z","steps":["trace[570272714] 'read index received' (duration: 44.213618ms)","trace[570272714] 'applied index is now lower than readState.Index' (duration: 522.055726ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:20.481265Z","caller":"traceutil/trace.go:171","msg":"trace[720924333] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"613.106846ms","start":"2026-07-14T23:11:19.86812Z","end":"2026-07-14T23:11:20.481227Z","steps":["trace[720924333] 'process raft request' (duration: 612.917652ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:20.481347Z","caller":"traceutil/trace.go:171","msg":"trace[2056187257] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"613.014802ms","start":"2026-07-14T23:11:19.868301Z","end":"2026-07-14T23:11:20.481316Z","steps":["trace[2056187257] 'process raft request' (duration: 612.794408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:20.481408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:19.868287Z","time spent":"613.086605ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:11:20.481445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:19.868094Z","time spent":"613.250568ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:11:20.481592Z","caller":"traceutil/trace.go:171","msg":"trace[1114451012] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"613.272438ms","start":"2026-07-14T23:11:19.868308Z","end":"2026-07-14T23:11:20.481581Z","steps":["trace[1114451012] 'process raft request' (duration: 612.828659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:20.481646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:19.868301Z","time spent":"613.316759ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:11:20.481695Z","caller":"traceutil/trace.go:171","msg":"trace[1395032598] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"613.902801ms","start":"2026-07-14T23:11:19.867777Z","end":"2026-07-14T23:11:20.48168Z","steps":["trace[1395032598] 'process raft request' (duration: 91.394217ms)","trace[1395032598] 'compare' (duration: 521.299901ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:11:20.481757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:19.867749Z","time spent":"613.974023ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:11:20.481771Z","caller":"traceutil/trace.go:171","msg":"trace[108285465] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"613.411591ms","start":"2026-07-14T23:11:19.86835Z","end":"2026-07-14T23:11:20.481762Z","steps":["trace[108285465] 'process raft request' (duration: 612.822329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:20.48183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:19.868345Z","time spent":"613.448382ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:11:20.496929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.513498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:11:20.497018Z","caller":"traceutil/trace.go:171","msg":"trace[43444047] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1228; }","duration":"393.6162ms","start":"2026-07-14T23:11:20.103384Z","end":"2026-07-14T23:11:20.497001Z","steps":["trace[43444047] 'agreement among raft nodes before linearized reading' (duration: 393.480857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:20.497063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:20.103368Z","time spent":"393.684572ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:11:20.497438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.518641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:11:20.497476Z","caller":"traceutil/trace.go:171","msg":"trace[1892076685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"582.605552ms","start":"2026-07-14T23:11:19.914859Z","end":"2026-07-14T23:11:20.497465Z","steps":["trace[1892076685] 'agreement among raft nodes before linearized reading' (duration: 567.06695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:20.497509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:19.914829Z","time spent":"582.671263ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T23:11:20.497795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.217797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:11:20.497829Z","caller":"traceutil/trace.go:171","msg":"trace[249598261] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1228; }","duration":"314.288767ms","start":"2026-07-14T23:11:20.183531Z","end":"2026-07-14T23:11:20.49782Z","steps":["trace[249598261] 'agreement among raft nodes before linearized reading' (duration: 314.120164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:20.497868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:20.183511Z","time spent":"314.349729ms","remote":"127.0.0.1:49842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-07-14T23:11:22.654101Z","caller":"traceutil/trace.go:171","msg":"trace[1536331024] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"161.422467ms","start":"2026-07-14T23:11:22.492655Z","end":"2026-07-14T23:11:22.654078Z","steps":["trace[1536331024] 'process raft request' (duration: 161.349665ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:22.654275Z","caller":"traceutil/trace.go:171","msg":"trace[156789996] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"161.643441ms","start":"2026-07-14T23:11:22.49262Z","end":"2026-07-14T23:11:22.654263Z","steps":["trace[156789996] 'process raft request' (duration: 161.232294ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:22.654425Z","caller":"traceutil/trace.go:171","msg":"trace[1390495413] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"161.716962ms","start":"2026-07-14T23:11:22.492699Z","end":"2026-07-14T23:11:22.654416Z","steps":["trace[1390495413] 'process raft request' (duration: 161.344525ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:22.65469Z","caller":"traceutil/trace.go:171","msg":"trace[553297174] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"161.912697ms","start":"2026-07-14T23:11:22.492754Z","end":"2026-07-14T23:11:22.654666Z","steps":["trace[553297174] 'process raft request' (duration: 161.627181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:25.522103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.360079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:11:25.522217Z","caller":"traceutil/trace.go:171","msg":"trace[354931001] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"156.775367ms","start":"2026-07-14T23:11:25.365424Z","end":"2026-07-14T23:11:25.522199Z","steps":["trace[354931001] 'process raft request' (duration: 52.244725ms)","trace[354931001] 'compare' (duration: 104.231216ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:26.687478Z","caller":"traceutil/trace.go:171","msg":"trace[718032313] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"119.028143ms","start":"2026-07-14T23:11:26.56843Z","end":"2026-07-14T23:11:26.687458Z","steps":["trace[718032313] 'process raft request' (duration: 118.726368ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:32.834443Z","caller":"traceutil/trace.go:171","msg":"trace[1123156755] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"113.313089ms","start":"2026-07-14T23:11:32.721097Z","end":"2026-07-14T23:11:32.83441Z","steps":["trace[1123156755] 'process raft request' (duration: 67.102862ms)","trace[1123156755] 'compare' (duration: 46.045324ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:32.834437Z","caller":"traceutil/trace.go:171","msg":"trace[451406932] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"100.793317ms","start":"2026-07-14T23:11:32.733625Z","end":"2026-07-14T23:11:32.834418Z","steps":["trace[451406932] 'read index received' (duration: 54.781913ms)","trace[451406932] 'applied index is now lower than readState.Index' (duration: 46.007694ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:11:32.83476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.240759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T23:11:32.834851Z","caller":"traceutil/trace.go:171","msg":"trace[1332269191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1266; }","duration":"112.338101ms","start":"2026-07-14T23:11:32.722476Z","end":"2026-07-14T23:11:32.834814Z","steps":["trace[1332269191] 'agreement among raft nodes before linearized reading' (duration: 112.007284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:32.834848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.057845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T23:11:32.834984Z","caller":"traceutil/trace.go:171","msg":"trace[1484120169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1266; }","duration":"112.31027ms","start":"2026-07-14T23:11:32.722635Z","end":"2026-07-14T23:11:32.834946Z","steps":["trace[1484120169] 'agreement among raft nodes before linearized reading' (duration: 111.78588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:33.079637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.003929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:11:33.079849Z","caller":"traceutil/trace.go:171","msg":"trace[963545233] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1315; }","duration":"163.410662ms","start":"2026-07-14T23:11:32.916416Z","end":"2026-07-14T23:11:33.079827Z","steps":["trace[963545233] 'read index received' (duration: 27.117226ms)","trace[963545233] 'applied index is now lower than readState.Index' (duration: 136.292316ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:11:33.080007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.592276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:11:33.080071Z","caller":"traceutil/trace.go:171","msg":"trace[388273627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1268; }","duration":"163.696277ms","start":"2026-07-14T23:11:32.91636Z","end":"2026-07-14T23:11:33.080056Z","steps":["trace[388273627] 'agreement among raft nodes before linearized reading' (duration: 163.544964ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:33.080086Z","caller":"traceutil/trace.go:171","msg":"trace[1213790770] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"240.568ms","start":"2026-07-14T23:11:32.83949Z","end":"2026-07-14T23:11:33.080058Z","steps":["trace[1213790770] 'process raft request' (duration: 104.05264ms)","trace[1213790770] 'compare' (duration: 135.867537ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:33.081238Z","caller":"traceutil/trace.go:171","msg":"trace[1669514286] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"240.951326ms","start":"2026-07-14T23:11:32.84025Z","end":"2026-07-14T23:11:33.081201Z","steps":["trace[1669514286] 'process raft request' (duration: 239.506338ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:35.51515Z","caller":"traceutil/trace.go:171","msg":"trace[188387337] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"427.837135ms","start":"2026-07-14T23:11:35.087292Z","end":"2026-07-14T23:11:35.515129Z","steps":["trace[188387337] 'process raft request' (duration: 427.714212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:35.515287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:35.087278Z","time spent":"427.935006ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:11:35.65489Z","caller":"traceutil/trace.go:171","msg":"trace[63611636] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"567.170618ms","start":"2026-07-14T23:11:35.087692Z","end":"2026-07-14T23:11:35.654862Z","steps":["trace[63611636] 'process raft request' (duration: 496.21723ms)","trace[63611636] 'compare' (duration: 70.734153ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:11:35.655042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:35.087684Z","time spent":"567.28153ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:11:35.655171Z","caller":"traceutil/trace.go:171","msg":"trace[1996987983] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"312.957545ms","start":"2026-07-14T23:11:35.342187Z","end":"2026-07-14T23:11:35.655144Z","steps":["trace[1996987983] 'process raft request' (duration: 312.643849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:35.65539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:35.34213Z","time spent":"313.160319ms","remote":"127.0.0.1:49516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":870,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T23:11:35.655029Z","caller":"traceutil/trace.go:171","msg":"trace[349804286] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1329; }","duration":"417.908922ms","start":"2026-07-14T23:11:35.237094Z","end":"2026-07-14T23:11:35.655003Z","steps":["trace[349804286] 'read index received' (duration: 277.928875ms)","trace[349804286] 'applied index is now lower than readState.Index' (duration: 139.978066ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:11:35.655437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.322389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:11:35.655488Z","caller":"traceutil/trace.go:171","msg":"trace[155485342] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1283; }","duration":"418.413211ms","start":"2026-07-14T23:11:35.23706Z","end":"2026-07-14T23:11:35.655473Z","steps":["trace[155485342] 'agreement among raft nodes before linearized reading' (duration: 418.169247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:35.655523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:35.237043Z","time spent":"418.470002ms","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:11:35.655621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.345068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:11:35.655698Z","caller":"traceutil/trace.go:171","msg":"trace[875616737] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1283; }","duration":"133.418739ms","start":"2026-07-14T23:11:35.522236Z","end":"2026-07-14T23:11:35.655654Z","steps":["trace[875616737] 'agreement among raft nodes before linearized reading' (duration: 133.311667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:35.655698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.385594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-14T23:11:35.655779Z","caller":"traceutil/trace.go:171","msg":"trace[553053909] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:1283; }","duration":"311.540356ms","start":"2026-07-14T23:11:35.344209Z","end":"2026-07-14T23:11:35.655749Z","steps":["trace[553053909] 'agreement among raft nodes before linearized reading' (duration: 311.322482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:35.655823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:35.344189Z","time spent":"311.625598ms","remote":"127.0.0.1:49472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.86\" "} {"level":"warn","ts":"2026-07-14T23:11:36.607661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.39754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:11:36.607739Z","caller":"traceutil/trace.go:171","msg":"trace[1635963288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1284; }","duration":"690.516773ms","start":"2026-07-14T23:11:35.917203Z","end":"2026-07-14T23:11:36.60772Z","steps":["trace[1635963288] 'range keys from in-memory index tree' (duration: 690.312129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:36.607808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:35.917183Z","time spent":"690.613054ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T23:11:36.607839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.628447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:11:36.607935Z","caller":"traceutil/trace.go:171","msg":"trace[1346770330] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"573.762835ms","start":"2026-07-14T23:11:36.034152Z","end":"2026-07-14T23:11:36.607915Z","steps":["trace[1346770330] 'process raft request' (duration: 258.967185ms)","trace[1346770330] 'compare' (duration: 314.411543ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:11:36.607994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:36.034133Z","time spent":"573.831997ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:11:37.236187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.161353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T23:11:37.236283Z","caller":"traceutil/trace.go:171","msg":"trace[1758890224] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1285; }","duration":"390.269995ms","start":"2026-07-14T23:11:36.845995Z","end":"2026-07-14T23:11:37.236265Z","steps":["trace[1758890224] 'range keys from in-memory index tree' (duration: 389.98529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:37.236324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:36.845937Z","time spent":"390.378227ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T23:11:37.236358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.53841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-14T23:11:37.236368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.391358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-14T23:11:37.236445Z","caller":"traceutil/trace.go:171","msg":"trace[1603267474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1285; }","duration":"388.646652ms","start":"2026-07-14T23:11:36.847779Z","end":"2026-07-14T23:11:37.236425Z","steps":["trace[1603267474] 'range keys from in-memory index tree' (duration: 388.296267ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:37.236453Z","caller":"traceutil/trace.go:171","msg":"trace[1137320] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1285; }","duration":"492.52384ms","start":"2026-07-14T23:11:36.743911Z","end":"2026-07-14T23:11:37.236435Z","steps":["trace[1137320] 'range keys from in-memory index tree' (duration: 492.159432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:37.236496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:36.743883Z","time spent":"492.603962ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T23:11:37.236496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:36.84772Z","time spent":"388.766825ms","remote":"127.0.0.1:49710","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-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T23:11:38.220978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.114666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:11:38.221095Z","caller":"traceutil/trace.go:171","msg":"trace[447147559] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"188.427877ms","start":"2026-07-14T23:11:38.032649Z","end":"2026-07-14T23:11:38.221077Z","steps":["trace[447147559] 'process raft request' (duration: 43.127527ms)","trace[447147559] 'compare' (duration: 144.983804ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:11:39.789499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.352383399s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"warn","ts":"2026-07-14T23:11:39.789484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.251973ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:11:39.789597Z","caller":"traceutil/trace.go:171","msg":"trace[1337003827] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1294; }","duration":"1.352508761s","start":"2026-07-14T23:11:38.437062Z","end":"2026-07-14T23:11:39.78957Z","steps":["trace[1337003827] 'range keys from in-memory index tree' (duration: 1.352258197s)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:39.789625Z","caller":"traceutil/trace.go:171","msg":"trace[738052095] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1294; }","duration":"596.398966ms","start":"2026-07-14T23:11:39.193201Z","end":"2026-07-14T23:11:39.7896Z","steps":["trace[738052095] 'range keys from in-memory index tree' (duration: 596.232843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:39.789709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:38.437031Z","time spent":"1.352597883s","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5354,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-14T23:11:39.790314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.302523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:11:39.790372Z","caller":"traceutil/trace.go:171","msg":"trace[2001731279] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"873.664877ms","start":"2026-07-14T23:11:38.916696Z","end":"2026-07-14T23:11:39.790361Z","steps":["trace[2001731279] 'read index received' (duration: 30.141µs)","trace[2001731279] 'applied index is now lower than readState.Index' (duration: 873.633756ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:11:39.790482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.777359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:11:39.790526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.11669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T23:11:39.790537Z","caller":"traceutil/trace.go:171","msg":"trace[1860289120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1295; }","duration":"873.85435ms","start":"2026-07-14T23:11:38.916667Z","end":"2026-07-14T23:11:39.790521Z","steps":["trace[1860289120] 'agreement among raft nodes before linearized reading' (duration: 873.778959ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:39.790562Z","caller":"traceutil/trace.go:171","msg":"trace[716526514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1295; }","duration":"511.185862ms","start":"2026-07-14T23:11:39.279358Z","end":"2026-07-14T23:11:39.790544Z","steps":["trace[716526514] 'agreement among raft nodes before linearized reading' (duration: 511.09597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:39.790578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:38.916654Z","time spent":"873.913862ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T23:11:39.790603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:39.279338Z","time spent":"511.251892ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-14T23:11:39.790654Z","caller":"traceutil/trace.go:171","msg":"trace[1836530250] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"1.177124466s","start":"2026-07-14T23:11:38.6135Z","end":"2026-07-14T23:11:39.790624Z","steps":["trace[1836530250] 'process raft request' (duration: 241.451306ms)","trace[1836530250] 'compare' (duration: 934.12904ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:11:39.790687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.865269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:11:39.790734Z","caller":"traceutil/trace.go:171","msg":"trace[1887837070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1295; }","duration":"471.956951ms","start":"2026-07-14T23:11:39.318765Z","end":"2026-07-14T23:11:39.790722Z","steps":["trace[1887837070] 'agreement among raft nodes before linearized reading' (duration: 471.796217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:39.790741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.343523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-14T23:11:39.790769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:39.318739Z","time spent":"472.021823ms","remote":"127.0.0.1:49710","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-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T23:11:39.790803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:38.613485Z","time spent":"1.177237329s","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:11:39.790817Z","caller":"traceutil/trace.go:171","msg":"trace[1466850496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1295; }","duration":"244.463835ms","start":"2026-07-14T23:11:39.546335Z","end":"2026-07-14T23:11:39.790799Z","steps":["trace[1466850496] 'agreement among raft nodes before linearized reading' (duration: 244.196419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:39.790971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.005519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T23:11:39.791033Z","caller":"traceutil/trace.go:171","msg":"trace[1422525600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1295; }","duration":"102.092862ms","start":"2026-07-14T23:11:39.688928Z","end":"2026-07-14T23:11:39.79102Z","steps":["trace[1422525600] 'agreement among raft nodes before linearized reading' (duration: 101.958339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:39.792652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.921628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-14T23:11:39.792696Z","caller":"traceutil/trace.go:171","msg":"trace[164134894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1295; }","duration":"475.010509ms","start":"2026-07-14T23:11:39.317675Z","end":"2026-07-14T23:11:39.792686Z","steps":["trace[164134894] 'agreement among raft nodes before linearized reading' (duration: 474.883676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:39.792729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:39.317648Z","time spent":"475.07416ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T23:11:40.004689Z","caller":"traceutil/trace.go:171","msg":"trace[1339641455] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"209.505166ms","start":"2026-07-14T23:11:39.795154Z","end":"2026-07-14T23:11:40.004659Z","steps":["trace[1339641455] 'process raft request' (duration: 116.580452ms)","trace[1339641455] 'compare' (duration: 92.74621ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:40.057186Z","caller":"traceutil/trace.go:171","msg":"trace[180587494] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"261.014327ms","start":"2026-07-14T23:11:39.79614Z","end":"2026-07-14T23:11:40.057154Z","steps":["trace[180587494] 'process raft request' (duration: 260.741992ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:40.05721Z","caller":"traceutil/trace.go:171","msg":"trace[505055] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"260.906464ms","start":"2026-07-14T23:11:39.796274Z","end":"2026-07-14T23:11:40.057181Z","steps":["trace[505055] 'process raft request' (duration: 260.73332ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:40.057258Z","caller":"traceutil/trace.go:171","msg":"trace[1974898504] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1346; }","duration":"131.592935ms","start":"2026-07-14T23:11:39.925638Z","end":"2026-07-14T23:11:40.057231Z","steps":["trace[1974898504] 'read index received' (duration: 131.157326ms)","trace[1974898504] 'applied index is now lower than readState.Index' (duration: 434.489µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:40.057362Z","caller":"traceutil/trace.go:171","msg":"trace[825428445] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"260.061458ms","start":"2026-07-14T23:11:39.797292Z","end":"2026-07-14T23:11:40.057353Z","steps":["trace[825428445] 'process raft request' (duration: 259.812963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:40.057372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.710667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:11:40.057391Z","caller":"traceutil/trace.go:171","msg":"trace[1697394882] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"261.030956ms","start":"2026-07-14T23:11:39.796349Z","end":"2026-07-14T23:11:40.05738Z","steps":["trace[1697394882] 'process raft request' (duration: 260.725501ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:40.057403Z","caller":"traceutil/trace.go:171","msg":"trace[1536384790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"131.800579ms","start":"2026-07-14T23:11:39.925592Z","end":"2026-07-14T23:11:40.057393Z","steps":["trace[1536384790] 'agreement among raft nodes before linearized reading' (duration: 131.729487ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:40.057541Z","caller":"traceutil/trace.go:171","msg":"trace[1031994949] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"258.948276ms","start":"2026-07-14T23:11:39.798584Z","end":"2026-07-14T23:11:40.057532Z","steps":["trace[1031994949] 'process raft request' (duration: 258.58935ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:40.205708Z","caller":"traceutil/trace.go:171","msg":"trace[1855368177] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"140.61798ms","start":"2026-07-14T23:11:40.065063Z","end":"2026-07-14T23:11:40.205681Z","steps":["trace[1855368177] 'process raft request' (duration: 67.864397ms)","trace[1855368177] 'compare' (duration: 72.652361ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:40.662289Z","caller":"traceutil/trace.go:171","msg":"trace[1998438173] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1352; }","duration":"100.847568ms","start":"2026-07-14T23:11:40.561411Z","end":"2026-07-14T23:11:40.662258Z","steps":["trace[1998438173] 'read index received' (duration: 22.424565ms)","trace[1998438173] 'applied index is now lower than readState.Index' (duration: 78.421293ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:11:40.662404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:11:40.662468Z","caller":"traceutil/trace.go:171","msg":"trace[457110499] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1302; }","duration":"101.060661ms","start":"2026-07-14T23:11:40.561392Z","end":"2026-07-14T23:11:40.662452Z","steps":["trace[457110499] 'agreement among raft nodes before linearized reading' (duration: 100.948909ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:41.615926Z","caller":"traceutil/trace.go:171","msg":"trace[999510018] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"433.430672ms","start":"2026-07-14T23:11:41.182471Z","end":"2026-07-14T23:11:41.615902Z","steps":["trace[999510018] 'process raft request' (duration: 433.245989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:41.616067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:41.18245Z","time spent":"433.541125ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:11:42.158131Z","caller":"traceutil/trace.go:171","msg":"trace[481989215] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1354; }","duration":"241.062068ms","start":"2026-07-14T23:11:41.917052Z","end":"2026-07-14T23:11:42.158114Z","steps":["trace[481989215] 'read index received' (duration: 240.899485ms)","trace[481989215] 'applied index is now lower than readState.Index' (duration: 161.863µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:42.158199Z","caller":"traceutil/trace.go:171","msg":"trace[331935779] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"361.124449ms","start":"2026-07-14T23:11:41.797043Z","end":"2026-07-14T23:11:42.158167Z","steps":["trace[331935779] 'process raft request' (duration: 360.949536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:42.158259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.182691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:11:42.158285Z","caller":"traceutil/trace.go:171","msg":"trace[1329541433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"241.267252ms","start":"2026-07-14T23:11:41.917008Z","end":"2026-07-14T23:11:42.158276Z","steps":["trace[1329541433] 'agreement among raft nodes before linearized reading' (duration: 241.204251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:42.158348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:41.797014Z","time spent":"361.252971ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:11:42.158522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.653175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:11:42.158597Z","caller":"traceutil/trace.go:171","msg":"trace[30740127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1304; }","duration":"149.776987ms","start":"2026-07-14T23:11:42.008802Z","end":"2026-07-14T23:11:42.158579Z","steps":["trace[30740127] 'agreement among raft nodes before linearized reading' (duration: 149.597394ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:42.319682Z","caller":"traceutil/trace.go:171","msg":"trace[1684094800] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"156.889545ms","start":"2026-07-14T23:11:42.162773Z","end":"2026-07-14T23:11:42.319662Z","steps":["trace[1684094800] 'process raft request' (duration: 71.330454ms)","trace[1684094800] 'compare' (duration: 85.429188ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:42.425113Z","caller":"traceutil/trace.go:171","msg":"trace[2082313301] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1355; }","duration":"241.370715ms","start":"2026-07-14T23:11:42.183719Z","end":"2026-07-14T23:11:42.42509Z","steps":["trace[2082313301] 'read index received' (duration: 50.343867ms)","trace[2082313301] 'applied index is now lower than readState.Index' (duration: 191.025838ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:11:42.425291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.534788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:11:42.425325Z","caller":"traceutil/trace.go:171","msg":"trace[1095003508] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1309; }","duration":"241.63773ms","start":"2026-07-14T23:11:42.183678Z","end":"2026-07-14T23:11:42.425315Z","steps":["trace[1095003508] 'agreement among raft nodes before linearized reading' (duration: 241.499767ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:42.425795Z","caller":"traceutil/trace.go:171","msg":"trace[1300884436] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"262.20406ms","start":"2026-07-14T23:11:42.163578Z","end":"2026-07-14T23:11:42.425782Z","steps":["trace[1300884436] 'process raft request' (duration: 260.783242ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:42.425921Z","caller":"traceutil/trace.go:171","msg":"trace[1411449915] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"262.22344ms","start":"2026-07-14T23:11:42.163689Z","end":"2026-07-14T23:11:42.425912Z","steps":["trace[1411449915] 'process raft request' (duration: 261.275511ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:42.426043Z","caller":"traceutil/trace.go:171","msg":"trace[922801456] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"262.155369ms","start":"2026-07-14T23:11:42.163878Z","end":"2026-07-14T23:11:42.426033Z","steps":["trace[922801456] 'process raft request' (duration: 261.130029ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:42.426115Z","caller":"traceutil/trace.go:171","msg":"trace[74178981] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"261.588628ms","start":"2026-07-14T23:11:42.164519Z","end":"2026-07-14T23:11:42.426107Z","steps":["trace[74178981] 'process raft request' (duration: 260.521477ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:43.035072Z","caller":"traceutil/trace.go:171","msg":"trace[843847578] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"115.654135ms","start":"2026-07-14T23:11:42.919393Z","end":"2026-07-14T23:11:43.035047Z","steps":["trace[843847578] 'process raft request' (duration: 115.515333ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:46.474143Z","caller":"traceutil/trace.go:171","msg":"trace[750282927] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"137.716803ms","start":"2026-07-14T23:11:46.336399Z","end":"2026-07-14T23:11:46.474116Z","steps":["trace[750282927] 'process raft request' (duration: 137.546769ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:49.258128Z","caller":"traceutil/trace.go:171","msg":"trace[841988514] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"342.346904ms","start":"2026-07-14T23:11:48.915754Z","end":"2026-07-14T23:11:49.258101Z","steps":["trace[841988514] 'read index received' (duration: 342.11908ms)","trace[841988514] 'applied index is now lower than readState.Index' (duration: 226.834µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:49.258218Z","caller":"traceutil/trace.go:171","msg":"trace[1202687285] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"539.186395ms","start":"2026-07-14T23:11:48.719006Z","end":"2026-07-14T23:11:49.258193Z","steps":["trace[1202687285] 'process raft request' (duration: 538.92858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:49.258284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.500157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:11:49.258337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:48.718987Z","time spent":"539.280157ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:11:49.25835Z","caller":"traceutil/trace.go:171","msg":"trace[193893505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1357; }","duration":"342.627929ms","start":"2026-07-14T23:11:48.915695Z","end":"2026-07-14T23:11:49.258323Z","steps":["trace[193893505] 'agreement among raft nodes before linearized reading' (duration: 342.515547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:49.258407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:48.915675Z","time spent":"342.720932ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T23:11:50.926172Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824171189933452,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T23:11:51.02589Z","caller":"traceutil/trace.go:171","msg":"trace[208653307] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"599.983955ms","start":"2026-07-14T23:11:50.425884Z","end":"2026-07-14T23:11:51.025868Z","steps":["trace[208653307] 'read index received' (duration: 599.933004ms)","trace[208653307] 'applied index is now lower than readState.Index' (duration: 50.041µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:51.026025Z","caller":"traceutil/trace.go:171","msg":"trace[133332879] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"811.49167ms","start":"2026-07-14T23:11:50.214493Z","end":"2026-07-14T23:11:51.025984Z","steps":["trace[133332879] 'process raft request' (duration: 811.255156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:51.026137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.23657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:11:51.026181Z","caller":"traceutil/trace.go:171","msg":"trace[1049358336] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1358; }","duration":"600.328142ms","start":"2026-07-14T23:11:50.425844Z","end":"2026-07-14T23:11:51.026172Z","steps":["trace[1049358336] 'agreement among raft nodes before linearized reading' (duration: 600.22624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:51.026225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:50.425827Z","time spent":"600.389523ms","remote":"127.0.0.1:49906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:11:51.026369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:50.21447Z","time spent":"811.603123ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:11:51.317813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.699392ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-14T23:11:51.318032Z","caller":"traceutil/trace.go:171","msg":"trace[1882725060] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1410; }","duration":"291.951637ms","start":"2026-07-14T23:11:51.026042Z","end":"2026-07-14T23:11:51.317994Z","steps":["trace[1882725060] 'read index received' (duration: 1.068141ms)","trace[1882725060] 'applied index is now lower than readState.Index' (duration: 290.881436ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:11:51.318176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.777337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-14T23:11:51.318219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.900769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-14T23:11:51.318243Z","caller":"traceutil/trace.go:171","msg":"trace[1915891320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1358; }","duration":"809.86633ms","start":"2026-07-14T23:11:50.50836Z","end":"2026-07-14T23:11:51.318227Z","steps":["trace[1915891320] 'agreement among raft nodes before linearized reading' (duration: 809.639126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:51.318212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.565005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T23:11:51.318263Z","caller":"traceutil/trace.go:171","msg":"trace[1831313119] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1358; }","duration":"844.97433ms","start":"2026-07-14T23:11:50.473265Z","end":"2026-07-14T23:11:51.318239Z","steps":["trace[1831313119] 'agreement among raft nodes before linearized reading' (duration: 844.819787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:51.318282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:50.508354Z","time spent":"809.920831ms","remote":"127.0.0.1:49710","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-svcs-lock\" "} {"level":"info","ts":"2026-07-14T23:11:51.318297Z","caller":"traceutil/trace.go:171","msg":"trace[403920265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1358; }","duration":"822.674338ms","start":"2026-07-14T23:11:50.495604Z","end":"2026-07-14T23:11:51.318279Z","steps":["trace[403920265] 'agreement among raft nodes before linearized reading' (duration: 822.463334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:51.318299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.668523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:11:51.31834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:50.495594Z","time spent":"822.737629ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T23:11:51.318353Z","caller":"traceutil/trace.go:171","msg":"trace[1290416683] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1358; }","duration":"830.749865ms","start":"2026-07-14T23:11:50.487589Z","end":"2026-07-14T23:11:51.318339Z","steps":["trace[1290416683] 'agreement among raft nodes before linearized reading' (duration: 830.659743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:51.318396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.174211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T23:11:51.318426Z","caller":"traceutil/trace.go:171","msg":"trace[1883065233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1358; }","duration":"833.258452ms","start":"2026-07-14T23:11:50.48516Z","end":"2026-07-14T23:11:51.318418Z","steps":["trace[1883065233] 'agreement among raft nodes before linearized reading' (duration: 833.176991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:51.318454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:50.485143Z","time spent":"833.303954ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T23:11:51.318391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:50.487543Z","time spent":"830.837357ms","remote":"127.0.0.1:49672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:11:51.318501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.601731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T23:11:51.31853Z","caller":"traceutil/trace.go:171","msg":"trace[1494995309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1358; }","duration":"814.646131ms","start":"2026-07-14T23:11:50.503875Z","end":"2026-07-14T23:11:51.318521Z","steps":["trace[1494995309] 'agreement among raft nodes before linearized reading' (duration: 814.56274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:51.318547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.830919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:11:51.318556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:50.503869Z","time spent":"814.681873ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T23:11:51.318579Z","caller":"traceutil/trace.go:171","msg":"trace[705770162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1358; }","duration":"401.89965ms","start":"2026-07-14T23:11:50.91667Z","end":"2026-07-14T23:11:51.31857Z","steps":["trace[705770162] 'agreement among raft nodes before linearized reading' (duration: 401.853419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:51.318308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:50.473247Z","time spent":"845.048212ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-14T23:11:51.318615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:50.916646Z","time spent":"401.956751ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T23:11:51.318726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.44705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-14T23:11:51.318754Z","caller":"traceutil/trace.go:171","msg":"trace[269830338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1358; }","duration":"809.481371ms","start":"2026-07-14T23:11:50.509262Z","end":"2026-07-14T23:11:51.318743Z","steps":["trace[269830338] 'agreement among raft nodes before linearized reading' (duration: 809.40837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:51.318781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:50.509257Z","time spent":"809.517942ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T23:12:05.507486Z","caller":"traceutil/trace.go:171","msg":"trace[1210502132] linearizableReadLoop","detail":"{readStateIndex:1495; appliedIndex:1494; }","duration":"110.530205ms","start":"2026-07-14T23:12:05.396927Z","end":"2026-07-14T23:12:05.507457Z","steps":["trace[1210502132] 'read index received' (duration: 110.23808ms)","trace[1210502132] 'applied index is now lower than readState.Index' (duration: 290.595µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:12:05.507842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.345446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T23:12:05.507941Z","caller":"traceutil/trace.go:171","msg":"trace[639444995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1439; }","duration":"103.459098ms","start":"2026-07-14T23:12:05.404417Z","end":"2026-07-14T23:12:05.507876Z","steps":["trace[639444995] 'agreement among raft nodes before linearized reading' (duration: 103.236824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:12:05.509299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.35631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T23:12:05.509396Z","caller":"traceutil/trace.go:171","msg":"trace[302252841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1439; }","duration":"112.468413ms","start":"2026-07-14T23:12:05.396893Z","end":"2026-07-14T23:12:05.509361Z","steps":["trace[302252841] 'agreement among raft nodes before linearized reading' (duration: 110.700789ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:12:05.639284Z","caller":"traceutil/trace.go:171","msg":"trace[620385681] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"126.118638ms","start":"2026-07-14T23:12:05.513131Z","end":"2026-07-14T23:12:05.63925Z","steps":["trace[620385681] 'process raft request' (duration: 126.042347ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:12:05.639452Z","caller":"traceutil/trace.go:171","msg":"trace[136830918] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"126.344022ms","start":"2026-07-14T23:12:05.513074Z","end":"2026-07-14T23:12:05.639418Z","steps":["trace[136830918] 'process raft request' (duration: 126.030906ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:12:05.639577Z","caller":"traceutil/trace.go:171","msg":"trace[1311162887] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"126.277451ms","start":"2026-07-14T23:12:05.513278Z","end":"2026-07-14T23:12:05.639555Z","steps":["trace[1311162887] 'process raft request' (duration: 125.931644ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:12:05.639423Z","caller":"traceutil/trace.go:171","msg":"trace[410086975] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"126.417313ms","start":"2026-07-14T23:12:05.512977Z","end":"2026-07-14T23:12:05.639395Z","steps":["trace[410086975] 'process raft request' (duration: 84.622141ms)","trace[410086975] 'compare' (duration: 41.279902ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:12:05.639733Z","caller":"traceutil/trace.go:171","msg":"trace[1238484041] linearizableReadLoop","detail":"{readStateIndex:1501; appliedIndex:1496; }","duration":"112.88595ms","start":"2026-07-14T23:12:05.526835Z","end":"2026-07-14T23:12:05.639721Z","steps":["trace[1238484041] 'read index received' (duration: 70.677261ms)","trace[1238484041] 'applied index is now lower than readState.Index' (duration: 42.182048ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:12:05.639779Z","caller":"traceutil/trace.go:171","msg":"trace[231768920] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"125.545866ms","start":"2026-07-14T23:12:05.514215Z","end":"2026-07-14T23:12:05.63976Z","steps":["trace[231768920] 'process raft request' (duration: 125.21944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:12:05.639914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.059574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-14T23:12:05.639978Z","caller":"traceutil/trace.go:171","msg":"trace[1814401732] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1445; }","duration":"113.164306ms","start":"2026-07-14T23:12:05.526802Z","end":"2026-07-14T23:12:05.639966Z","steps":["trace[1814401732] 'agreement among raft nodes before linearized reading' (duration: 112.996812ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:12:19.946972Z","caller":"traceutil/trace.go:171","msg":"trace[1048692524] linearizableReadLoop","detail":"{readStateIndex:1568; appliedIndex:1566; }","duration":"110.613007ms","start":"2026-07-14T23:12:19.836316Z","end":"2026-07-14T23:12:19.946929Z","steps":["trace[1048692524] 'read index received' (duration: 34.806176ms)","trace[1048692524] 'applied index is now lower than readState.Index' (duration: 75.805081ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:12:19.947075Z","caller":"traceutil/trace.go:171","msg":"trace[707259616] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"127.326171ms","start":"2026-07-14T23:12:19.819704Z","end":"2026-07-14T23:12:19.94703Z","steps":["trace[707259616] 'process raft request' (duration: 124.340894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:12:19.947264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.935583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-14T23:12:19.947345Z","caller":"traceutil/trace.go:171","msg":"trace[690181767] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1510; }","duration":"111.106486ms","start":"2026-07-14T23:12:19.836221Z","end":"2026-07-14T23:12:19.947327Z","steps":["trace[690181767] 'agreement among raft nodes before linearized reading' (duration: 110.847211ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:12:26.119923Z","caller":"traceutil/trace.go:171","msg":"trace[1778431752] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"101.285856ms","start":"2026-07-14T23:12:26.018595Z","end":"2026-07-14T23:12:26.119881Z","steps":["trace[1778431752] 'process raft request' (duration: 43.846531ms)","trace[1778431752] 'compare' (duration: 57.263862ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:13:00.335478Z","caller":"traceutil/trace.go:171","msg":"trace[1006073067] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"109.623698ms","start":"2026-07-14T23:13:00.225837Z","end":"2026-07-14T23:13:00.33546Z","steps":["trace[1006073067] 'process raft request' (duration: 109.518886ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:00.381082Z","caller":"traceutil/trace.go:171","msg":"trace[1375929373] transaction","detail":"{read_only:false; response_revision:1692; number_of_response:1; }","duration":"153.917614ms","start":"2026-07-14T23:13:00.227136Z","end":"2026-07-14T23:13:00.381054Z","steps":["trace[1375929373] 'process raft request' (duration: 153.142548ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:00.3813Z","caller":"traceutil/trace.go:171","msg":"trace[136517230] transaction","detail":"{read_only:false; response_revision:1693; number_of_response:1; }","duration":"153.860952ms","start":"2026-07-14T23:13:00.227425Z","end":"2026-07-14T23:13:00.381286Z","steps":["trace[136517230] 'process raft request' (duration: 153.020585ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:00.381415Z","caller":"traceutil/trace.go:171","msg":"trace[548777823] transaction","detail":"{read_only:false; response_revision:1694; number_of_response:1; }","duration":"148.708872ms","start":"2026-07-14T23:13:00.232694Z","end":"2026-07-14T23:13:00.381403Z","steps":["trace[548777823] 'process raft request' (duration: 147.799844ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:14.038535Z","caller":"traceutil/trace.go:171","msg":"trace[732475756] transaction","detail":"{read_only:false; response_revision:1753; number_of_response:1; }","duration":"100.740595ms","start":"2026-07-14T23:13:13.937766Z","end":"2026-07-14T23:13:14.038507Z","steps":["trace[732475756] 'process raft request' (duration: 100.587313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:13:14.242568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.190944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-14T23:13:14.242706Z","caller":"traceutil/trace.go:171","msg":"trace[766681695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1753; }","duration":"132.354807ms","start":"2026-07-14T23:13:14.11029Z","end":"2026-07-14T23:13:14.242644Z","steps":["trace[766681695] 'range keys from in-memory index tree' (duration: 131.99451ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:36.538297Z","caller":"traceutil/trace.go:171","msg":"trace[1475000041] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"157.662026ms","start":"2026-07-14T23:13:36.380603Z","end":"2026-07-14T23:13:36.538265Z","steps":["trace[1475000041] 'process raft request' (duration: 157.39432ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:15:52.225358Z","caller":"traceutil/trace.go:171","msg":"trace[408007216] transaction","detail":"{read_only:false; response_revision:2480; number_of_response:1; }","duration":"144.283145ms","start":"2026-07-14T23:15:52.081025Z","end":"2026-07-14T23:15:52.225308Z","steps":["trace[408007216] 'process raft request' (duration: 144.231034ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:15:52.225507Z","caller":"traceutil/trace.go:171","msg":"trace[1162357079] transaction","detail":"{read_only:false; response_revision:2479; number_of_response:1; }","duration":"184.575109ms","start":"2026-07-14T23:15:52.040891Z","end":"2026-07-14T23:15:52.225466Z","steps":["trace[1162357079] 'process raft request' (duration: 121.986596ms)","trace[1162357079] 'compare' (duration: 62.243387ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:16:04.926818Z","caller":"traceutil/trace.go:171","msg":"trace[837623738] transaction","detail":"{read_only:false; response_revision:2564; number_of_response:1; }","duration":"124.403171ms","start":"2026-07-14T23:16:04.802377Z","end":"2026-07-14T23:16:04.92678Z","steps":["trace[837623738] 'process raft request' (duration: 94.102249ms)","trace[837623738] 'compare' (duration: 29.903845ms)"],"step_count":2}