{"level":"warn","ts":"2026-07-29T15:49:19.770852Z","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-29T15:49:19.770966Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.204.45.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-29T15:49:19.771053Z","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-29T15:49:19.771065Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-07-29T15:49:19.771088Z","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-29T15:49:19.771905Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-07-29T15:49:19.771983Z","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.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-29T15:49:19.77676Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.55752ms"} {"level":"info","ts":"2026-07-29T15:49:19.783415Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-07-29T15:49:19.783475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-07-29T15:49:19.783498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-07-29T15:49:19.783511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-29T15:49:19.783522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-07-29T15:49:19.783553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-07-29T15:49:19.795148Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-29T15:49:19.796679Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-29T15:49:19.797716Z","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-29T15:49:19.800883Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-29T15:49:19.803047Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-29T15:49:19.804601Z","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-29T15:49:19.804711Z","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-29T15:49:19.804723Z","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-29T15:49:19.805402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-07-29T15:49:19.805505Z","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-29T15:49:19.805632Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-07-29T15:49:19.805851Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-29T15:49:19.805907Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-29T15:49:19.805963Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-07-29T15:49:19.805992Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-07-29T15:49:20.284154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-07-29T15:49:20.284212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-29T15:49:20.284244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-07-29T15:49:20.284261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-07-29T15:49:20.284268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-07-29T15:49:20.284279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-07-29T15:49:20.284289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-07-29T15:49:20.285377Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-07-29T15:49:20.285554Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-29T15:49:20.285644Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-29T15:49:20.285747Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T15:49:20.286423Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-29T15:49:20.286509Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-29T15:49:20.286977Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T15:49:20.28712Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T15:49:20.287162Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T15:49:20.28799Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-07-29T15:49:20.290817Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-29T15:49:22.821835Z","caller":"traceutil/trace.go:171","msg":"trace[410595313] linearizableReadLoop","detail":"{readStateIndex:123; appliedIndex:122; }","duration":"108.481168ms","start":"2026-07-29T15:49:22.713322Z","end":"2026-07-29T15:49:22.821803Z","steps":["trace[410595313] 'read index received' (duration: 79.098172ms)","trace[410595313] 'applied index is now lower than readState.Index' (duration: 29.381786ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:49:22.821904Z","caller":"traceutil/trace.go:171","msg":"trace[949756402] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"119.835699ms","start":"2026-07-29T15:49:22.702043Z","end":"2026-07-29T15:49:22.821878Z","steps":["trace[949756402] 'process raft request' (duration: 90.455593ms)","trace[949756402] 'compare' (duration: 29.14564ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:49:22.822034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.691553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-29T15:49:22.822124Z","caller":"traceutil/trace.go:171","msg":"trace[1532938272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:118; }","duration":"108.947081ms","start":"2026-07-29T15:49:22.713157Z","end":"2026-07-29T15:49:22.822104Z","steps":["trace[1532938272] 'agreement among raft nodes before linearized reading' (duration: 108.785967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:23.135654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.299825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-29T15:49:23.135769Z","caller":"traceutil/trace.go:171","msg":"trace[1319573434] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"223.438077ms","start":"2026-07-29T15:49:22.912313Z","end":"2026-07-29T15:49:23.135751Z","steps":["trace[1319573434] 'process raft request' (duration: 92.564687ms)","trace[1319573434] 'compare' (duration: 130.079849ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:49:23.382492Z","caller":"traceutil/trace.go:171","msg":"trace[938117979] transaction","detail":"{read_only:false; response_revision:124; number_of_response:1; }","duration":"220.275503ms","start":"2026-07-29T15:49:23.162187Z","end":"2026-07-29T15:49:23.382463Z","steps":["trace[938117979] 'process raft request' (duration: 145.655871ms)","trace[938117979] 'compare' (duration: 74.476138ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:49:23.63053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.011601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-29T15:49:23.630649Z","caller":"traceutil/trace.go:171","msg":"trace[1734481172] transaction","detail":"{read_only:false; response_revision:126; number_of_response:1; }","duration":"196.749042ms","start":"2026-07-29T15:49:23.433882Z","end":"2026-07-29T15:49:23.630631Z","steps":["trace[1734481172] 'process raft request' (duration: 50.569817ms)","trace[1734481172] 'compare' (duration: 145.827296ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:49:23.974555Z","caller":"traceutil/trace.go:171","msg":"trace[936806675] transaction","detail":"{read_only:false; response_revision:129; number_of_response:1; }","duration":"277.150788ms","start":"2026-07-29T15:49:23.697388Z","end":"2026-07-29T15:49:23.974539Z","steps":["trace[936806675] 'process raft request' (duration: 220.241323ms)","trace[936806675] 'compare' (duration: 56.663039ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:49:23.97452Z","caller":"traceutil/trace.go:171","msg":"trace[117625857] linearizableReadLoop","detail":"{readStateIndex:134; appliedIndex:133; }","duration":"255.750962ms","start":"2026-07-29T15:49:23.718723Z","end":"2026-07-29T15:49:23.974474Z","steps":["trace[117625857] 'read index received' (duration: 198.772045ms)","trace[117625857] 'applied index is now lower than readState.Index' (duration: 56.977577ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:49:23.974679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.946808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:49:23.974729Z","caller":"traceutil/trace.go:171","msg":"trace[2032618651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:129; }","duration":"256.091332ms","start":"2026-07-29T15:49:23.718624Z","end":"2026-07-29T15:49:23.974715Z","steps":["trace[2032618651] 'agreement among raft nodes before linearized reading' (duration: 255.955348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:23.974853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.883003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:49:23.974943Z","caller":"traceutil/trace.go:171","msg":"trace[2054907226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:129; }","duration":"167.027467ms","start":"2026-07-29T15:49:23.807894Z","end":"2026-07-29T15:49:23.974921Z","steps":["trace[2054907226] 'agreement among raft nodes before linearized reading' (duration: 166.868823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:24.249763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.660348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-29T15:49:24.249955Z","caller":"traceutil/trace.go:171","msg":"trace[207828991] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"261.175965ms","start":"2026-07-29T15:49:23.988761Z","end":"2026-07-29T15:49:24.249937Z","steps":["trace[207828991] 'process raft request' (duration: 261.109234ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:24.250027Z","caller":"traceutil/trace.go:171","msg":"trace[1829738965] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"263.838025ms","start":"2026-07-29T15:49:23.986137Z","end":"2026-07-29T15:49:24.249975Z","steps":["trace[1829738965] 'process raft request' (duration: 105.90332ms)","trace[1829738965] 'compare' (duration: 157.502244ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:49:24.518482Z","caller":"traceutil/trace.go:171","msg":"trace[1679530625] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"221.011824ms","start":"2026-07-29T15:49:24.297436Z","end":"2026-07-29T15:49:24.518448Z","steps":["trace[1679530625] 'process raft request' (duration: 177.166204ms)","trace[1679530625] 'compare' (duration: 43.677696ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:49:24.803389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.301911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-29T15:49:24.803568Z","caller":"traceutil/trace.go:171","msg":"trace[707467776] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"280.330422ms","start":"2026-07-29T15:49:24.523171Z","end":"2026-07-29T15:49:24.803501Z","steps":["trace[707467776] 'process raft request' (duration: 148.847166ms)","trace[707467776] 'compare' (duration: 131.144467ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:49:25.101342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.423071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-29T15:49:25.101614Z","caller":"traceutil/trace.go:171","msg":"trace[706098666] linearizableReadLoop","detail":"{readStateIndex:142; appliedIndex:140; }","duration":"288.564179ms","start":"2026-07-29T15:49:24.813027Z","end":"2026-07-29T15:49:25.101592Z","steps":["trace[706098666] 'read index received' (duration: 99.912612ms)","trace[706098666] 'applied index is now lower than readState.Index' (duration: 188.650407ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:49:25.101642Z","caller":"traceutil/trace.go:171","msg":"trace[857840538] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"293.016848ms","start":"2026-07-29T15:49:24.80857Z","end":"2026-07-29T15:49:25.101587Z","steps":["trace[857840538] 'process raft request' (duration: 104.267198ms)","trace[857840538] 'compare' (duration: 188.285128ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:49:25.101729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.695313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:49:25.101779Z","caller":"traceutil/trace.go:171","msg":"trace[806974237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:137; }","duration":"288.789606ms","start":"2026-07-29T15:49:24.812973Z","end":"2026-07-29T15:49:25.101762Z","steps":["trace[806974237] 'agreement among raft nodes before linearized reading' (duration: 288.683623ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:25.10177Z","caller":"traceutil/trace.go:171","msg":"trace[394881690] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"291.866697ms","start":"2026-07-29T15:49:24.809864Z","end":"2026-07-29T15:49:25.10173Z","steps":["trace[394881690] 'process raft request' (duration: 291.61425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:25.810019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.054464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-29T15:49:25.810129Z","caller":"traceutil/trace.go:171","msg":"trace[511407538] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"701.738475ms","start":"2026-07-29T15:49:25.108372Z","end":"2026-07-29T15:49:25.81011Z","steps":["trace[511407538] 'process raft request' (duration: 109.503516ms)","trace[511407538] 'compare' (duration: 591.865959ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:49:25.810182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:25.108354Z","time spent":"701.806496ms","remote":"127.0.0.1:50462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-29T15:49:25.81082Z","caller":"traceutil/trace.go:171","msg":"trace[635037153] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"701.748054ms","start":"2026-07-29T15:49:25.109058Z","end":"2026-07-29T15:49:25.810806Z","steps":["trace[635037153] 'process raft request' (duration: 701.502608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:25.811094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:25.109038Z","time spent":"701.813385ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:49:26.034207Z","caller":"traceutil/trace.go:171","msg":"trace[250491292] linearizableReadLoop","detail":"{readStateIndex:148; appliedIndex:147; }","duration":"124.963734ms","start":"2026-07-29T15:49:25.909221Z","end":"2026-07-29T15:49:26.034184Z","steps":["trace[250491292] 'read index received' (duration: 35.119349ms)","trace[250491292] 'applied index is now lower than readState.Index' (duration: 89.843645ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:49:26.034284Z","caller":"traceutil/trace.go:171","msg":"trace[111567927] transaction","detail":"{read_only:false; response_revision:143; number_of_response:1; }","duration":"130.911812ms","start":"2026-07-29T15:49:25.90333Z","end":"2026-07-29T15:49:26.034242Z","steps":["trace[111567927] 'process raft request' (duration: 41.032595ms)","trace[111567927] 'compare' (duration: 89.701002ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:49:26.034484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.534333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:49:26.03456Z","caller":"traceutil/trace.go:171","msg":"trace[1593837756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:143; }","duration":"121.661757ms","start":"2026-07-29T15:49:25.912883Z","end":"2026-07-29T15:49:26.034545Z","steps":["trace[1593837756] 'agreement among raft nodes before linearized reading' (duration: 121.545294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:26.034902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.646393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:49:26.035208Z","caller":"traceutil/trace.go:171","msg":"trace[590665834] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver; range_end:; response_count:0; response_revision:143; }","duration":"125.837968ms","start":"2026-07-29T15:49:25.909169Z","end":"2026-07-29T15:49:26.035007Z","steps":["trace[590665834] 'agreement among raft nodes before linearized reading' (duration: 125.639633ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:26.200736Z","caller":"traceutil/trace.go:171","msg":"trace[1453687956] transaction","detail":"{read_only:false; response_revision:145; number_of_response:1; }","duration":"119.248713ms","start":"2026-07-29T15:49:26.08146Z","end":"2026-07-29T15:49:26.200709Z","steps":["trace[1453687956] 'process raft request' (duration: 55.97424ms)","trace[1453687956] 'compare' (duration: 63.120449ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:49:26.42854Z","caller":"traceutil/trace.go:171","msg":"trace[1852766449] transaction","detail":"{read_only:false; response_revision:148; number_of_response:1; }","duration":"134.699751ms","start":"2026-07-29T15:49:26.293809Z","end":"2026-07-29T15:49:26.428508Z","steps":["trace[1852766449] 'process raft request' (duration: 94.251561ms)","trace[1852766449] 'compare' (duration: 40.166343ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:49:33.353651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.41209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-07-29T15:49:33.35368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.904008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-29T15:49:33.353735Z","caller":"traceutil/trace.go:171","msg":"trace[1790315567] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:346; }","duration":"197.560565ms","start":"2026-07-29T15:49:33.156159Z","end":"2026-07-29T15:49:33.35372Z","steps":["trace[1790315567] 'range keys from in-memory index tree' (duration: 197.191615ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:33.353757Z","caller":"traceutil/trace.go:171","msg":"trace[1894459102] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:346; }","duration":"348.005261ms","start":"2026-07-29T15:49:33.005733Z","end":"2026-07-29T15:49:33.353739Z","steps":["trace[1894459102] 'range keys from in-memory index tree' (duration: 347.797386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:33.353799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.00571Z","time spent":"348.080823ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":228,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-07-29T15:49:33.353804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.547087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-29T15:49:33.353877Z","caller":"traceutil/trace.go:171","msg":"trace[738972803] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:346; }","duration":"297.66214ms","start":"2026-07-29T15:49:33.056194Z","end":"2026-07-29T15:49:33.353856Z","steps":["trace[738972803] 'range keys from in-memory index tree' (duration: 297.417344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:33.353943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.85695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-07-29T15:49:33.353947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.302568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-07-29T15:49:33.353976Z","caller":"traceutil/trace.go:171","msg":"trace[69647624] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:346; }","duration":"498.909031ms","start":"2026-07-29T15:49:32.855052Z","end":"2026-07-29T15:49:33.353961Z","steps":["trace[69647624] 'range keys from in-memory index tree' (duration: 498.735507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:33.354004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:32.855033Z","time spent":"498.964903ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-07-29T15:49:33.354008Z","caller":"traceutil/trace.go:171","msg":"trace[412974968] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:346; }","duration":"548.407331ms","start":"2026-07-29T15:49:32.805586Z","end":"2026-07-29T15:49:33.353993Z","steps":["trace[412974968] 'range keys from in-memory index tree' (duration: 548.212805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:33.353657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.650995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-07-29T15:49:33.354041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:32.805466Z","time spent":"548.568765ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-07-29T15:49:33.354064Z","caller":"traceutil/trace.go:171","msg":"trace[989172839] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:346; }","duration":"249.174268ms","start":"2026-07-29T15:49:33.104881Z","end":"2026-07-29T15:49:33.354055Z","steps":["trace[989172839] 'range keys from in-memory index tree' (duration: 248.545822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:33.354102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.783656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-07-29T15:49:33.354136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.13879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:49:33.354184Z","caller":"traceutil/trace.go:171","msg":"trace[44104750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:346; }","duration":"515.219753ms","start":"2026-07-29T15:49:32.838958Z","end":"2026-07-29T15:49:33.354178Z","steps":["trace[44104750] 'range keys from in-memory index tree' (duration: 514.960416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:33.354203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:32.83894Z","time spent":"515.256044ms","remote":"127.0.0.1:50120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-29T15:49:33.354136Z","caller":"traceutil/trace.go:171","msg":"trace[1997706280] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:346; }","duration":"448.816347ms","start":"2026-07-29T15:49:32.905311Z","end":"2026-07-29T15:49:33.354127Z","steps":["trace[1997706280] 'range keys from in-memory index tree' (duration: 448.716394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:33.354259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:32.905254Z","time spent":"448.990491ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-07-29T15:49:33.354387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.692739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-29T15:49:33.354445Z","caller":"traceutil/trace.go:171","msg":"trace[807715471] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:346; }","duration":"397.572062ms","start":"2026-07-29T15:49:32.956857Z","end":"2026-07-29T15:49:33.354429Z","steps":["trace[807715471] 'range keys from in-memory index tree' (duration: 396.571216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:33.353751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.608017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-07-29T15:49:33.354481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:32.95682Z","time spent":"397.649444ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":214,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-07-29T15:49:33.354509Z","caller":"traceutil/trace.go:171","msg":"trace[2098781890] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:346; }","duration":"144.428839ms","start":"2026-07-29T15:49:33.210067Z","end":"2026-07-29T15:49:33.354496Z","steps":["trace[2098781890] 'range keys from in-memory index tree' (duration: 143.482544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:33.354812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.65648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-07-29T15:49:33.354871Z","caller":"traceutil/trace.go:171","msg":"trace[1274536193] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:346; }","duration":"505.790363ms","start":"2026-07-29T15:49:32.849064Z","end":"2026-07-29T15:49:33.354854Z","steps":["trace[1274536193] 'range keys from in-memory index tree' (duration: 504.437338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:33.354912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:32.849032Z","time spent":"505.872794ms","remote":"127.0.0.1:50298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7324,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-07-29T15:49:33.905971Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882612559358511,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-29T15:49:34.218002Z","caller":"traceutil/trace.go:171","msg":"trace[1648865946] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"856.249481ms","start":"2026-07-29T15:49:33.361733Z","end":"2026-07-29T15:49:34.217982Z","steps":["trace[1648865946] 'process raft request' (duration: 856.125568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.218137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.361713Z","time spent":"856.352553ms","remote":"127.0.0.1:50298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:49:34.3699Z","caller":"traceutil/trace.go:171","msg":"trace[1037075363] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"1.006617461s","start":"2026-07-29T15:49:33.36325Z","end":"2026-07-29T15:49:34.369867Z","steps":["trace[1037075363] 'process raft request' (duration: 970.062345ms)","trace[1037075363] 'compare' (duration: 36.33414ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:49:34.36994Z","caller":"traceutil/trace.go:171","msg":"trace[701264808] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:356; }","duration":"965.027622ms","start":"2026-07-29T15:49:33.404894Z","end":"2026-07-29T15:49:34.369922Z","steps":["trace[701264808] 'read index received' (duration: 812.916836ms)","trace[701264808] 'applied index is now lower than readState.Index' (duration: 152.110126ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:49:34.370054Z","caller":"traceutil/trace.go:171","msg":"trace[831642802] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"1.003686864s","start":"2026-07-29T15:49:33.366358Z","end":"2026-07-29T15:49:34.370045Z","steps":["trace[831642802] 'process raft request' (duration: 1.00353916s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.370082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.363239Z","time spent":"1.006754775s","remote":"127.0.0.1:50216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-29T15:49:34.370082Z","caller":"traceutil/trace.go:171","msg":"trace[498227595] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"1.0042868s","start":"2026-07-29T15:49:33.365758Z","end":"2026-07-29T15:49:34.370044Z","steps":["trace[498227595] 'process raft request' (duration: 1.004110165s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.370148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.366353Z","time spent":"1.003721225s","remote":"127.0.0.1:50462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:49:34.370179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.712234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-07-29T15:49:34.370207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.365752Z","time spent":"1.004388712s","remote":"127.0.0.1:50462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:49:34.370243Z","caller":"traceutil/trace.go:171","msg":"trace[230531925] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:351; }","duration":"964.827167ms","start":"2026-07-29T15:49:33.405398Z","end":"2026-07-29T15:49:34.370225Z","steps":["trace[230531925] 'agreement among raft nodes before linearized reading' (duration: 964.710734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.370279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.405378Z","time spent":"964.895378ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-07-29T15:49:34.370266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.666774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-07-29T15:49:34.370319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.047924ms","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-29T15:49:34.370323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.659555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-29T15:49:34.370366Z","caller":"traceutil/trace.go:171","msg":"trace[1032264816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:351; }","duration":"527.119916ms","start":"2026-07-29T15:49:33.843237Z","end":"2026-07-29T15:49:34.370357Z","steps":["trace[1032264816] 'agreement among raft nodes before linearized reading' (duration: 527.050884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.370391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.843227Z","time spent":"527.159807ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-29T15:49:34.3704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.255964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:49:34.370403Z","caller":"traceutil/trace.go:171","msg":"trace[1263867263] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:351; }","duration":"814.804039ms","start":"2026-07-29T15:49:33.555579Z","end":"2026-07-29T15:49:34.370383Z","steps":["trace[1263867263] 'agreement among raft nodes before linearized reading' (duration: 814.640584ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:34.370428Z","caller":"traceutil/trace.go:171","msg":"trace[265855156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:351; }","duration":"531.308356ms","start":"2026-07-29T15:49:33.839111Z","end":"2026-07-29T15:49:34.37042Z","steps":["trace[265855156] 'agreement among raft nodes before linearized reading' (duration: 531.266224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.370444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.555558Z","time spent":"814.878041ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":228,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-07-29T15:49:34.370433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.536468ms","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-29T15:49:34.370464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.839098Z","time spent":"531.358657ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-29T15:49:34.370503Z","caller":"traceutil/trace.go:171","msg":"trace[1460835832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:351; }","duration":"894.64006ms","start":"2026-07-29T15:49:33.475845Z","end":"2026-07-29T15:49:34.370485Z","steps":["trace[1460835832] 'agreement among raft nodes before linearized reading' (duration: 894.505627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.370508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.649102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-07-29T15:49:34.370549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.475826Z","time spent":"894.710833ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-29T15:49:34.37056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.39413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-29T15:49:34.370558Z","caller":"traceutil/trace.go:171","msg":"trace[960097677] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:351; }","duration":"863.754904ms","start":"2026-07-29T15:49:33.506787Z","end":"2026-07-29T15:49:34.370542Z","steps":["trace[960097677] 'agreement among raft nodes before linearized reading' (duration: 863.667121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.370577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.693358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-07-29T15:49:34.370584Z","caller":"traceutil/trace.go:171","msg":"trace[1657241374] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:351; }","duration":"564.477733ms","start":"2026-07-29T15:49:33.806098Z","end":"2026-07-29T15:49:34.370576Z","steps":["trace[1657241374] 'agreement among raft nodes before linearized reading' (duration: 564.439932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.370608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.806069Z","time spent":"564.535073ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":214,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-07-29T15:49:34.370609Z","caller":"traceutil/trace.go:171","msg":"trace[1942296579] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:351; }","duration":"965.723269ms","start":"2026-07-29T15:49:33.404876Z","end":"2026-07-29T15:49:34.3706Z","steps":["trace[1942296579] 'agreement among raft nodes before linearized reading' (duration: 965.670648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.370606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.506767Z","time spent":"863.822216ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-07-29T15:49:34.37045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.560457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-07-29T15:49:34.370645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.481173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-29T15:49:34.370665Z","caller":"traceutil/trace.go:171","msg":"trace[246953863] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:351; }","duration":"914.802324ms","start":"2026-07-29T15:49:33.455857Z","end":"2026-07-29T15:49:34.370659Z","steps":["trace[246953863] 'agreement among raft nodes before linearized reading' (duration: 914.567208ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:34.370694Z","caller":"traceutil/trace.go:171","msg":"trace[1075130853] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:351; }","duration":"613.572125ms","start":"2026-07-29T15:49:33.757107Z","end":"2026-07-29T15:49:34.370679Z","steps":["trace[1075130853] 'agreement among raft nodes before linearized reading' (duration: 613.492733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.370712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.455837Z","time spent":"914.860175ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-07-29T15:49:34.370728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.757087Z","time spent":"613.633256ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":228,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-07-29T15:49:34.370735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.404807Z","time spent":"965.825352ms","remote":"127.0.0.1:50290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4218,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-29T15:49:34.370762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.005414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-07-29T15:49:34.370792Z","caller":"traceutil/trace.go:171","msg":"trace[153367077] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:351; }","duration":"714.102016ms","start":"2026-07-29T15:49:33.656681Z","end":"2026-07-29T15:49:34.370783Z","steps":["trace[153367077] 'agreement among raft nodes before linearized reading' (duration: 714.049265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.370815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.656661Z","time spent":"714.148738ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":224,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-07-29T15:49:34.370349Z","caller":"traceutil/trace.go:171","msg":"trace[476879060] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:351; }","duration":"663.823828ms","start":"2026-07-29T15:49:33.706504Z","end":"2026-07-29T15:49:34.370328Z","steps":["trace[476879060] 'agreement among raft nodes before linearized reading' (duration: 663.632163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.370858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.70649Z","time spent":"664.359812ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-07-29T15:49:34.370885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.433743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-29T15:49:34.37092Z","caller":"traceutil/trace.go:171","msg":"trace[413078885] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:351; }","duration":"765.543066ms","start":"2026-07-29T15:49:33.605367Z","end":"2026-07-29T15:49:34.37091Z","steps":["trace[413078885] 'agreement among raft nodes before linearized reading' (duration: 765.465064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.37095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.605331Z","time spent":"765.611528ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":235,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-07-29T15:49:34.371087Z","caller":"traceutil/trace.go:171","msg":"trace[563038736] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"1.005367417s","start":"2026-07-29T15:49:33.365697Z","end":"2026-07-29T15:49:34.371065Z","steps":["trace[563038736] 'process raft request' (duration: 1.004119485s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.371231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:33.365689Z","time spent":"1.005458379s","remote":"127.0.0.1:50462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:49:34.74917Z","caller":"traceutil/trace.go:171","msg":"trace[1439647945] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"363.438028ms","start":"2026-07-29T15:49:34.385697Z","end":"2026-07-29T15:49:34.749135Z","steps":["trace[1439647945] 'process raft request' (duration: 363.291445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.749502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:34.385675Z","time spent":"363.739716ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:49:34.750746Z","caller":"traceutil/trace.go:171","msg":"trace[805580786] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"348.893628ms","start":"2026-07-29T15:49:34.401835Z","end":"2026-07-29T15:49:34.750729Z","steps":["trace[805580786] 'process raft request' (duration: 348.237791ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:34.750845Z","caller":"traceutil/trace.go:171","msg":"trace[1841310998] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"348.828646ms","start":"2026-07-29T15:49:34.401942Z","end":"2026-07-29T15:49:34.75077Z","steps":["trace[1841310998] 'process raft request' (duration: 348.159298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.750976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:34.401922Z","time spent":"349.011281ms","remote":"127.0.0.1:50288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-29T15:49:34.749591Z","caller":"traceutil/trace.go:171","msg":"trace[1556546305] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"359.363971ms","start":"2026-07-29T15:49:34.390215Z","end":"2026-07-29T15:49:34.749579Z","steps":["trace[1556546305] 'process raft request' (duration: 358.923529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.751064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:34.390207Z","time spent":"360.824119ms","remote":"127.0.0.1:50606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-29T15:49:34.750878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:34.401815Z","time spent":"349.001651ms","remote":"127.0.0.1:50394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-29T15:49:34.749235Z","caller":"traceutil/trace.go:171","msg":"trace[1451037849] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"363.538101ms","start":"2026-07-29T15:49:34.385663Z","end":"2026-07-29T15:49:34.749202Z","steps":["trace[1451037849] 'process raft request' (duration: 264.440652ms)","trace[1451037849] 'compare' (duration: 98.73643ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:49:34.751346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:34.385656Z","time spent":"365.632126ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:49:34.749285Z","caller":"traceutil/trace.go:171","msg":"trace[1074631026] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"359.199876ms","start":"2026-07-29T15:49:34.390076Z","end":"2026-07-29T15:49:34.749276Z","steps":["trace[1074631026] 'process raft request' (duration: 359.026672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.751479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:34.390068Z","time spent":"361.364904ms","remote":"127.0.0.1:50290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:49:34.749208Z","caller":"traceutil/trace.go:171","msg":"trace[1354957881] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"361.067705ms","start":"2026-07-29T15:49:34.388108Z","end":"2026-07-29T15:49:34.749176Z","steps":["trace[1354957881] 'process raft request' (duration: 360.945522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.751769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:34.3881Z","time spent":"363.546401ms","remote":"127.0.0.1:50462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:49:34.750969Z","caller":"traceutil/trace.go:171","msg":"trace[1931910502] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"354.698659ms","start":"2026-07-29T15:49:34.396255Z","end":"2026-07-29T15:49:34.750954Z","steps":["trace[1931910502] 'process raft request' (duration: 353.787415ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:34.750167Z","caller":"traceutil/trace.go:171","msg":"trace[224392387] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:362; }","duration":"171.518523ms","start":"2026-07-29T15:49:34.578632Z","end":"2026-07-29T15:49:34.75015Z","steps":["trace[224392387] 'read index received' (duration: 71.580172ms)","trace[224392387] 'applied index is now lower than readState.Index' (duration: 99.937551ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:49:34.750272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.625715ms","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-29T15:49:34.750312Z","caller":"traceutil/trace.go:171","msg":"trace[302974460] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"354.296318ms","start":"2026-07-29T15:49:34.396004Z","end":"2026-07-29T15:49:34.7503Z","steps":["trace[302974460] 'process raft request' (duration: 353.9667ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:34.750352Z","caller":"traceutil/trace.go:171","msg":"trace[1184906645] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"360.102779ms","start":"2026-07-29T15:49:34.390234Z","end":"2026-07-29T15:49:34.750337Z","steps":["trace[1184906645] 'process raft request' (duration: 359.186636ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:34.750441Z","caller":"traceutil/trace.go:171","msg":"trace[1133165269] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"354.282818ms","start":"2026-07-29T15:49:34.396148Z","end":"2026-07-29T15:49:34.750431Z","steps":["trace[1133165269] 'process raft request' (duration: 353.858447ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:34.750477Z","caller":"traceutil/trace.go:171","msg":"trace[687531999] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"360.212463ms","start":"2026-07-29T15:49:34.390254Z","end":"2026-07-29T15:49:34.750467Z","steps":["trace[687531999] 'process raft request' (duration: 359.613487ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:34.750507Z","caller":"traceutil/trace.go:171","msg":"trace[843577839] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"360.197113ms","start":"2026-07-29T15:49:34.390303Z","end":"2026-07-29T15:49:34.7505Z","steps":["trace[843577839] 'process raft request' (duration: 359.611167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.752014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:34.390295Z","time spent":"361.671082ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:49:34.752163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:34.395989Z","time spent":"356.137047ms","remote":"127.0.0.1:50598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-29T15:49:34.752196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:34.39625Z","time spent":"355.909021ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-29T15:49:34.752206Z","caller":"traceutil/trace.go:171","msg":"trace[2038915020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:365; }","duration":"173.619627ms","start":"2026-07-29T15:49:34.578574Z","end":"2026-07-29T15:49:34.752194Z","steps":["trace[2038915020] 'agreement among raft nodes before linearized reading' (duration: 171.625395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.752371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:34.396143Z","time spent":"356.198988ms","remote":"127.0.0.1:50462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:49:34.752422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:34.390222Z","time spent":"362.173135ms","remote":"127.0.0.1:50370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:49:34.752424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:34.390242Z","time spent":"362.146133ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-29T15:49:34.938664Z","caller":"traceutil/trace.go:171","msg":"trace[381501815] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"176.76652ms","start":"2026-07-29T15:49:34.761868Z","end":"2026-07-29T15:49:34.938634Z","steps":["trace[381501815] 'process raft request' (duration: 144.429194ms)","trace[381501815] 'compare' (duration: 32.174891ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:49:34.959216Z","caller":"traceutil/trace.go:171","msg":"trace[1910626727] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:375; }","duration":"196.70268ms","start":"2026-07-29T15:49:34.762489Z","end":"2026-07-29T15:49:34.959192Z","steps":["trace[1910626727] 'read index received' (duration: 143.746816ms)","trace[1910626727] 'applied index is now lower than readState.Index' (duration: 52.954994ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:49:34.959343Z","caller":"traceutil/trace.go:171","msg":"trace[1305663873] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"195.645323ms","start":"2026-07-29T15:49:34.763689Z","end":"2026-07-29T15:49:34.959334Z","steps":["trace[1305663873] 'process raft request' (duration: 195.378316ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:34.959273Z","caller":"traceutil/trace.go:171","msg":"trace[38674476] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"196.798282ms","start":"2026-07-29T15:49:34.762445Z","end":"2026-07-29T15:49:34.959243Z","steps":["trace[38674476] 'process raft request' (duration: 196.397322ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:34.959617Z","caller":"traceutil/trace.go:171","msg":"trace[200739125] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"194.449572ms","start":"2026-07-29T15:49:34.765155Z","end":"2026-07-29T15:49:34.959605Z","steps":["trace[200739125] 'process raft request' (duration: 193.99511ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:34.959637Z","caller":"traceutil/trace.go:171","msg":"trace[1915148393] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"197.035059ms","start":"2026-07-29T15:49:34.762569Z","end":"2026-07-29T15:49:34.959605Z","steps":["trace[1915148393] 'process raft request' (duration: 196.432053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.959765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.183154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:49:34.959841Z","caller":"traceutil/trace.go:171","msg":"trace[965549114] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"195.13979ms","start":"2026-07-29T15:49:34.764692Z","end":"2026-07-29T15:49:34.959831Z","steps":["trace[965549114] 'process raft request' (duration: 194.420802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:34.95991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.417488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-29T15:49:34.959801Z","caller":"traceutil/trace.go:171","msg":"trace[834650272] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"197.143512ms","start":"2026-07-29T15:49:34.762539Z","end":"2026-07-29T15:49:34.959683Z","steps":["trace[834650272] 'process raft request' (duration: 196.411803ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:34.959971Z","caller":"traceutil/trace.go:171","msg":"trace[2035104234] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:373; }","duration":"197.501691ms","start":"2026-07-29T15:49:34.76246Z","end":"2026-07-29T15:49:34.959962Z","steps":["trace[2035104234] 'agreement among raft nodes before linearized reading' (duration: 197.409758ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:34.959845Z","caller":"traceutil/trace.go:171","msg":"trace[831391883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:373; }","duration":"119.315847ms","start":"2026-07-29T15:49:34.84051Z","end":"2026-07-29T15:49:34.959826Z","steps":["trace[831391883] 'agreement among raft nodes before linearized reading' (duration: 119.153283ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:34.960183Z","caller":"traceutil/trace.go:171","msg":"trace[1030574020] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"196.71445ms","start":"2026-07-29T15:49:34.763454Z","end":"2026-07-29T15:49:34.960169Z","steps":["trace[1030574020] 'process raft request' (duration: 195.575831ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:49:55.363105Z","caller":"traceutil/trace.go:171","msg":"trace[12564548] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"341.404951ms","start":"2026-07-29T15:49:55.021676Z","end":"2026-07-29T15:49:55.363081Z","steps":["trace[12564548] 'process raft request' (duration: 341.270527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:49:55.363299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:49:55.021656Z","time spent":"341.524284ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:50:03.025747Z","caller":"traceutil/trace.go:171","msg":"trace[1712338679] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"134.594057ms","start":"2026-07-29T15:50:02.89113Z","end":"2026-07-29T15:50:03.025725Z","steps":["trace[1712338679] 'read index received' (duration: 128.181579ms)","trace[1712338679] 'applied index is now lower than readState.Index' (duration: 6.411678ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:50:03.025885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.743111ms","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-29T15:50:03.02591Z","caller":"traceutil/trace.go:171","msg":"trace[173808429] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"134.801833ms","start":"2026-07-29T15:50:02.891102Z","end":"2026-07-29T15:50:03.025904Z","steps":["trace[173808429] 'agreement among raft nodes before linearized reading' (duration: 134.70141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:03.025989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.288644ms","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-29T15:50:03.026026Z","caller":"traceutil/trace.go:171","msg":"trace[1979285777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"125.352406ms","start":"2026-07-29T15:50:02.900665Z","end":"2026-07-29T15:50:03.026017Z","steps":["trace[1979285777] 'agreement among raft nodes before linearized reading' (duration: 125.259664ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:50:03.02606Z","caller":"traceutil/trace.go:171","msg":"trace[1757489121] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"136.084707ms","start":"2026-07-29T15:50:02.889968Z","end":"2026-07-29T15:50:03.026053Z","steps":["trace[1757489121] 'process raft request' (duration: 129.272258ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:50:03.026222Z","caller":"traceutil/trace.go:171","msg":"trace[77168834] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"121.384662ms","start":"2026-07-29T15:50:02.904831Z","end":"2026-07-29T15:50:03.026216Z","steps":["trace[77168834] 'process raft request' (duration: 121.016093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:03.71917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882612559358944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-29T15:50:04.219749Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882612559358944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-29T15:50:04.429088Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.217530408s","expected-duration":"1s"} {"level":"info","ts":"2026-07-29T15:50:04.460947Z","caller":"traceutil/trace.go:171","msg":"trace[137251943] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.249129774s","start":"2026-07-29T15:50:03.211425Z","end":"2026-07-29T15:50:04.460555Z","steps":["trace[137251943] 'process raft request' (duration: 1.21799152s)","trace[137251943] 'compare' (duration: 30.59198ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:50:04.46131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.21141Z","time spent":"1.249676447s","remote":"127.0.0.1:50188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:50:04.474249Z","caller":"traceutil/trace.go:171","msg":"trace[238480458] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.112953726s","start":"2026-07-29T15:50:03.361273Z","end":"2026-07-29T15:50:04.474226Z","steps":["trace[238480458] 'process raft request' (duration: 1.112390091s)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:50:04.474324Z","caller":"traceutil/trace.go:171","msg":"trace[1890961364] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:613; }","duration":"1.255747486s","start":"2026-07-29T15:50:03.218564Z","end":"2026-07-29T15:50:04.474311Z","steps":["trace[1890961364] 'read index received' (duration: 1.210998417s)","trace[1890961364] 'applied index is now lower than readState.Index' (duration: 44.747999ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:50:04.474364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.36125Z","time spent":"1.113054098s","remote":"127.0.0.1:50188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:50:04.474647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256078035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.474682Z","caller":"traceutil/trace.go:171","msg":"trace[1909973509] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:596; }","duration":"1.256136526s","start":"2026-07-29T15:50:03.218538Z","end":"2026-07-29T15:50:04.474674Z","steps":["trace[1909973509] 'agreement among raft nodes before linearized reading' (duration: 1.256044284s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.4747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.218527Z","time spent":"1.256169427s","remote":"127.0.0.1:53896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-07-29T15:50:04.474821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256222758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.474854Z","caller":"traceutil/trace.go:171","msg":"trace[940507048] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:596; }","duration":"1.25627444s","start":"2026-07-29T15:50:03.218566Z","end":"2026-07-29T15:50:04.474841Z","steps":["trace[940507048] 'agreement among raft nodes before linearized reading' (duration: 1.256232149s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.47487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.218553Z","time spent":"1.25631282s","remote":"127.0.0.1:53896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-29T15:50:04.474927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.628225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.474944Z","caller":"traceutil/trace.go:171","msg":"trace[348633446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"634.681676ms","start":"2026-07-29T15:50:03.840257Z","end":"2026-07-29T15:50:04.474939Z","steps":["trace[348633446] 'agreement among raft nodes before linearized reading' (duration: 634.655336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.474939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125934734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T15:50:04.474955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.840238Z","time spent":"634.714798ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-29T15:50:04.474966Z","caller":"traceutil/trace.go:171","msg":"trace[2120757322] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:596; }","duration":"1.125972475s","start":"2026-07-29T15:50:03.348986Z","end":"2026-07-29T15:50:04.474958Z","steps":["trace[2120757322] 'agreement among raft nodes before linearized reading' (duration: 1.125928914s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.474994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.348982Z","time spent":"1.126005957s","remote":"127.0.0.1:53914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-07-29T15:50:04.475037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126275953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.475058Z","caller":"traceutil/trace.go:171","msg":"trace[199385246] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:596; }","duration":"1.126307114s","start":"2026-07-29T15:50:03.348746Z","end":"2026-07-29T15:50:04.475053Z","steps":["trace[199385246] 'agreement among raft nodes before linearized reading' (duration: 1.126275773s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.475093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.348743Z","time spent":"1.126340475s","remote":"127.0.0.1:53914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-07-29T15:50:04.475122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073031452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.475162Z","caller":"traceutil/trace.go:171","msg":"trace[2033127323] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:596; }","duration":"1.073075353s","start":"2026-07-29T15:50:03.402078Z","end":"2026-07-29T15:50:04.475153Z","steps":["trace[2033127323] 'agreement among raft nodes before linearized reading' (duration: 1.073020051s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.475189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126448448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T15:50:04.475195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.402073Z","time spent":"1.073112664s","remote":"127.0.0.1:53932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"info","ts":"2026-07-29T15:50:04.475208Z","caller":"traceutil/trace.go:171","msg":"trace[700533676] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:596; }","duration":"1.126478118s","start":"2026-07-29T15:50:03.348725Z","end":"2026-07-29T15:50:04.475204Z","steps":["trace[700533676] 'agreement among raft nodes before linearized reading' (duration: 1.126446867s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.475229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.348717Z","time spent":"1.126505359s","remote":"127.0.0.1:53914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-07-29T15:50:04.47531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138005389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.47533Z","caller":"traceutil/trace.go:171","msg":"trace[487148613] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:596; }","duration":"1.1380338s","start":"2026-07-29T15:50:03.337292Z","end":"2026-07-29T15:50:04.475326Z","steps":["trace[487148613] 'agreement among raft nodes before linearized reading' (duration: 1.13800192s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.475325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073491773s","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:5"} {"level":"warn","ts":"2026-07-29T15:50:04.475345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.33729Z","time spent":"1.138050701s","remote":"127.0.0.1:53906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-07-29T15:50:04.475351Z","caller":"traceutil/trace.go:171","msg":"trace[1775139476] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:596; }","duration":"1.073529095s","start":"2026-07-29T15:50:03.401814Z","end":"2026-07-29T15:50:04.475343Z","steps":["trace[1775139476] 'agreement among raft nodes before linearized reading' (duration: 1.073487734s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.475382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.40181Z","time spent":"1.073564936s","remote":"127.0.0.1:53940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":26,"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-29T15:50:04.475547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0737383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.475591Z","caller":"traceutil/trace.go:171","msg":"trace[1367397747] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:596; }","duration":"1.073798662s","start":"2026-07-29T15:50:03.401783Z","end":"2026-07-29T15:50:04.475582Z","steps":["trace[1367397747] 'agreement among raft nodes before linearized reading' (duration: 1.07373768s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.475618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.401775Z","time spent":"1.073835103s","remote":"127.0.0.1:53940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-07-29T15:50:04.475824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.083302029s","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-29T15:50:04.475858Z","caller":"traceutil/trace.go:171","msg":"trace[1192476158] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"1.083361411s","start":"2026-07-29T15:50:03.39249Z","end":"2026-07-29T15:50:04.475851Z","steps":["trace[1192476158] 'agreement among raft nodes before linearized reading' (duration: 1.083229368s)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:50:04.475827Z","caller":"traceutil/trace.go:171","msg":"trace[1260983674] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.029106503s","start":"2026-07-29T15:50:03.446669Z","end":"2026-07-29T15:50:04.475776Z","steps":["trace[1260983674] 'process raft request' (duration: 1.027373998s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.47588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.392479Z","time spent":"1.083396252s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-29T15:50:04.475978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.446637Z","time spent":"1.029263927s","remote":"127.0.0.1:50188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-29T15:50:04.476024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086040781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.476044Z","caller":"traceutil/trace.go:171","msg":"trace[1285604596] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:596; }","duration":"1.086061342s","start":"2026-07-29T15:50:03.389976Z","end":"2026-07-29T15:50:04.476038Z","steps":["trace[1285604596] 'agreement among raft nodes before linearized reading' (duration: 1.086034191s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.47607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.389961Z","time spent":"1.086103732s","remote":"127.0.0.1:53932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-07-29T15:50:04.476168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109343331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T15:50:04.475998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085963439s","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:5"} {"level":"warn","ts":"2026-07-29T15:50:04.47622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110220083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.476225Z","caller":"traceutil/trace.go:171","msg":"trace[36713482] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:596; }","duration":"1.086182624s","start":"2026-07-29T15:50:03.390027Z","end":"2026-07-29T15:50:04.47621Z","steps":["trace[36713482] 'agreement among raft nodes before linearized reading' (duration: 1.085951298s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.476266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110295756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.476287Z","caller":"traceutil/trace.go:171","msg":"trace[1553859717] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:596; }","duration":"1.110332767s","start":"2026-07-29T15:50:03.36595Z","end":"2026-07-29T15:50:04.476283Z","steps":["trace[1553859717] 'agreement among raft nodes before linearized reading' (duration: 1.110303705s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.47629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.390018Z","time spent":"1.086251007s","remote":"127.0.0.1:53932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":26,"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-29T15:50:04.47631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.365943Z","time spent":"1.110362208s","remote":"127.0.0.1:53918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-07-29T15:50:04.476192Z","caller":"traceutil/trace.go:171","msg":"trace[1404529497] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:596; }","duration":"1.109377422s","start":"2026-07-29T15:50:03.366809Z","end":"2026-07-29T15:50:04.476187Z","steps":["trace[1404529497] 'agreement among raft nodes before linearized reading' (duration: 1.109340341s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.476582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.366803Z","time spent":"1.109760742s","remote":"127.0.0.1:53918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-07-29T15:50:04.476616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139322075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.476637Z","caller":"traceutil/trace.go:171","msg":"trace[1801706036] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:596; }","duration":"1.139353425s","start":"2026-07-29T15:50:03.337279Z","end":"2026-07-29T15:50:04.476632Z","steps":["trace[1801706036] 'agreement among raft nodes before linearized reading' (duration: 1.139318524s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.47666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.337276Z","time spent":"1.139379506s","remote":"127.0.0.1:53906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"info","ts":"2026-07-29T15:50:04.476242Z","caller":"traceutil/trace.go:171","msg":"trace[80597795] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:596; }","duration":"1.110252934s","start":"2026-07-29T15:50:03.365984Z","end":"2026-07-29T15:50:04.476237Z","steps":["trace[80597795] 'agreement among raft nodes before linearized reading' (duration: 1.110220023s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.476779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.36598Z","time spent":"1.110785409s","remote":"127.0.0.1:53918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-07-29T15:50:04.477238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231173855s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.477267Z","caller":"traceutil/trace.go:171","msg":"trace[1008116346] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:596; }","duration":"1.231222356s","start":"2026-07-29T15:50:03.246037Z","end":"2026-07-29T15:50:04.477259Z","steps":["trace[1008116346] 'agreement among raft nodes before linearized reading' (duration: 1.231163505s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.477284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.246033Z","time spent":"1.231247067s","remote":"127.0.0.1:53900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-29T15:50:04.477377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140109804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.477401Z","caller":"traceutil/trace.go:171","msg":"trace[425661857] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:596; }","duration":"1.140155386s","start":"2026-07-29T15:50:03.33724Z","end":"2026-07-29T15:50:04.477396Z","steps":["trace[425661857] 'agreement among raft nodes before linearized reading' (duration: 1.140121275s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.477423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.337227Z","time spent":"1.140190426s","remote":"127.0.0.1:53906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"warn","ts":"2026-07-29T15:50:04.477468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204162589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.477485Z","caller":"traceutil/trace.go:171","msg":"trace[331560356] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:596; }","duration":"1.204179539s","start":"2026-07-29T15:50:03.273301Z","end":"2026-07-29T15:50:04.477481Z","steps":["trace[331560356] 'agreement among raft nodes before linearized reading' (duration: 1.204152998s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.477498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.273298Z","time spent":"1.204196319s","remote":"127.0.0.1:53902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-29T15:50:04.477594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204295972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.477611Z","caller":"traceutil/trace.go:171","msg":"trace[1365278097] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:596; }","duration":"1.204328493s","start":"2026-07-29T15:50:03.273279Z","end":"2026-07-29T15:50:04.477607Z","steps":["trace[1365278097] 'agreement among raft nodes before linearized reading' (duration: 1.204303843s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.477624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.273275Z","time spent":"1.204346234s","remote":"127.0.0.1:53902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-07-29T15:50:04.477682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204432966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.477699Z","caller":"traceutil/trace.go:171","msg":"trace[1040536858] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:596; }","duration":"1.204476077s","start":"2026-07-29T15:50:03.273219Z","end":"2026-07-29T15:50:04.477695Z","steps":["trace[1040536858] 'agreement among raft nodes before linearized reading' (duration: 1.204448956s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.477712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.273199Z","time spent":"1.204508948s","remote":"127.0.0.1:53902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 "} {"level":"warn","ts":"2026-07-29T15:50:04.478179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.232145301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:04.478236Z","caller":"traceutil/trace.go:171","msg":"trace[321364573] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:596; }","duration":"1.232231462s","start":"2026-07-29T15:50:03.245992Z","end":"2026-07-29T15:50:04.478223Z","steps":["trace[321364573] 'agreement among raft nodes before linearized reading' (duration: 1.23214235s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:04.478276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:03.245976Z","time spent":"1.232291194s","remote":"127.0.0.1:53900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-07-29T15:50:05.443916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.712235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-07-29T15:50:05.444022Z","caller":"traceutil/trace.go:171","msg":"trace[822869032] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:614; }","duration":"182.876279ms","start":"2026-07-29T15:50:05.261124Z","end":"2026-07-29T15:50:05.444Z","steps":["trace[822869032] 'range keys from in-memory index tree' (duration: 182.463008ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:50:10.812969Z","caller":"traceutil/trace.go:171","msg":"trace[232941409] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"296.317023ms","start":"2026-07-29T15:50:10.516628Z","end":"2026-07-29T15:50:10.812945Z","steps":["trace[232941409] 'read index received' (duration: 295.566954ms)","trace[232941409] 'applied index is now lower than readState.Index' (duration: 749.069µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:50:10.813012Z","caller":"traceutil/trace.go:171","msg":"trace[626400466] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"343.492376ms","start":"2026-07-29T15:50:10.469481Z","end":"2026-07-29T15:50:10.812973Z","steps":["trace[626400466] 'process raft request' (duration: 342.948332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:10.813178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:10.46946Z","time spent":"343.61714ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:50:10.813248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.593921ms","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-29T15:50:10.813359Z","caller":"traceutil/trace.go:171","msg":"trace[1771719205] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"296.719035ms","start":"2026-07-29T15:50:10.5166Z","end":"2026-07-29T15:50:10.813319Z","steps":["trace[1771719205] 'agreement among raft nodes before linearized reading' (duration: 296.472588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:13.324822Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882612559359085,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-29T15:50:13.595008Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.239452622s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-29T15:50:14.421379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.131769ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-07-29T15:50:14.421705Z","caller":"traceutil/trace.go:171","msg":"trace[652552233] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:676; }","duration":"1.59758622s","start":"2026-07-29T15:50:12.824081Z","end":"2026-07-29T15:50:14.421667Z","steps":["trace[652552233] 'read index received' (duration: 771.153714ms)","trace[652552233] 'applied index is now lower than readState.Index' (duration: 826.430886ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:50:14.421826Z","caller":"traceutil/trace.go:171","msg":"trace[1542233337] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.588200394s","start":"2026-07-29T15:50:12.83357Z","end":"2026-07-29T15:50:14.42177Z","steps":["trace[1542233337] 'process raft request' (duration: 1.587902876s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:14.421953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:12.833556Z","time spent":"1.588329558s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:50:14.465791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353419349s","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-29T15:50:14.465865Z","caller":"traceutil/trace.go:171","msg":"trace[1409530474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:656; }","duration":"1.353537471s","start":"2026-07-29T15:50:13.112308Z","end":"2026-07-29T15:50:14.465846Z","steps":["trace[1409530474] 'agreement among raft nodes before linearized reading' (duration: 1.309507401s)","trace[1409530474] 'range keys from in-memory index tree' (duration: 43.848556ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:50:14.465904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:13.112286Z","time spent":"1.353608974s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-29T15:50:14.465894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.641750264s","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-29T15:50:14.465946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.626142245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:14.465988Z","caller":"traceutil/trace.go:171","msg":"trace[480776214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:656; }","duration":"1.641930188s","start":"2026-07-29T15:50:12.824033Z","end":"2026-07-29T15:50:14.465963Z","steps":["trace[480776214] 'agreement among raft nodes before linearized reading' (duration: 1.597750234s)","trace[480776214] 'range keys from in-memory index tree' (duration: 43.897426ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:50:14.466003Z","caller":"traceutil/trace.go:171","msg":"trace[298719637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"1.626226848s","start":"2026-07-29T15:50:12.839759Z","end":"2026-07-29T15:50:14.465986Z","steps":["trace[298719637] 'agreement among raft nodes before linearized reading' (duration: 1.582076485s)","trace[298719637] 'range keys from in-memory index tree' (duration: 44.075611ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:50:14.466038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:12.839744Z","time spent":"1.62628735s","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-29T15:50:14.466041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:12.823991Z","time spent":"1.642040052s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-29T15:50:14.46615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.636867197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T15:50:14.466193Z","caller":"traceutil/trace.go:171","msg":"trace[815277160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:656; }","duration":"1.636939749s","start":"2026-07-29T15:50:12.829239Z","end":"2026-07-29T15:50:14.466179Z","steps":["trace[815277160] 'agreement among raft nodes before linearized reading' (duration: 1.592562159s)","trace[815277160] 'range keys from in-memory index tree' (duration: 44.275907ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:50:14.466237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:12.829223Z","time spent":"1.637003491s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-29T15:50:14.466526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355185325s","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-29T15:50:14.466577Z","caller":"traceutil/trace.go:171","msg":"trace[1748103499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"1.357730802s","start":"2026-07-29T15:50:13.108827Z","end":"2026-07-29T15:50:14.466558Z","steps":["trace[1748103499] 'agreement among raft nodes before linearized reading' (duration: 1.312998372s)","trace[1748103499] 'range keys from in-memory index tree' (duration: 43.946088ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:50:14.466614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:13.108806Z","time spent":"1.357798714s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-29T15:50:16.494165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.734005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:16.494269Z","caller":"traceutil/trace.go:171","msg":"trace[1944469867] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:690; }","duration":"306.88872ms","start":"2026-07-29T15:50:16.187355Z","end":"2026-07-29T15:50:16.494243Z","steps":["trace[1944469867] 'range keys from in-memory index tree' (duration: 306.603302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:16.494321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:16.187335Z","time spent":"306.972233ms","remote":"127.0.0.1:50188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":26,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-07-29T15:50:16.49441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.426068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:16.494498Z","caller":"traceutil/trace.go:171","msg":"trace[341538052] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:690; }","duration":"107.547531ms","start":"2026-07-29T15:50:16.386926Z","end":"2026-07-29T15:50:16.494474Z","steps":["trace[341538052] 'range keys from in-memory index tree' (duration: 107.334736ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:50:27.302864Z","caller":"traceutil/trace.go:171","msg":"trace[2049339028] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"132.665638ms","start":"2026-07-29T15:50:27.170162Z","end":"2026-07-29T15:50:27.302828Z","steps":["trace[2049339028] 'read index received' (duration: 132.529984ms)","trace[2049339028] 'applied index is now lower than readState.Index' (duration: 134.344µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:50:27.303153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.957005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T15:50:27.303254Z","caller":"traceutil/trace.go:171","msg":"trace[1294833383] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:796; }","duration":"133.119699ms","start":"2026-07-29T15:50:27.170115Z","end":"2026-07-29T15:50:27.303234Z","steps":["trace[1294833383] 'agreement among raft nodes before linearized reading' (duration: 132.896073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:31.30422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.465370944s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T15:50:31.304378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.461966796s","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-29T15:50:31.304481Z","caller":"traceutil/trace.go:171","msg":"trace[273246621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:811; }","duration":"1.462097099s","start":"2026-07-29T15:50:29.842353Z","end":"2026-07-29T15:50:31.30445Z","steps":["trace[273246621] 'range keys from in-memory index tree' (duration: 1.461801581s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:31.304533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:29.842341Z","time spent":"1.462181772s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-29T15:50:31.304749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:29.77982Z","time spent":"1.52492364s","remote":"127.0.0.1:36652","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-29T15:50:31.305112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.076399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T15:50:31.305242Z","caller":"traceutil/trace.go:171","msg":"trace[1332134539] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"1.236146265s","start":"2026-07-29T15:50:30.069075Z","end":"2026-07-29T15:50:31.305222Z","steps":["trace[1332134539] 'process raft request' (duration: 818.86123ms)","trace[1332134539] 'compare' (duration: 416.476913ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:50:31.30532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:30.069048Z","time spent":"1.236236917s","remote":"127.0.0.1:50360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:50:31.304326Z","caller":"traceutil/trace.go:171","msg":"trace[882531219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:811; }","duration":"1.465515848s","start":"2026-07-29T15:50:29.838777Z","end":"2026-07-29T15:50:31.304293Z","steps":["trace[882531219] 'range keys from in-memory index tree' (duration: 1.465291892s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:31.305864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:29.838761Z","time spent":"1.46708658s","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-29T15:50:31.748218Z","caller":"traceutil/trace.go:171","msg":"trace[1248999931] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:838; }","duration":"1.183842068s","start":"2026-07-29T15:50:30.564349Z","end":"2026-07-29T15:50:31.748191Z","steps":["trace[1248999931] 'read index received' (duration: 323.680769ms)","trace[1248999931] 'applied index is now lower than readState.Index' (duration: 860.160659ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:50:31.748253Z","caller":"traceutil/trace.go:171","msg":"trace[1694234657] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"1.671748388s","start":"2026-07-29T15:50:30.076473Z","end":"2026-07-29T15:50:31.748221Z","steps":["trace[1694234657] 'process raft request' (duration: 1.671480411s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:31.748409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:30.076445Z","time spent":"1.671883831s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:50:31.748457Z","caller":"traceutil/trace.go:171","msg":"trace[505003957] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"1.53482947s","start":"2026-07-29T15:50:30.213614Z","end":"2026-07-29T15:50:31.748443Z","steps":["trace[505003957] 'process raft request' (duration: 1.534526222s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:31.748512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:30.213589Z","time spent":"1.534891781s","remote":"127.0.0.1:50290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:50:31.749928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185560752s","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-29T15:50:31.751067Z","caller":"traceutil/trace.go:171","msg":"trace[639248426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:814; }","duration":"1.186762204s","start":"2026-07-29T15:50:30.564285Z","end":"2026-07-29T15:50:31.751047Z","steps":["trace[639248426] 'agreement among raft nodes before linearized reading' (duration: 1.184180527s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:31.751121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:30.564258Z","time spent":"1.186848296s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-29T15:50:31.755327Z","caller":"traceutil/trace.go:171","msg":"trace[1951316847] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"440.196863ms","start":"2026-07-29T15:50:31.315113Z","end":"2026-07-29T15:50:31.75531Z","steps":["trace[1951316847] 'process raft request' (duration: 439.986088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:31.755449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:31.315096Z","time spent":"440.284406ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:50:31.755817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184965437s","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-29T15:50:31.755842Z","caller":"traceutil/trace.go:171","msg":"trace[1286060845] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:815; }","duration":"1.185021778s","start":"2026-07-29T15:50:30.570813Z","end":"2026-07-29T15:50:31.755835Z","steps":["trace[1286060845] 'agreement among raft nodes before linearized reading' (duration: 1.184928025s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:31.755861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:30.570793Z","time spent":"1.185064219s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-29T15:50:31.755959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.753007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:31.755976Z","caller":"traceutil/trace.go:171","msg":"trace[971021000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"448.805608ms","start":"2026-07-29T15:50:31.307165Z","end":"2026-07-29T15:50:31.755971Z","steps":["trace[971021000] 'agreement among raft nodes before linearized reading' (duration: 448.764917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:31.755997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:31.307144Z","time spent":"448.84801ms","remote":"127.0.0.1:50120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-29T15:50:31.75616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184898725s","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-29T15:50:31.756183Z","caller":"traceutil/trace.go:171","msg":"trace[1970989183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:816; }","duration":"1.184941496s","start":"2026-07-29T15:50:30.571235Z","end":"2026-07-29T15:50:31.756176Z","steps":["trace[1970989183] 'agreement among raft nodes before linearized reading' (duration: 1.184861953s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:31.756199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:30.571226Z","time spent":"1.184969697s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-29T15:50:31.756354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185487631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T15:50:31.756405Z","caller":"traceutil/trace.go:171","msg":"trace[293666834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:816; }","duration":"1.185570773s","start":"2026-07-29T15:50:30.57082Z","end":"2026-07-29T15:50:31.756391Z","steps":["trace[293666834] 'agreement among raft nodes before linearized reading' (duration: 1.18543973s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:31.756433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:50:30.570805Z","time spent":"1.185620825s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-29T15:51:06.754671Z","caller":"traceutil/trace.go:171","msg":"trace[254577965] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"817.746594ms","start":"2026-07-29T15:51:05.936904Z","end":"2026-07-29T15:51:06.754651Z","steps":["trace[254577965] 'process raft request' (duration: 817.61543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:06.7548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:05.936897Z","time spent":"817.835407ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:51:07.342305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882612559359893,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-29T15:51:08.464595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.529231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T15:51:08.4648Z","caller":"traceutil/trace.go:171","msg":"trace[1408020477] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"2.526554524s","start":"2026-07-29T15:51:05.938208Z","end":"2026-07-29T15:51:08.464762Z","steps":["trace[1408020477] 'process raft request' (duration: 1.611762616s)","trace[1408020477] 'compare' (duration: 914.382787ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:51:08.464899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.623527809s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T15:51:08.464956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:05.938188Z","time spent":"2.526687888s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:51:08.465001Z","caller":"traceutil/trace.go:171","msg":"trace[619067843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1031; }","duration":"1.623619741s","start":"2026-07-29T15:51:06.841314Z","end":"2026-07-29T15:51:08.464934Z","steps":["trace[619067843] 'agreement among raft nodes before linearized reading' (duration: 1.623543759s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:08.465028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:06.841285Z","time spent":"1.623736274s","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-29T15:51:08.464835Z","caller":"traceutil/trace.go:171","msg":"trace[12733746] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1063; }","duration":"1.623439456s","start":"2026-07-29T15:51:06.841382Z","end":"2026-07-29T15:51:08.464822Z","steps":["trace[12733746] 'read index received' (duration: 708.533546ms)","trace[12733746] 'applied index is now lower than readState.Index' (duration: 914.90475ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:51:08.465328Z","caller":"traceutil/trace.go:171","msg":"trace[1656419979] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"2.207269271s","start":"2026-07-29T15:51:06.25804Z","end":"2026-07-29T15:51:08.46531Z","steps":["trace[1656419979] 'process raft request' (duration: 2.206737927s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:08.465396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:06.258004Z","time spent":"2.207353913s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:51:08.465485Z","caller":"traceutil/trace.go:171","msg":"trace[1545255722] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"2.525136726s","start":"2026-07-29T15:51:05.940342Z","end":"2026-07-29T15:51:08.465479Z","steps":["trace[1545255722] 'process raft request' (duration: 2.524335884s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:08.465488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.344288063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-29T15:51:08.465551Z","caller":"traceutil/trace.go:171","msg":"trace[1453594953] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"2.525115426s","start":"2026-07-29T15:51:05.940391Z","end":"2026-07-29T15:51:08.465506Z","steps":["trace[1453594953] 'process raft request' (duration: 2.524364935s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:08.465628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138480265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T15:51:08.465641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.88336ms","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-29T15:51:08.465674Z","caller":"traceutil/trace.go:171","msg":"trace[498538198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1031; }","duration":"526.952731ms","start":"2026-07-29T15:51:07.938714Z","end":"2026-07-29T15:51:08.465667Z","steps":["trace[498538198] 'agreement among raft nodes before linearized reading' (duration: 526.856039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:08.465671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:05.940386Z","time spent":"2.525227819s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:51:08.465681Z","caller":"traceutil/trace.go:171","msg":"trace[606631075] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1031; }","duration":"1.138587367s","start":"2026-07-29T15:51:07.32708Z","end":"2026-07-29T15:51:08.465668Z","steps":["trace[606631075] 'agreement among raft nodes before linearized reading' (duration: 1.138498635s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:08.465694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:07.938695Z","time spent":"526.994563ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-29T15:51:08.46572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:07.327058Z","time spent":"1.138650588s","remote":"127.0.0.1:50360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":26,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-07-29T15:51:08.46563Z","caller":"traceutil/trace.go:171","msg":"trace[1087484864] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:1031; }","duration":"1.344477639s","start":"2026-07-29T15:51:07.121124Z","end":"2026-07-29T15:51:08.465602Z","steps":["trace[1087484864] 'agreement among raft nodes before linearized reading' (duration: 1.3441762s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:08.465562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:05.940332Z","time spent":"2.525169678s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:51:08.465773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:07.121102Z","time spent":"1.344659694s","remote":"127.0.0.1:50168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"info","ts":"2026-07-29T15:51:08.597912Z","caller":"traceutil/trace.go:171","msg":"trace[2072355138] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"127.614708ms","start":"2026-07-29T15:51:08.469854Z","end":"2026-07-29T15:51:08.597468Z","steps":["trace[2072355138] 'process raft request' (duration: 124.805333ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:51:33.257882Z","caller":"traceutil/trace.go:171","msg":"trace[926870801] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"382.060183ms","start":"2026-07-29T15:51:32.875543Z","end":"2026-07-29T15:51:33.257603Z","steps":["trace[926870801] 'process raft request' (duration: 381.901969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:33.258029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:32.87552Z","time spent":"382.438673ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:51:34.785995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.034532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:51:34.786098Z","caller":"traceutil/trace.go:171","msg":"trace[1944021180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1113; }","duration":"945.145405ms","start":"2026-07-29T15:51:33.840926Z","end":"2026-07-29T15:51:34.786072Z","steps":["trace[1944021180] 'range keys from in-memory index tree' (duration: 944.955761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:34.786154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:33.840873Z","time spent":"945.268079ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-29T15:51:34.797419Z","caller":"traceutil/trace.go:171","msg":"trace[954600833] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"228.325969ms","start":"2026-07-29T15:51:34.569077Z","end":"2026-07-29T15:51:34.797403Z","steps":["trace[954600833] 'process raft request' (duration: 228.188176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:34.797623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.348991ms","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-29T15:51:34.797608Z","caller":"traceutil/trace.go:171","msg":"trace[1106279722] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1155; }","duration":"216.100234ms","start":"2026-07-29T15:51:34.581268Z","end":"2026-07-29T15:51:34.797368Z","steps":["trace[1106279722] 'read index received' (duration: 215.93873ms)","trace[1106279722] 'applied index is now lower than readState.Index' (duration: 160.824µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:51:34.797705Z","caller":"traceutil/trace.go:171","msg":"trace[370754856] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1114; }","duration":"216.435283ms","start":"2026-07-29T15:51:34.581257Z","end":"2026-07-29T15:51:34.797692Z","steps":["trace[370754856] 'agreement among raft nodes before linearized reading' (duration: 216.235988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:34.797937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.588611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T15:51:34.797969Z","caller":"traceutil/trace.go:171","msg":"trace[622583249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1114; }","duration":"200.658943ms","start":"2026-07-29T15:51:34.597302Z","end":"2026-07-29T15:51:34.797961Z","steps":["trace[622583249] 'agreement among raft nodes before linearized reading' (duration: 200.53719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:34.798232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.952511ms","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-29T15:51:34.798282Z","caller":"traceutil/trace.go:171","msg":"trace[1938002025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1114; }","duration":"213.020932ms","start":"2026-07-29T15:51:34.585252Z","end":"2026-07-29T15:51:34.798273Z","steps":["trace[1938002025] 'agreement among raft nodes before linearized reading' (duration: 212.56602ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:51:36.690006Z","caller":"traceutil/trace.go:171","msg":"trace[985119922] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1161; }","duration":"371.967055ms","start":"2026-07-29T15:51:36.318016Z","end":"2026-07-29T15:51:36.689983Z","steps":["trace[985119922] 'read index received' (duration: 371.758229ms)","trace[985119922] 'applied index is now lower than readState.Index' (duration: 207.996µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:51:36.690182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.130889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:51:36.69025Z","caller":"traceutil/trace.go:171","msg":"trace[1889319967] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"514.985482ms","start":"2026-07-29T15:51:36.175205Z","end":"2026-07-29T15:51:36.690191Z","steps":["trace[1889319967] 'process raft request' (duration: 514.633003ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:51:36.690276Z","caller":"traceutil/trace.go:171","msg":"trace[850083606] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1120; }","duration":"372.453978ms","start":"2026-07-29T15:51:36.317805Z","end":"2026-07-29T15:51:36.690259Z","steps":["trace[850083606] 'agreement among raft nodes before linearized reading' (duration: 372.276753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:36.690329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:36.317783Z","time spent":"372.529329ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-07-29T15:51:36.690372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:36.175176Z","time spent":"515.120746ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:51:37.008393Z","caller":"traceutil/trace.go:171","msg":"trace[948620174] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1162; }","duration":"165.827315ms","start":"2026-07-29T15:51:36.842547Z","end":"2026-07-29T15:51:37.008374Z","steps":["trace[948620174] 'read index received' (duration: 165.671091ms)","trace[948620174] 'applied index is now lower than readState.Index' (duration: 155.654µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:51:37.008496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.948078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:51:37.008517Z","caller":"traceutil/trace.go:171","msg":"trace[529069153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1121; }","duration":"166.02898ms","start":"2026-07-29T15:51:36.842481Z","end":"2026-07-29T15:51:37.00851Z","steps":["trace[529069153] 'agreement among raft nodes before linearized reading' (duration: 165.975029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:37.008714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.186336ms","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-29T15:51:37.008784Z","caller":"traceutil/trace.go:171","msg":"trace[2009314275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1121; }","duration":"136.353391ms","start":"2026-07-29T15:51:36.872418Z","end":"2026-07-29T15:51:37.008771Z","steps":["trace[2009314275] 'agreement among raft nodes before linearized reading' (duration: 136.100363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:37.008794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.297906ms","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-29T15:51:37.008876Z","caller":"traceutil/trace.go:171","msg":"trace[99469910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1121; }","duration":"131.389919ms","start":"2026-07-29T15:51:36.877462Z","end":"2026-07-29T15:51:37.008851Z","steps":["trace[99469910] 'agreement among raft nodes before linearized reading' (duration: 131.09876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:37.008795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.733111ms","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-29T15:51:37.009219Z","caller":"traceutil/trace.go:171","msg":"trace[167447759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1121; }","duration":"131.179523ms","start":"2026-07-29T15:51:36.878025Z","end":"2026-07-29T15:51:37.009205Z","steps":["trace[167447759] 'agreement among raft nodes before linearized reading' (duration: 130.601037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:37.008935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.545803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T15:51:37.009566Z","caller":"traceutil/trace.go:171","msg":"trace[147311764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1121; }","duration":"132.202899ms","start":"2026-07-29T15:51:36.877352Z","end":"2026-07-29T15:51:37.009555Z","steps":["trace[147311764] 'agreement among raft nodes before linearized reading' (duration: 131.519021ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:51:37.008936Z","caller":"traceutil/trace.go:171","msg":"trace[1030176562] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"205.305506ms","start":"2026-07-29T15:51:36.80314Z","end":"2026-07-29T15:51:37.008445Z","steps":["trace[1030176562] 'process raft request' (duration: 205.128352ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:51:39.256834Z","caller":"traceutil/trace.go:171","msg":"trace[559402294] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"211.958374ms","start":"2026-07-29T15:51:39.044845Z","end":"2026-07-29T15:51:39.256803Z","steps":["trace[559402294] 'process raft request' (duration: 207.406743ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:51:39.256635Z","caller":"traceutil/trace.go:171","msg":"trace[650253354] linearizableReadLoop","detail":"{readStateIndex:1172; appliedIndex:1171; }","duration":"209.970551ms","start":"2026-07-29T15:51:39.046629Z","end":"2026-07-29T15:51:39.256599Z","steps":["trace[650253354] 'read index received' (duration: 205.597594ms)","trace[650253354] 'applied index is now lower than readState.Index' (duration: 4.371097ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:51:39.257384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.736101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T15:51:39.257449Z","caller":"traceutil/trace.go:171","msg":"trace[1140570267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1129; }","duration":"210.832944ms","start":"2026-07-29T15:51:39.046589Z","end":"2026-07-29T15:51:39.257422Z","steps":["trace[1140570267] 'agreement among raft nodes before linearized reading' (duration: 210.67851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:39.25769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.160368ms","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-29T15:51:39.257733Z","caller":"traceutil/trace.go:171","msg":"trace[1412257587] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1129; }","duration":"209.2257ms","start":"2026-07-29T15:51:39.048494Z","end":"2026-07-29T15:51:39.25772Z","steps":["trace[1412257587] 'agreement among raft nodes before linearized reading' (duration: 209.118558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:39.258014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.333486ms","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-29T15:51:39.258142Z","caller":"traceutil/trace.go:171","msg":"trace[1456922850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1129; }","duration":"211.45349ms","start":"2026-07-29T15:51:39.046631Z","end":"2026-07-29T15:51:39.258084Z","steps":["trace[1456922850] 'agreement among raft nodes before linearized reading' (duration: 210.946407ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:51:39.470416Z","caller":"traceutil/trace.go:171","msg":"trace[8536484] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"124.013992ms","start":"2026-07-29T15:51:39.346372Z","end":"2026-07-29T15:51:39.470386Z","steps":["trace[8536484] 'process raft request' (duration: 123.889269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:53.846521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.281565ms","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-29T15:51:53.846575Z","caller":"traceutil/trace.go:171","msg":"trace[893713061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1227; }","duration":"443.368157ms","start":"2026-07-29T15:51:53.403196Z","end":"2026-07-29T15:51:53.846564Z","steps":["trace[893713061] 'range keys from in-memory index tree' (duration: 443.187782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:53.846599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:53.403181Z","time spent":"443.411558ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-29T15:51:53.846745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.240433ms","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-29T15:51:53.846772Z","caller":"traceutil/trace.go:171","msg":"trace[2078136197] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1227; }","duration":"438.279594ms","start":"2026-07-29T15:51:53.408486Z","end":"2026-07-29T15:51:53.846765Z","steps":["trace[2078136197] 'range keys from in-memory index tree' (duration: 438.182402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:53.84679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:53.408478Z","time spent":"438.307215ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-29T15:51:53.847243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.606607ms","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-29T15:51:53.84734Z","caller":"traceutil/trace.go:171","msg":"trace[1687328984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1227; }","duration":"316.736161ms","start":"2026-07-29T15:51:53.530588Z","end":"2026-07-29T15:51:53.847324Z","steps":["trace[1687328984] 'range keys from in-memory index tree' (duration: 316.420582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:53.847372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:53.530574Z","time spent":"316.790422ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-29T15:51:55.845405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882612559360385,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-29T15:51:56.346114Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882612559360385,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-29T15:51:56.846794Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882612559360385,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-29T15:51:57.217759Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.142620288s","expected-duration":"1s"} {"level":"info","ts":"2026-07-29T15:51:57.21806Z","caller":"traceutil/trace.go:171","msg":"trace[1561649498] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"2.143021039s","start":"2026-07-29T15:51:55.075014Z","end":"2026-07-29T15:51:57.218035Z","steps":["trace[1561649498] 'process raft request' (duration: 2.142888304s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:57.218191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:55.074992Z","time spent":"2.143127142s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:51:57.218646Z","caller":"traceutil/trace.go:171","msg":"trace[1823729589] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1280; }","duration":"1.873879201s","start":"2026-07-29T15:51:55.344752Z","end":"2026-07-29T15:51:57.218631Z","steps":["trace[1823729589] 'read index received' (duration: 1.873875021s)","trace[1823729589] 'applied index is now lower than readState.Index' (duration: 3.35µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:51:57.218795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.874050725s","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-29T15:51:57.218826Z","caller":"traceutil/trace.go:171","msg":"trace[392460557] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1231; }","duration":"1.874110757s","start":"2026-07-29T15:51:55.344706Z","end":"2026-07-29T15:51:57.218816Z","steps":["trace[392460557] 'agreement among raft nodes before linearized reading' (duration: 1.873994914s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:57.218856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:55.344691Z","time spent":"1.874159008s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-29T15:51:57.222141Z","caller":"traceutil/trace.go:171","msg":"trace[21712145] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"341.64216ms","start":"2026-07-29T15:51:56.880484Z","end":"2026-07-29T15:51:57.222126Z","steps":["trace[21712145] 'process raft request' (duration: 341.554868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:57.22226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:56.880463Z","time spent":"341.726533ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:51:57.22235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.852371859s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T15:51:57.222385Z","caller":"traceutil/trace.go:171","msg":"trace[594976758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1232; }","duration":"1.852436271s","start":"2026-07-29T15:51:55.36994Z","end":"2026-07-29T15:51:57.222377Z","steps":["trace[594976758] 'agreement among raft nodes before linearized reading' (duration: 1.852327548s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:57.222404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:55.369924Z","time spent":"1.852475752s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-29T15:51:57.222615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364306615s","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-29T15:51:57.222641Z","caller":"traceutil/trace.go:171","msg":"trace[452790883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1232; }","duration":"1.364343356s","start":"2026-07-29T15:51:55.858291Z","end":"2026-07-29T15:51:57.222635Z","steps":["trace[452790883] 'agreement among raft nodes before linearized reading' (duration: 1.364288685s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:57.222658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:55.85828Z","time spent":"1.364373377s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-29T15:51:57.222663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367874858s","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-29T15:51:57.222692Z","caller":"traceutil/trace.go:171","msg":"trace[1559715841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1232; }","duration":"1.36793013s","start":"2026-07-29T15:51:55.854754Z","end":"2026-07-29T15:51:57.222684Z","steps":["trace[1559715841] 'agreement among raft nodes before linearized reading' (duration: 1.367838947s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:57.222713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:55.854739Z","time spent":"1.36796991s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-29T15:51:57.222752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382289904s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:51:57.222771Z","caller":"traceutil/trace.go:171","msg":"trace[1765401749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1232; }","duration":"1.382308075s","start":"2026-07-29T15:51:55.840457Z","end":"2026-07-29T15:51:57.222765Z","steps":["trace[1765401749] 'agreement among raft nodes before linearized reading' (duration: 1.382280424s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:57.222787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:55.84042Z","time spent":"1.382364136s","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-29T15:51:57.222838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364852539s","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-29T15:51:57.222861Z","caller":"traceutil/trace.go:171","msg":"trace[2071185963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1232; }","duration":"1.36490515s","start":"2026-07-29T15:51:55.857949Z","end":"2026-07-29T15:51:57.222855Z","steps":["trace[2071185963] 'agreement among raft nodes before linearized reading' (duration: 1.364848389s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:57.222879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:51:55.857933Z","time spent":"1.364942042s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-29T15:52:09.494702Z","caller":"traceutil/trace.go:171","msg":"trace[1680503545] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"226.861344ms","start":"2026-07-29T15:52:09.267817Z","end":"2026-07-29T15:52:09.494679Z","steps":["trace[1680503545] 'read index received' (duration: 226.673969ms)","trace[1680503545] 'applied index is now lower than readState.Index' (duration: 186.595µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:52:09.494827Z","caller":"traceutil/trace.go:171","msg":"trace[369792203] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"233.606019ms","start":"2026-07-29T15:52:09.261181Z","end":"2026-07-29T15:52:09.494787Z","steps":["trace[369792203] 'process raft request' (duration: 233.363443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:09.494997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.769736ms","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-29T15:52:09.495058Z","caller":"traceutil/trace.go:171","msg":"trace[1452940315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1272; }","duration":"194.874499ms","start":"2026-07-29T15:52:09.300166Z","end":"2026-07-29T15:52:09.49504Z","steps":["trace[1452940315] 'agreement among raft nodes before linearized reading' (duration: 194.706935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:09.49507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.710972ms","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-29T15:52:09.495128Z","caller":"traceutil/trace.go:171","msg":"trace[298745016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1272; }","duration":"192.817095ms","start":"2026-07-29T15:52:09.302296Z","end":"2026-07-29T15:52:09.495113Z","steps":["trace[298745016] 'agreement among raft nodes before linearized reading' (duration: 192.62792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:09.49514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.096316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-29T15:52:09.495142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.274432ms","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-29T15:52:09.495218Z","caller":"traceutil/trace.go:171","msg":"trace[139686167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1272; }","duration":"192.204449ms","start":"2026-07-29T15:52:09.302994Z","end":"2026-07-29T15:52:09.495198Z","steps":["trace[139686167] 'agreement among raft nodes before linearized reading' (duration: 191.962403ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:09.495218Z","caller":"traceutil/trace.go:171","msg":"trace[1389340210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1272; }","duration":"194.390875ms","start":"2026-07-29T15:52:09.300813Z","end":"2026-07-29T15:52:09.495204Z","steps":["trace[1389340210] 'agreement among raft nodes before linearized reading' (duration: 194.20387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:09.49538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.566821ms","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-29T15:52:09.49542Z","caller":"traceutil/trace.go:171","msg":"trace[1027222498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1272; }","duration":"227.627673ms","start":"2026-07-29T15:52:09.267778Z","end":"2026-07-29T15:52:09.495406Z","steps":["trace[1027222498] 'agreement among raft nodes before linearized reading' (duration: 227.028598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:17.608282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.310136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T15:52:17.608444Z","caller":"traceutil/trace.go:171","msg":"trace[715427138] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"186.955153ms","start":"2026-07-29T15:52:17.421448Z","end":"2026-07-29T15:52:17.608403Z","steps":["trace[715427138] 'process raft request' (duration: 70.41153ms)","trace[715427138] 'compare' (duration: 116.150082ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:52:17.62217Z","caller":"traceutil/trace.go:171","msg":"trace[778224274] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"103.01996ms","start":"2026-07-29T15:52:17.519116Z","end":"2026-07-29T15:52:17.622136Z","steps":["trace[778224274] 'process raft request' (duration: 102.806735ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:17.759918Z","caller":"traceutil/trace.go:171","msg":"trace[1289567028] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"133.169627ms","start":"2026-07-29T15:52:17.626715Z","end":"2026-07-29T15:52:17.759885Z","steps":["trace[1289567028] 'process raft request' (duration: 116.338208ms)","trace[1289567028] 'compare' (duration: 16.714446ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:52:17.7816Z","caller":"traceutil/trace.go:171","msg":"trace[1601024462] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"154.760811ms","start":"2026-07-29T15:52:17.626813Z","end":"2026-07-29T15:52:17.781574Z","steps":["trace[1601024462] 'process raft request' (duration: 154.607347ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:17.781617Z","caller":"traceutil/trace.go:171","msg":"trace[244722593] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"154.779712ms","start":"2026-07-29T15:52:17.626784Z","end":"2026-07-29T15:52:17.781564Z","steps":["trace[244722593] 'process raft request' (duration: 154.613307ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:17.782078Z","caller":"traceutil/trace.go:171","msg":"trace[910922156] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"154.822732ms","start":"2026-07-29T15:52:17.62724Z","end":"2026-07-29T15:52:17.782063Z","steps":["trace[910922156] 'process raft request' (duration: 154.215437ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:17.782208Z","caller":"traceutil/trace.go:171","msg":"trace[686909285] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"155.314285ms","start":"2026-07-29T15:52:17.626742Z","end":"2026-07-29T15:52:17.782056Z","steps":["trace[686909285] 'process raft request' (duration: 154.524765ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:20.085629Z","caller":"traceutil/trace.go:171","msg":"trace[23203410] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"986.796717ms","start":"2026-07-29T15:52:19.098801Z","end":"2026-07-29T15:52:20.085598Z","steps":["trace[23203410] 'process raft request' (duration: 986.585062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:20.085832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:19.098777Z","time spent":"986.963702ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:52:20.102152Z","caller":"traceutil/trace.go:171","msg":"trace[2074008630] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1392; }","duration":"337.274776ms","start":"2026-07-29T15:52:19.764862Z","end":"2026-07-29T15:52:20.102137Z","steps":["trace[2074008630] 'read index received' (duration: 320.504228ms)","trace[2074008630] 'applied index is now lower than readState.Index' (duration: 16.769678ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:52:20.102257Z","caller":"traceutil/trace.go:171","msg":"trace[1851830183] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"474.47547ms","start":"2026-07-29T15:52:19.627749Z","end":"2026-07-29T15:52:20.102224Z","steps":["trace[1851830183] 'process raft request' (duration: 474.296515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:20.102505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.883837ms","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-29T15:52:20.102548Z","caller":"traceutil/trace.go:171","msg":"trace[1357957074] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1340; }","duration":"313.97672ms","start":"2026-07-29T15:52:19.788561Z","end":"2026-07-29T15:52:20.102537Z","steps":["trace[1357957074] 'agreement among raft nodes before linearized reading' (duration: 313.809524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:20.102532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.777896ms","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-29T15:52:20.102575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:19.78854Z","time spent":"314.02881ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-29T15:52:20.102587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:19.627723Z","time spent":"474.801359ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:52:20.102609Z","caller":"traceutil/trace.go:171","msg":"trace[766899057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1340; }","duration":"315.910119ms","start":"2026-07-29T15:52:19.786682Z","end":"2026-07-29T15:52:20.102592Z","steps":["trace[766899057] 'agreement among raft nodes before linearized reading' (duration: 315.696032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:20.102651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:19.78665Z","time spent":"315.991741ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-29T15:52:20.102321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.452131ms","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":"warn","ts":"2026-07-29T15:52:20.102618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.930119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T15:52:20.102761Z","caller":"traceutil/trace.go:171","msg":"trace[1318704789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1340; }","duration":"337.930295ms","start":"2026-07-29T15:52:19.764812Z","end":"2026-07-29T15:52:20.102742Z","steps":["trace[1318704789] 'agreement among raft nodes before linearized reading' (duration: 337.415661ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:20.102779Z","caller":"traceutil/trace.go:171","msg":"trace[1924159088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1340; }","duration":"316.157875ms","start":"2026-07-29T15:52:19.786602Z","end":"2026-07-29T15:52:20.10276Z","steps":["trace[1924159088] 'agreement among raft nodes before linearized reading' (duration: 315.759424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:20.102813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:19.764792Z","time spent":"338.008846ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-29T15:52:20.102844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:19.78658Z","time spent":"316.251708ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-29T15:52:20.102446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.733604ms","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-29T15:52:20.102924Z","caller":"traceutil/trace.go:171","msg":"trace[2006266883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1340; }","duration":"316.219047ms","start":"2026-07-29T15:52:19.786693Z","end":"2026-07-29T15:52:20.102912Z","steps":["trace[2006266883] 'agreement among raft nodes before linearized reading' (duration: 315.702573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:20.10297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:19.786687Z","time spent":"316.269698ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-29T15:52:20.102456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.01652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:52:20.103044Z","caller":"traceutil/trace.go:171","msg":"trace[578106676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1340; }","duration":"265.647797ms","start":"2026-07-29T15:52:19.837386Z","end":"2026-07-29T15:52:20.103034Z","steps":["trace[578106676] 'agreement among raft nodes before linearized reading' (duration: 264.99969ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:20.215418Z","caller":"traceutil/trace.go:171","msg":"trace[1886390451] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"108.081123ms","start":"2026-07-29T15:52:20.107316Z","end":"2026-07-29T15:52:20.215397Z","steps":["trace[1886390451] 'process raft request' (duration: 61.726611ms)","trace[1886390451] 'compare' (duration: 46.256549ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:52:20.262383Z","caller":"traceutil/trace.go:171","msg":"trace[296214546] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"153.385566ms","start":"2026-07-29T15:52:20.108971Z","end":"2026-07-29T15:52:20.262357Z","steps":["trace[296214546] 'process raft request' (duration: 153.17024ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:20.262426Z","caller":"traceutil/trace.go:171","msg":"trace[61913574] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"151.594129ms","start":"2026-07-29T15:52:20.110808Z","end":"2026-07-29T15:52:20.262402Z","steps":["trace[61913574] 'process raft request' (duration: 151.531467ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:20.26269Z","caller":"traceutil/trace.go:171","msg":"trace[185785078] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"153.56617ms","start":"2026-07-29T15:52:20.109111Z","end":"2026-07-29T15:52:20.262678Z","steps":["trace[185785078] 'process raft request' (duration: 153.195301ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:20.262776Z","caller":"traceutil/trace.go:171","msg":"trace[619768605] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"153.785136ms","start":"2026-07-29T15:52:20.108981Z","end":"2026-07-29T15:52:20.262767Z","steps":["trace[619768605] 'process raft request' (duration: 153.286144ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:21.265252Z","caller":"traceutil/trace.go:171","msg":"trace[1524974140] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"390.6055ms","start":"2026-07-29T15:52:20.874629Z","end":"2026-07-29T15:52:21.265234Z","steps":["trace[1524974140] 'process raft request' (duration: 390.491597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:21.265383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:20.874615Z","time spent":"390.694612ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:52:21.906086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.009443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-29T15:52:21.906161Z","caller":"traceutil/trace.go:171","msg":"trace[333708763] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1346; }","duration":"246.133846ms","start":"2026-07-29T15:52:21.660007Z","end":"2026-07-29T15:52:21.906141Z","steps":["trace[333708763] 'range keys from in-memory index tree' (duration: 245.853849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:21.906252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.169117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:108142"} {"level":"info","ts":"2026-07-29T15:52:21.906341Z","caller":"traceutil/trace.go:171","msg":"trace[690776999] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1346; }","duration":"246.28501ms","start":"2026-07-29T15:52:21.660025Z","end":"2026-07-29T15:52:21.90631Z","steps":["trace[690776999] 'range keys from in-memory index tree' (duration: 245.92109ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:22.262908Z","caller":"traceutil/trace.go:171","msg":"trace[726113701] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"150.144741ms","start":"2026-07-29T15:52:22.112734Z","end":"2026-07-29T15:52:22.262879Z","steps":["trace[726113701] 'process raft request' (duration: 149.610617ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:22.394377Z","caller":"traceutil/trace.go:171","msg":"trace[1462892395] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1402; }","duration":"118.913876ms","start":"2026-07-29T15:52:22.275441Z","end":"2026-07-29T15:52:22.394354Z","steps":["trace[1462892395] 'read index received' (duration: 98.80632ms)","trace[1462892395] 'applied index is now lower than readState.Index' (duration: 20.106376ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:52:22.394596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.9867ms","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-29T15:52:22.394645Z","caller":"traceutil/trace.go:171","msg":"trace[227527474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1350; }","duration":"126.069172ms","start":"2026-07-29T15:52:22.268561Z","end":"2026-07-29T15:52:22.39463Z","steps":["trace[227527474] 'agreement among raft nodes before linearized reading' (duration: 125.886407ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:22.394702Z","caller":"traceutil/trace.go:171","msg":"trace[129035954] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"125.499888ms","start":"2026-07-29T15:52:22.269155Z","end":"2026-07-29T15:52:22.394655Z","steps":["trace[129035954] 'process raft request' (duration: 105.119045ms)","trace[129035954] 'compare' (duration: 19.849119ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:52:22.394945Z","caller":"traceutil/trace.go:171","msg":"trace[2079420989] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"123.534036ms","start":"2026-07-29T15:52:22.271375Z","end":"2026-07-29T15:52:22.394909Z","steps":["trace[2079420989] 'process raft request' (duration: 122.88772ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:22.491987Z","caller":"traceutil/trace.go:171","msg":"trace[479375023] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"213.129235ms","start":"2026-07-29T15:52:22.278835Z","end":"2026-07-29T15:52:22.491965Z","steps":["trace[479375023] 'process raft request' (duration: 213.024093ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:24.574538Z","caller":"traceutil/trace.go:171","msg":"trace[1103352643] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"171.700904ms","start":"2026-07-29T15:52:24.402807Z","end":"2026-07-29T15:52:24.574508Z","steps":["trace[1103352643] 'process raft request' (duration: 171.519089ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:24.576539Z","caller":"traceutil/trace.go:171","msg":"trace[96225196] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"172.908545ms","start":"2026-07-29T15:52:24.403608Z","end":"2026-07-29T15:52:24.576517Z","steps":["trace[96225196] 'process raft request' (duration: 172.761821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:26.366542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.255377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:52:26.366628Z","caller":"traceutil/trace.go:171","msg":"trace[549377708] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1367; }","duration":"252.39357ms","start":"2026-07-29T15:52:26.11421Z","end":"2026-07-29T15:52:26.366603Z","steps":["trace[549377708] 'count revisions from in-memory index tree' (duration: 252.162264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:26.366773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.09414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T15:52:26.366846Z","caller":"traceutil/trace.go:171","msg":"trace[147184135] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1367; }","duration":"865.219563ms","start":"2026-07-29T15:52:25.501612Z","end":"2026-07-29T15:52:26.366831Z","steps":["trace[147184135] 'count revisions from in-memory index tree' (duration: 864.987826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:26.366876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:25.50159Z","time spent":"865.277474ms","remote":"127.0.0.1:50458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":28,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-29T15:52:26.366986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.80813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T15:52:26.367018Z","caller":"traceutil/trace.go:171","msg":"trace[802650165] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1367; }","duration":"412.841741ms","start":"2026-07-29T15:52:25.954166Z","end":"2026-07-29T15:52:26.367008Z","steps":["trace[802650165] 'count revisions from in-memory index tree' (duration: 412.710588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:26.367046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:25.954109Z","time spent":"412.927043ms","remote":"127.0.0.1:50496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-29T15:52:27.080091Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882612559360681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-29T15:52:27.58063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882612559360681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-29T15:52:28.080717Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.631215634s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-29T15:52:28.080944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882612559360681,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-29T15:52:28.083862Z","caller":"traceutil/trace.go:171","msg":"trace[57225001] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"1.710622879s","start":"2026-07-29T15:52:26.373204Z","end":"2026-07-29T15:52:28.083827Z","steps":["trace[57225001] 'process raft request' (duration: 1.707933399s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:28.084617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:26.373187Z","time spent":"1.710755442s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:52:28.100718Z","caller":"traceutil/trace.go:171","msg":"trace[95330599] linearizableReadLoop","detail":"{readStateIndex:1426; appliedIndex:1424; }","duration":"1.520997126s","start":"2026-07-29T15:52:26.579705Z","end":"2026-07-29T15:52:28.100702Z","steps":["trace[95330599] 'read index received' (duration: 1.502126373s)","trace[95330599] 'applied index is now lower than readState.Index' (duration: 18.870113ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:52:28.100975Z","caller":"traceutil/trace.go:171","msg":"trace[1355263625] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"249.500755ms","start":"2026-07-29T15:52:27.851465Z","end":"2026-07-29T15:52:28.100966Z","steps":["trace[1355263625] 'process raft request' (duration: 249.153356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:28.10123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.521503059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T15:52:28.101315Z","caller":"traceutil/trace.go:171","msg":"trace[1502035709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1371; }","duration":"1.521633143s","start":"2026-07-29T15:52:26.579663Z","end":"2026-07-29T15:52:28.101296Z","steps":["trace[1502035709] 'agreement among raft nodes before linearized reading' (duration: 1.521374996s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:28.101361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:26.579644Z","time spent":"1.521707205s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-29T15:52:28.10175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.521637883s","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-29T15:52:28.102212Z","caller":"traceutil/trace.go:171","msg":"trace[1486798510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1371; }","duration":"1.522154937s","start":"2026-07-29T15:52:26.580038Z","end":"2026-07-29T15:52:28.102193Z","steps":["trace[1486798510] 'agreement among raft nodes before linearized reading' (duration: 1.521476449s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:28.101823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.26148789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T15:52:28.10234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:26.580009Z","time spent":"1.52231556s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-29T15:52:28.102394Z","caller":"traceutil/trace.go:171","msg":"trace[1950125497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1371; }","duration":"1.262063575s","start":"2026-07-29T15:52:26.840288Z","end":"2026-07-29T15:52:28.102352Z","steps":["trace[1950125497] 'agreement among raft nodes before linearized reading' (duration: 1.26149792s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:28.102558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:26.840261Z","time spent":"1.262277611s","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-29T15:52:28.101959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.502901794s","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-29T15:52:28.102993Z","caller":"traceutil/trace.go:171","msg":"trace[1671085450] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1371; }","duration":"1.503992082s","start":"2026-07-29T15:52:26.598988Z","end":"2026-07-29T15:52:28.10298Z","steps":["trace[1671085450] 'agreement among raft nodes before linearized reading' (duration: 1.502830602s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:28.103069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:26.598967Z","time spent":"1.504090684s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-29T15:52:28.102025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.50428897s","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-29T15:52:28.103184Z","caller":"traceutil/trace.go:171","msg":"trace[2096248522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1371; }","duration":"1.505466271s","start":"2026-07-29T15:52:26.597694Z","end":"2026-07-29T15:52:28.103161Z","steps":["trace[2096248522] 'agreement among raft nodes before linearized reading' (duration: 1.504201508s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:28.103247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:26.59768Z","time spent":"1.505550673s","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-29T15:52:28.101909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.466134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-29T15:52:28.103522Z","caller":"traceutil/trace.go:171","msg":"trace[1737533412] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:1371; }","duration":"971.112928ms","start":"2026-07-29T15:52:27.132396Z","end":"2026-07-29T15:52:28.103509Z","steps":["trace[1737533412] 'agreement among raft nodes before linearized reading' (duration: 969.449934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:28.103566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:27.132373Z","time spent":"971.1776ms","remote":"127.0.0.1:50168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"info","ts":"2026-07-29T15:55:04.962171Z","caller":"traceutil/trace.go:171","msg":"trace[1981941151] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"165.785096ms","start":"2026-07-29T15:55:04.796359Z","end":"2026-07-29T15:55:04.962144Z","steps":["trace[1981941151] 'process raft request' (duration: 165.601651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:55:04.962333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.656619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:55:04.962391Z","caller":"traceutil/trace.go:171","msg":"trace[1448557512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2108; }","duration":"120.816213ms","start":"2026-07-29T15:55:04.841561Z","end":"2026-07-29T15:55:04.962378Z","steps":["trace[1448557512] 'agreement among raft nodes before linearized reading' (duration: 120.664279ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:55:04.962122Z","caller":"traceutil/trace.go:171","msg":"trace[1390845506] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2194; }","duration":"120.485284ms","start":"2026-07-29T15:55:04.84161Z","end":"2026-07-29T15:55:04.962096Z","steps":["trace[1390845506] 'read index received' (duration: 120.33627ms)","trace[1390845506] 'applied index is now lower than readState.Index' (duration: 147.974µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:55:05.098597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.029187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-29T15:55:05.09862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.630729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-29T15:55:05.098623Z","caller":"traceutil/trace.go:171","msg":"trace[217701282] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"201.856618ms","start":"2026-07-29T15:55:04.896732Z","end":"2026-07-29T15:55:05.098588Z","steps":["trace[217701282] 'process raft request' (duration: 177.747269ms)","trace[217701282] 'compare' (duration: 23.851153ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:55:05.09869Z","caller":"traceutil/trace.go:171","msg":"trace[947647329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2109; }","duration":"207.739323ms","start":"2026-07-29T15:55:04.890939Z","end":"2026-07-29T15:55:05.098678Z","steps":["trace[947647329] 'agreement among raft nodes before linearized reading' (duration: 207.551088ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:55:05.098652Z","caller":"traceutil/trace.go:171","msg":"trace[1094364292] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2109; }","duration":"206.11676ms","start":"2026-07-29T15:55:04.892522Z","end":"2026-07-29T15:55:05.098639Z","steps":["trace[1094364292] 'agreement among raft nodes before linearized reading' (duration: 205.942445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:55:05.098788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.090239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-29T15:55:05.09881Z","caller":"traceutil/trace.go:171","msg":"trace[1913695168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2109; }","duration":"216.139791ms","start":"2026-07-29T15:55:04.882664Z","end":"2026-07-29T15:55:05.098804Z","steps":["trace[1913695168] 'agreement among raft nodes before linearized reading' (duration: 216.078089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:55:05.098781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.911777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-29T15:55:05.098866Z","caller":"traceutil/trace.go:171","msg":"trace[2131861547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2109; }","duration":"213.04831ms","start":"2026-07-29T15:55:04.885797Z","end":"2026-07-29T15:55:05.098845Z","steps":["trace[2131861547] 'agreement among raft nodes before linearized reading' (duration: 212.798274ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:55:05.098901Z","caller":"traceutil/trace.go:171","msg":"trace[729310753] linearizableReadLoop","detail":"{readStateIndex:2196; appliedIndex:2195; }","duration":"134.799879ms","start":"2026-07-29T15:55:04.963644Z","end":"2026-07-29T15:55:05.098444Z","steps":["trace[729310753] 'read index received' (duration: 110.814402ms)","trace[729310753] 'applied index is now lower than readState.Index' (duration: 23.984177ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:55:05.099155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.741527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:55:05.099199Z","caller":"traceutil/trace.go:171","msg":"trace[609864043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2109; }","duration":"134.812578ms","start":"2026-07-29T15:55:04.964376Z","end":"2026-07-29T15:55:05.099188Z","steps":["trace[609864043] 'agreement among raft nodes before linearized reading' (duration: 134.747806ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:55:07.330203Z","caller":"traceutil/trace.go:171","msg":"trace[1892455739] linearizableReadLoop","detail":"{readStateIndex:2207; appliedIndex:2205; }","duration":"159.02422ms","start":"2026-07-29T15:55:07.171156Z","end":"2026-07-29T15:55:07.33018Z","steps":["trace[1892455739] 'read index received' (duration: 33.777773ms)","trace[1892455739] 'applied index is now lower than readState.Index' (duration: 125.245437ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:55:07.330305Z","caller":"traceutil/trace.go:171","msg":"trace[217964437] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"173.628941ms","start":"2026-07-29T15:55:07.156666Z","end":"2026-07-29T15:55:07.330295Z","steps":["trace[217964437] 'process raft request' (duration: 173.355504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:55:07.330401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.233185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-29T15:55:07.330505Z","caller":"traceutil/trace.go:171","msg":"trace[1204674872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2119; }","duration":"159.344018ms","start":"2026-07-29T15:55:07.171149Z","end":"2026-07-29T15:55:07.330493Z","steps":["trace[1204674872] 'agreement among raft nodes before linearized reading' (duration: 159.174274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:55:07.331383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.020846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-29T15:55:07.331433Z","caller":"traceutil/trace.go:171","msg":"trace[96473310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2120; }","duration":"160.109748ms","start":"2026-07-29T15:55:07.17131Z","end":"2026-07-29T15:55:07.33142Z","steps":["trace[96473310] 'agreement among raft nodes before linearized reading' (duration: 159.955124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:55:07.331442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.832444ms","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:531"} {"level":"info","ts":"2026-07-29T15:55:07.331496Z","caller":"traceutil/trace.go:171","msg":"trace[1314214125] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2120; }","duration":"148.916266ms","start":"2026-07-29T15:55:07.182567Z","end":"2026-07-29T15:55:07.331483Z","steps":["trace[1314214125] 'agreement among raft nodes before linearized reading' (duration: 148.725051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:55:07.331669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.244021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-29T15:55:07.331706Z","caller":"traceutil/trace.go:171","msg":"trace[106760569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2120; }","duration":"160.332674ms","start":"2026-07-29T15:55:07.171364Z","end":"2026-07-29T15:55:07.331697Z","steps":["trace[106760569] 'agreement among raft nodes before linearized reading' (duration: 160.236432ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:55:35.228468Z","caller":"traceutil/trace.go:171","msg":"trace[2024119437] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"117.992939ms","start":"2026-07-29T15:55:35.110444Z","end":"2026-07-29T15:55:35.228437Z","steps":["trace[2024119437] 'process raft request' (duration: 117.829985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:55:35.633237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.849285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T15:55:35.633354Z","caller":"traceutil/trace.go:171","msg":"trace[266092665] linearizableReadLoop","detail":"{readStateIndex:2342; appliedIndex:2341; }","duration":"129.559472ms","start":"2026-07-29T15:55:35.503784Z","end":"2026-07-29T15:55:35.633343Z","steps":["trace[266092665] 'read index received' (duration: 24.559111ms)","trace[266092665] 'applied index is now lower than readState.Index' (duration: 104.999421ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:55:35.633418Z","caller":"traceutil/trace.go:171","msg":"trace[2081965802] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"130.416284ms","start":"2026-07-29T15:55:35.502967Z","end":"2026-07-29T15:55:35.633383Z","steps":["trace[2081965802] 'process raft request' (duration: 25.364492ms)","trace[2081965802] 'compare' (duration: 104.729382ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:55:35.633632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.851569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-29T15:55:35.633664Z","caller":"traceutil/trace.go:171","msg":"trace[915705414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2250; }","duration":"129.905811ms","start":"2026-07-29T15:55:35.503746Z","end":"2026-07-29T15:55:35.633652Z","steps":["trace[915705414] 'agreement among raft nodes before linearized reading' (duration: 129.659324ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:55:35.633815Z","caller":"traceutil/trace.go:171","msg":"trace[1955529147] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"127.349544ms","start":"2026-07-29T15:55:35.506438Z","end":"2026-07-29T15:55:35.633787Z","steps":["trace[1955529147] 'process raft request' (duration: 127.17125ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:55:35.633891Z","caller":"traceutil/trace.go:171","msg":"trace[1881146511] transaction","detail":"{read_only:false; response_revision:2250; number_of_response:1; }","duration":"129.582842ms","start":"2026-07-29T15:55:35.504295Z","end":"2026-07-29T15:55:35.633878Z","steps":["trace[1881146511] 'process raft request' (duration: 129.015967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:55:35.633911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.957011ms","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-29T15:55:35.633992Z","caller":"traceutil/trace.go:171","msg":"trace[1480266027] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2251; }","duration":"130.073905ms","start":"2026-07-29T15:55:35.503896Z","end":"2026-07-29T15:55:35.63397Z","steps":["trace[1480266027] 'agreement among raft nodes before linearized reading' (duration: 129.838789ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:55:35.9532Z","caller":"traceutil/trace.go:171","msg":"trace[1625528685] linearizableReadLoop","detail":"{readStateIndex:2346; appliedIndex:2345; }","duration":"111.549571ms","start":"2026-07-29T15:55:35.841624Z","end":"2026-07-29T15:55:35.953173Z","steps":["trace[1625528685] 'read index received' (duration: 49.667187ms)","trace[1625528685] 'applied index is now lower than readState.Index' (duration: 61.881074ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:55:35.953496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.862069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:55:35.953721Z","caller":"traceutil/trace.go:171","msg":"trace[749329286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2253; }","duration":"112.140896ms","start":"2026-07-29T15:55:35.841561Z","end":"2026-07-29T15:55:35.953702Z","steps":["trace[749329286] 'agreement among raft nodes before linearized reading' (duration: 111.810478ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:55:35.953291Z","caller":"traceutil/trace.go:171","msg":"trace[670879269] transaction","detail":"{read_only:false; response_revision:2253; number_of_response:1; }","duration":"314.52925ms","start":"2026-07-29T15:55:35.638729Z","end":"2026-07-29T15:55:35.953258Z","steps":["trace[670879269] 'process raft request' (duration: 252.642315ms)","trace[670879269] 'compare' (duration: 61.68945ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:55:35.954223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:55:35.638706Z","time spent":"315.430432ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:55:37.751833Z","caller":"traceutil/trace.go:171","msg":"trace[655431806] transaction","detail":"{read_only:false; response_revision:2260; number_of_response:1; }","duration":"109.087107ms","start":"2026-07-29T15:55:37.642719Z","end":"2026-07-29T15:55:37.751806Z","steps":["trace[655431806] 'process raft request' (duration: 82.543664ms)","trace[655431806] 'compare' (duration: 26.371869ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:56:42.477456Z","caller":"traceutil/trace.go:171","msg":"trace[810535941] transaction","detail":"{read_only:false; response_revision:2578; number_of_response:1; }","duration":"354.862726ms","start":"2026-07-29T15:56:42.122562Z","end":"2026-07-29T15:56:42.477425Z","steps":["trace[810535941] 'process raft request' (duration: 352.563305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:56:42.477675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:56:42.12254Z","time spent":"355.048291ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:56:42.477896Z","caller":"traceutil/trace.go:171","msg":"trace[214456269] linearizableReadLoop","detail":"{readStateIndex:2685; appliedIndex:2684; }","duration":"219.55296ms","start":"2026-07-29T15:56:42.258293Z","end":"2026-07-29T15:56:42.477846Z","steps":["trace[214456269] 'read index received' (duration: 217.395723ms)","trace[214456269] 'applied index is now lower than readState.Index' (duration: 2.150176ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:56:42.480703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.399254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T15:56:42.480762Z","caller":"traceutil/trace.go:171","msg":"trace[549238109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2578; }","duration":"222.468035ms","start":"2026-07-29T15:56:42.258281Z","end":"2026-07-29T15:56:42.480749Z","steps":["trace[549238109] 'agreement among raft nodes before linearized reading' (duration: 219.702713ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:56:48.324345Z","caller":"traceutil/trace.go:171","msg":"trace[210360700] transaction","detail":"{read_only:false; response_revision:2609; number_of_response:1; }","duration":"321.581846ms","start":"2026-07-29T15:56:48.002752Z","end":"2026-07-29T15:56:48.324334Z","steps":["trace[210360700] 'process raft request' (duration: 321.418002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:56:48.324448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:56:48.00273Z","time spent":"321.662698ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:56:48.324522Z","caller":"traceutil/trace.go:171","msg":"trace[172248788] linearizableReadLoop","detail":"{readStateIndex:2718; appliedIndex:2717; }","duration":"260.739106ms","start":"2026-07-29T15:56:48.063566Z","end":"2026-07-29T15:56:48.324305Z","steps":["trace[172248788] 'read index received' (duration: 260.572401ms)","trace[172248788] 'applied index is now lower than readState.Index' (duration: 165.905µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:56:48.32467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.091534ms","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-29T15:56:48.32481Z","caller":"traceutil/trace.go:171","msg":"trace[1024016755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2609; }","duration":"261.264489ms","start":"2026-07-29T15:56:48.063523Z","end":"2026-07-29T15:56:48.324787Z","steps":["trace[1024016755] 'agreement among raft nodes before linearized reading' (duration: 260.959771ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:56:48.576277Z","caller":"traceutil/trace.go:171","msg":"trace[1343692937] linearizableReadLoop","detail":"{readStateIndex:2719; appliedIndex:2718; }","duration":"167.280762ms","start":"2026-07-29T15:56:48.408972Z","end":"2026-07-29T15:56:48.576253Z","steps":["trace[1343692937] 'read index received' (duration: 165.539746ms)","trace[1343692937] 'applied index is now lower than readState.Index' (duration: 1.739906ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:56:48.576534Z","caller":"traceutil/trace.go:171","msg":"trace[1256829857] transaction","detail":"{read_only:false; response_revision:2610; number_of_response:1; }","duration":"248.003782ms","start":"2026-07-29T15:56:48.3285Z","end":"2026-07-29T15:56:48.576504Z","steps":["trace[1256829857] 'process raft request' (duration: 245.944268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:56:48.576612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.627842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:56:48.576665Z","caller":"traceutil/trace.go:171","msg":"trace[2025452393] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2610; }","duration":"167.733895ms","start":"2026-07-29T15:56:48.408915Z","end":"2026-07-29T15:56:48.576649Z","steps":["trace[2025452393] 'agreement among raft nodes before linearized reading' (duration: 167.624992ms)"],"step_count":1}