{"level":"warn","ts":"2026-08-10T15:54:44.127109Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-10T15:54:44.127203Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-08-10T15:54:44.127281Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-10T15:54:44.127292Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-08-10T15:54:44.127319Z","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-08-10T15:54:44.12818Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-08-10T15:54:44.128292Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-08-10T15:54:44.155315Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.776818ms"} {"level":"info","ts":"2026-08-10T15:54:44.191512Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-08-10T15:54:44.191642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-08-10T15:54:44.19168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-08-10T15:54:44.19172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-10T15:54:44.191744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-08-10T15:54:44.191806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-08-10T15:54:44.209889Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-10T15:54:44.212484Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-10T15:54:44.215576Z","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-08-10T15:54:44.218398Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-10T15:54:44.218678Z","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-08-10T15:54:44.218757Z","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-08-10T15:54:44.218768Z","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-08-10T15:54:44.218895Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-10T15:54:44.21965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-08-10T15:54:44.220105Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-08-10T15:54:44.221801Z","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-08-10T15:54:44.222118Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-10T15:54:44.222152Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-10T15:54:44.223001Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-08-10T15:54:44.223029Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-08-10T15:54:44.892276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-08-10T15:54:44.892357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-10T15:54:44.892423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-08-10T15:54:44.892453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-08-10T15:54:44.892469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-08-10T15:54:44.892491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-08-10T15:54:44.892509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-08-10T15:54:44.893441Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T15:54:44.894155Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T15:54:44.89427Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T15:54:44.894145Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-08-10T15:54:44.894785Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-10T15:54:44.894892Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-10T15:54:44.895243Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T15:54:44.895612Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T15:54:44.895681Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T15:54:44.898071Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-08-10T15:54:44.899104Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-10T15:55:08.141824Z","caller":"traceutil/trace.go:171","msg":"trace[148117418] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:398; }","duration":"130.804751ms","start":"2026-08-10T15:55:08.010994Z","end":"2026-08-10T15:55:08.141799Z","steps":["trace[148117418] 'read index received' (duration: 130.695339ms)","trace[148117418] 'applied index is now lower than readState.Index' (duration: 108.302µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:55:08.142015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.955565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:55:08.142066Z","caller":"traceutil/trace.go:171","msg":"trace[447120908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:384; }","duration":"131.108619ms","start":"2026-08-10T15:55:08.010945Z","end":"2026-08-10T15:55:08.142054Z","steps":["trace[447120908] 'agreement among raft nodes before linearized reading' (duration: 130.979135ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:08.142284Z","caller":"traceutil/trace.go:171","msg":"trace[1551423312] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"170.938733ms","start":"2026-08-10T15:55:07.971306Z","end":"2026-08-10T15:55:08.142244Z","steps":["trace[1551423312] 'process raft request' (duration: 170.332359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:09.139251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.630386ms","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-08-10T15:55:09.139362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.797416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-08-10T15:55:09.139403Z","caller":"traceutil/trace.go:171","msg":"trace[1403437612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:384; }","duration":"257.817072ms","start":"2026-08-10T15:55:08.881527Z","end":"2026-08-10T15:55:09.139344Z","steps":["trace[1403437612] 'range keys from in-memory index tree' (duration: 257.434282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:09.139495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.606653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-10T15:55:09.139285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.052425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-10T15:55:09.139538Z","caller":"traceutil/trace.go:171","msg":"trace[899743394] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:384; }","duration":"348.898528ms","start":"2026-08-10T15:55:08.790523Z","end":"2026-08-10T15:55:09.139421Z","steps":["trace[899743394] 'range keys from in-memory index tree' (duration: 348.714384ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:09.13958Z","caller":"traceutil/trace.go:171","msg":"trace[869805060] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:384; }","duration":"817.721046ms","start":"2026-08-10T15:55:08.321844Z","end":"2026-08-10T15:55:09.139565Z","steps":["trace[869805060] 'range keys from in-memory index tree' (duration: 817.290205ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:09.139605Z","caller":"traceutil/trace.go:171","msg":"trace[1340308387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:384; }","duration":"898.412195ms","start":"2026-08-10T15:55:08.241163Z","end":"2026-08-10T15:55:09.139575Z","steps":["trace[1340308387] 'range keys from in-memory index tree' (duration: 897.956034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:09.139625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:08.790503Z","time spent":"349.109683ms","remote":"127.0.0.1:60830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4398,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-10T15:55:09.139622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:08.321829Z","time spent":"817.783327ms","remote":"127.0.0.1:60720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"warn","ts":"2026-08-10T15:55:09.139654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.512223ms","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-08-10T15:55:09.139691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:08.241142Z","time spent":"898.528397ms","remote":"127.0.0.1:60930","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-08-10T15:55:09.139754Z","caller":"traceutil/trace.go:171","msg":"trace[153889884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:384; }","duration":"725.708507ms","start":"2026-08-10T15:55:08.414019Z","end":"2026-08-10T15:55:09.139728Z","steps":["trace[153889884] 'range keys from in-memory index tree' (duration: 725.041142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:09.13984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:08.414002Z","time spent":"725.81213ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-10T15:55:09.139734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.233505ms","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-08-10T15:55:09.140402Z","caller":"traceutil/trace.go:171","msg":"trace[166461176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:384; }","duration":"263.440649ms","start":"2026-08-10T15:55:08.876459Z","end":"2026-08-10T15:55:09.1399Z","steps":["trace[166461176] 'range keys from in-memory index tree' (duration: 262.868087ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:09.81995Z","caller":"traceutil/trace.go:171","msg":"trace[725914998] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"673.603679ms","start":"2026-08-10T15:55:09.14631Z","end":"2026-08-10T15:55:09.819914Z","steps":["trace[725914998] 'process raft request' (duration: 672.617637ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:09.820206Z","caller":"traceutil/trace.go:171","msg":"trace[17782427] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"673.071018ms","start":"2026-08-10T15:55:09.147121Z","end":"2026-08-10T15:55:09.820192Z","steps":["trace[17782427] 'process raft request' (duration: 672.709799ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:09.820412Z","caller":"traceutil/trace.go:171","msg":"trace[1266395985] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"672.108905ms","start":"2026-08-10T15:55:09.148291Z","end":"2026-08-10T15:55:09.8204Z","steps":["trace[1266395985] 'process raft request' (duration: 671.802159ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:09.820484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:09.14629Z","time spent":"673.730412ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:55:09.820518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:09.148277Z","time spent":"672.200847ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:55:09.820631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:09.14708Z","time spent":"673.1721ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:55:09.820703Z","caller":"traceutil/trace.go:171","msg":"trace[1416191615] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"672.096895ms","start":"2026-08-10T15:55:09.148578Z","end":"2026-08-10T15:55:09.820675Z","steps":["trace[1416191615] 'process raft request' (duration: 671.786219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:09.82089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:09.148557Z","time spent":"672.226258ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:55:09.821114Z","caller":"traceutil/trace.go:171","msg":"trace[1296431138] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"585.841955ms","start":"2026-08-10T15:55:09.235249Z","end":"2026-08-10T15:55:09.821091Z","steps":["trace[1296431138] 'process raft request' (duration: 585.690411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:09.82188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:09.235224Z","time spent":"586.52575ms","remote":"127.0.0.1:60720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:55:11.081426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.557184ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:55:11.081575Z","caller":"traceutil/trace.go:171","msg":"trace[1667687042] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:419; }","duration":"192.725567ms","start":"2026-08-10T15:55:10.888825Z","end":"2026-08-10T15:55:11.081551Z","steps":["trace[1667687042] 'range keys from in-memory index tree' (duration: 192.534013ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:17.19334Z","caller":"traceutil/trace.go:171","msg":"trace[638741515] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"535.908103ms","start":"2026-08-10T15:55:16.657407Z","end":"2026-08-10T15:55:17.193315Z","steps":["trace[638741515] 'process raft request' (duration: 535.78548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:17.19349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:16.657379Z","time spent":"536.037476ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:55:22.002682Z","caller":"traceutil/trace.go:171","msg":"trace[1574310298] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"104.317886ms","start":"2026-08-10T15:55:21.898229Z","end":"2026-08-10T15:55:22.002547Z","steps":["trace[1574310298] 'process raft request' (duration: 104.092841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:25.511186Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196308344,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T15:55:26.012226Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196308344,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T15:55:26.390742Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.50285236s","expected-duration":"1s"} {"level":"info","ts":"2026-08-10T15:55:26.409849Z","caller":"traceutil/trace.go:171","msg":"trace[1695315169] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.522080142s","start":"2026-08-10T15:55:24.887744Z","end":"2026-08-10T15:55:26.409824Z","steps":["trace[1695315169] 'process raft request' (duration: 1.503365881s)","trace[1695315169] 'compare' (duration: 18.390794ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:55:26.409985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:24.887724Z","time spent":"1.522192324s","remote":"127.0.0.1:60738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:55:26.41492Z","caller":"traceutil/trace.go:171","msg":"trace[1735127087] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.525790764s","start":"2026-08-10T15:55:24.889106Z","end":"2026-08-10T15:55:26.414897Z","steps":["trace[1735127087] 'process raft request' (duration: 1.525490778s)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:26.414963Z","caller":"traceutil/trace.go:171","msg":"trace[2073035465] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:558; }","duration":"1.404395522s","start":"2026-08-10T15:55:25.01053Z","end":"2026-08-10T15:55:26.414926Z","steps":["trace[2073035465] 'read index received' (duration: 1.380587381s)","trace[2073035465] 'applied index is now lower than readState.Index' (duration: 23.807091ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:55:26.415094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:24.889098Z","time spent":"1.525921366s","remote":"127.0.0.1:60738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-10T15:55:26.41518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.404651347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:55:26.415255Z","caller":"traceutil/trace.go:171","msg":"trace[1199137147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"1.404722308s","start":"2026-08-10T15:55:25.010513Z","end":"2026-08-10T15:55:26.415236Z","steps":["trace[1199137147] 'agreement among raft nodes before linearized reading' (duration: 1.404613476s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:26.415293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:25.010452Z","time spent":"1.404833451s","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-10T15:55:26.41541Z","caller":"traceutil/trace.go:171","msg":"trace[1965303268] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.50770732s","start":"2026-08-10T15:55:24.907514Z","end":"2026-08-10T15:55:26.415221Z","steps":["trace[1965303268] 'process raft request' (duration: 1.507284502s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:26.415545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:24.907498Z","time spent":"1.507973747s","remote":"127.0.0.1:60738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:55:26.415567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.866468ms","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-08-10T15:55:26.415667Z","caller":"traceutil/trace.go:171","msg":"trace[2012984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"293.00731ms","start":"2026-08-10T15:55:26.122636Z","end":"2026-08-10T15:55:26.415643Z","steps":["trace[2012984] 'agreement among raft nodes before linearized reading' (duration: 292.681004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:26.41569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.601773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-10T15:55:26.415748Z","caller":"traceutil/trace.go:171","msg":"trace[13274030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"292.669374ms","start":"2026-08-10T15:55:26.123066Z","end":"2026-08-10T15:55:26.415735Z","steps":["trace[13274030] 'agreement among raft nodes before linearized reading' (duration: 292.526951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:26.415884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.225305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-10T15:55:26.415976Z","caller":"traceutil/trace.go:171","msg":"trace[378743374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"293.332677ms","start":"2026-08-10T15:55:26.122619Z","end":"2026-08-10T15:55:26.415952Z","steps":["trace[378743374] 'agreement among raft nodes before linearized reading' (duration: 293.016621ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:26.41617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.84509ms","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-08-10T15:55:26.416239Z","caller":"traceutil/trace.go:171","msg":"trace[1155441794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"338.537104ms","start":"2026-08-10T15:55:26.077683Z","end":"2026-08-10T15:55:26.41622Z","steps":["trace[1155441794] 'agreement among raft nodes before linearized reading' (duration: 337.565434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:26.416287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:26.077662Z","time spent":"338.609135ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-10T15:55:27.364052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.829437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:55:27.364167Z","caller":"traceutil/trace.go:171","msg":"trace[1417135791] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"440.445183ms","start":"2026-08-10T15:55:26.9237Z","end":"2026-08-10T15:55:27.364145Z","steps":["trace[1417135791] 'process raft request' (duration: 28.917293ms)","trace[1417135791] 'compare' (duration: 410.627813ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:55:27.364235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:26.923685Z","time spent":"440.520864ms","remote":"127.0.0.1:60738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20851,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:55:27.553355Z","caller":"traceutil/trace.go:171","msg":"trace[240266368] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"608.839467ms","start":"2026-08-10T15:55:26.944496Z","end":"2026-08-10T15:55:27.553336Z","steps":["trace[240266368] 'process raft request' (duration: 578.432978ms)","trace[240266368] 'compare' (duration: 30.301487ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:55:27.553454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:26.944481Z","time spent":"608.930509ms","remote":"127.0.0.1:60738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-10T15:55:27.56134Z","caller":"traceutil/trace.go:171","msg":"trace[111012973] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"525.64112ms","start":"2026-08-10T15:55:27.035681Z","end":"2026-08-10T15:55:27.561322Z","steps":["trace[111012973] 'process raft request' (duration: 524.039678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:27.56143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:27.035649Z","time spent":"525.733181ms","remote":"127.0.0.1:60738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:55:28.825179Z","caller":"traceutil/trace.go:171","msg":"trace[1648947824] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"338.114221ms","start":"2026-08-10T15:55:28.487032Z","end":"2026-08-10T15:55:28.825146Z","steps":["trace[1648947824] 'process raft request' (duration: 298.19114ms)","trace[1648947824] 'compare' (duration: 39.567004ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:55:28.825346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:28.487014Z","time spent":"338.252423ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:55:28.825353Z","caller":"traceutil/trace.go:171","msg":"trace[228242003] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"337.357476ms","start":"2026-08-10T15:55:28.487968Z","end":"2026-08-10T15:55:28.825326Z","steps":["trace[228242003] 'process raft request' (duration: 337.115832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:28.825493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:28.487955Z","time spent":"337.461778ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:55:29.981821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.823605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:55:29.981907Z","caller":"traceutil/trace.go:171","msg":"trace[34197124] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:573; }","duration":"123.366675ms","start":"2026-08-10T15:55:29.858521Z","end":"2026-08-10T15:55:29.981888Z","steps":["trace[34197124] 'range keys from in-memory index tree' (duration: 122.697443ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:31.121642Z","caller":"traceutil/trace.go:171","msg":"trace[1281022854] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"111.901142ms","start":"2026-08-10T15:55:31.00971Z","end":"2026-08-10T15:55:31.121611Z","steps":["trace[1281022854] 'process raft request' (duration: 78.647946ms)","trace[1281022854] 'compare' (duration: 32.885358ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:55:33.084498Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196308475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T15:55:33.386936Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.209846766s","expected-duration":"1s"} {"level":"info","ts":"2026-08-10T15:55:33.388187Z","caller":"traceutil/trace.go:171","msg":"trace[1590671769] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"804.5858ms","start":"2026-08-10T15:55:32.58357Z","end":"2026-08-10T15:55:33.388155Z","steps":["trace[1590671769] 'read index received' (duration: 803.541099ms)","trace[1590671769] 'applied index is now lower than readState.Index' (duration: 1.043761ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:55:33.388284Z","caller":"traceutil/trace.go:171","msg":"trace[558482347] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.212228671s","start":"2026-08-10T15:55:32.176044Z","end":"2026-08-10T15:55:33.388273Z","steps":["trace[558482347] 'process raft request' (duration: 1.211431165s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:33.388431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:32.17602Z","time spent":"1.212297953s","remote":"127.0.0.1:60762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:55:33.388876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.984718ms","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-08-10T15:55:33.388919Z","caller":"traceutil/trace.go:171","msg":"trace[2138002240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"805.054069ms","start":"2026-08-10T15:55:32.583853Z","end":"2026-08-10T15:55:33.388907Z","steps":["trace[2138002240] 'agreement among raft nodes before linearized reading' (duration: 804.901616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:33.388949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:32.583837Z","time spent":"805.10557ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-10T15:55:33.389124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.562689ms","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-08-10T15:55:33.389152Z","caller":"traceutil/trace.go:171","msg":"trace[1905927828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"805.621589ms","start":"2026-08-10T15:55:32.583523Z","end":"2026-08-10T15:55:33.389144Z","steps":["trace[1905927828] 'agreement among raft nodes before linearized reading' (duration: 805.551998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:33.389176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:32.583507Z","time spent":"805.664591ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-10T15:55:33.389471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.529651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-10T15:55:33.389501Z","caller":"traceutil/trace.go:171","msg":"trace[1799833454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"547.577882ms","start":"2026-08-10T15:55:32.841915Z","end":"2026-08-10T15:55:33.389493Z","steps":["trace[1799833454] 'agreement among raft nodes before linearized reading' (duration: 547.49997ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:33.389528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:32.841906Z","time spent":"547.616773ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-10T15:55:33.389491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.846623ms","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-08-10T15:55:33.389591Z","caller":"traceutil/trace.go:171","msg":"trace[1446872678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"552.995075ms","start":"2026-08-10T15:55:32.836569Z","end":"2026-08-10T15:55:33.389564Z","steps":["trace[1446872678] 'agreement among raft nodes before linearized reading' (duration: 552.693409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:33.389672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:32.836549Z","time spent":"553.107298ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-10T15:55:33.998282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.934369ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-08-10T15:55:34.510504Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196308490,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-10T15:55:34.536841Z","caller":"traceutil/trace.go:171","msg":"trace[1576559255] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"527.213121ms","start":"2026-08-10T15:55:34.009605Z","end":"2026-08-10T15:55:34.536818Z","steps":["trace[1576559255] 'read index received' (duration: 526.427176ms)","trace[1576559255] 'applied index is now lower than readState.Index' (duration: 784.815µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:55:34.536916Z","caller":"traceutil/trace.go:171","msg":"trace[1228086416] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"578.439318ms","start":"2026-08-10T15:55:33.958441Z","end":"2026-08-10T15:55:34.53688Z","steps":["trace[1228086416] 'process raft request' (duration: 577.851886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:34.537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.374244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T15:55:34.537079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:33.958415Z","time spent":"578.56932ms","remote":"127.0.0.1:60762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:55:34.537092Z","caller":"traceutil/trace.go:171","msg":"trace[913028768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"527.510927ms","start":"2026-08-10T15:55:34.009555Z","end":"2026-08-10T15:55:34.537066Z","steps":["trace[913028768] 'agreement among raft nodes before linearized reading' (duration: 527.352704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:34.53714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:34.009539Z","time spent":"527.592838ms","remote":"127.0.0.1:60678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-10T15:55:34.53714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.496542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4trxs\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-10T15:55:34.537197Z","caller":"traceutil/trace.go:171","msg":"trace[376346344] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4trxs; range_end:; response_count:1; response_revision:594; }","duration":"280.602024ms","start":"2026-08-10T15:55:34.256581Z","end":"2026-08-10T15:55:34.537183Z","steps":["trace[376346344] 'agreement among raft nodes before linearized reading' (duration: 280.38887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:35.510915Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196308494,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T15:55:35.895725Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.20371755s","expected-duration":"1s"} {"level":"info","ts":"2026-08-10T15:55:35.89625Z","caller":"traceutil/trace.go:171","msg":"trace[1053409369] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.344063383s","start":"2026-08-10T15:55:34.552167Z","end":"2026-08-10T15:55:35.89623Z","steps":["trace[1053409369] 'process raft request' (duration: 1.34391212s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:35.896382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:34.552142Z","time spent":"1.344186335s","remote":"127.0.0.1:60840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:55:36.032114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.831583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:55:36.032215Z","caller":"traceutil/trace.go:171","msg":"trace[961637585] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"1.022064561s","start":"2026-08-10T15:55:35.010132Z","end":"2026-08-10T15:55:36.032197Z","steps":["trace[961637585] 'read index received' (duration: 885.951149ms)","trace[961637585] 'applied index is now lower than readState.Index' (duration: 136.112222ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:55:36.032352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022213425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:55:36.032417Z","caller":"traceutil/trace.go:171","msg":"trace[641870293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"1.022315277s","start":"2026-08-10T15:55:35.010084Z","end":"2026-08-10T15:55:36.032399Z","steps":["trace[641870293] 'agreement among raft nodes before linearized reading' (duration: 1.022190074s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:36.03246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:35.010065Z","time spent":"1.022386439s","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-10T15:55:36.032554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.653442ms","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-08-10T15:55:36.032462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.015487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-10T15:55:36.032613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.418957ms","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-08-10T15:55:36.032653Z","caller":"traceutil/trace.go:171","msg":"trace[159073150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"542.248501ms","start":"2026-08-10T15:55:35.490393Z","end":"2026-08-10T15:55:36.032642Z","steps":["trace[159073150] 'agreement among raft nodes before linearized reading' (duration: 541.890854ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:36.03267Z","caller":"traceutil/trace.go:171","msg":"trace[1561800782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"541.497598ms","start":"2026-08-10T15:55:35.491155Z","end":"2026-08-10T15:55:36.032653Z","steps":["trace[1561800782] 'agreement among raft nodes before linearized reading' (duration: 541.339965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:36.032684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.945886ms","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-08-10T15:55:36.032696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:35.49037Z","time spent":"542.314813ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-10T15:55:36.032709Z","caller":"traceutil/trace.go:171","msg":"trace[812400803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"542.006968ms","start":"2026-08-10T15:55:35.490696Z","end":"2026-08-10T15:55:36.032703Z","steps":["trace[812400803] 'agreement among raft nodes before linearized reading' (duration: 541.938806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:36.032716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:35.491144Z","time spent":"541.561449ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-10T15:55:36.032743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:35.490675Z","time spent":"542.060538ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-10T15:55:36.032614Z","caller":"traceutil/trace.go:171","msg":"trace[1493983993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:596; }","duration":"540.758364ms","start":"2026-08-10T15:55:35.491845Z","end":"2026-08-10T15:55:36.032604Z","steps":["trace[1493983993] 'agreement among raft nodes before linearized reading' (duration: 540.646972ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:36.03257Z","caller":"traceutil/trace.go:171","msg":"trace[428567087] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.437341353s","start":"2026-08-10T15:55:34.595151Z","end":"2026-08-10T15:55:36.032492Z","steps":["trace[428567087] 'process raft request' (duration: 1.33506139s)","trace[428567087] 'compare' (duration: 101.681691ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:55:36.032934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:35.491836Z","time spent":"541.000228ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-10T15:55:36.03359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:34.595123Z","time spent":"1.437778981s","remote":"127.0.0.1:60996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-10T15:55:36.543401Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196308499,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T15:55:37.044257Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196308499,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T15:55:37.54476Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196308499,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T15:55:38.043309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999975564s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-10T15:55:38.043446Z","caller":"traceutil/trace.go:171","msg":"trace[1891757057] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000151426s","start":"2026-08-10T15:55:36.043272Z","end":"2026-08-10T15:55:38.043424Z","steps":["trace[1891757057] 'agreement among raft nodes before linearized reading' (duration: 1.999990483s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:38.043522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:36.043256Z","time spent":"2.000251099s","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-10T15:55:38.045291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196308499,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T15:55:38.545882Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196308499,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T15:55:38.742439Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.623869896s","expected-duration":"1s"} {"level":"info","ts":"2026-08-10T15:55:38.742815Z","caller":"traceutil/trace.go:171","msg":"trace[1992860535] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"2.70509727s","start":"2026-08-10T15:55:36.037691Z","end":"2026-08-10T15:55:38.742788Z","steps":["trace[1992860535] 'process raft request' (duration: 2.704937157s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:38.742966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:36.037657Z","time spent":"2.705231461s","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:55:38.797467Z","caller":"traceutil/trace.go:171","msg":"trace[2085646400] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"2.759462783s","start":"2026-08-10T15:55:36.037974Z","end":"2026-08-10T15:55:38.797437Z","steps":["trace[2085646400] 'process raft request' (duration: 2.759190267s)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:38.797529Z","caller":"traceutil/trace.go:171","msg":"trace[369284341] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:624; }","duration":"2.754202126s","start":"2026-08-10T15:55:36.043302Z","end":"2026-08-10T15:55:38.797504Z","steps":["trace[369284341] 'read index received' (duration: 2.699400134s)","trace[369284341] 'applied index is now lower than readState.Index' (duration: 54.800902ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:55:38.797532Z","caller":"traceutil/trace.go:171","msg":"trace[1875712731] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"2.759258999s","start":"2026-08-10T15:55:36.038243Z","end":"2026-08-10T15:55:38.797502Z","steps":["trace[1875712731] 'process raft request' (duration: 2.759067804s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:38.797625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:36.037945Z","time spent":"2.759599016s","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:55:38.797643Z","caller":"traceutil/trace.go:171","msg":"trace[99414511] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"2.758205279s","start":"2026-08-10T15:55:36.039427Z","end":"2026-08-10T15:55:38.797632Z","steps":["trace[99414511] 'process raft request' (duration: 2.757944855s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:38.797752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:36.039411Z","time spent":"2.75826291s","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:55:38.797747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:36.038231Z","time spent":"2.759385231s","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:55:38.797949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.754311147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:55:38.797999Z","caller":"traceutil/trace.go:171","msg":"trace[1667747677] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:601; }","duration":"2.754371828s","start":"2026-08-10T15:55:36.043613Z","end":"2026-08-10T15:55:38.797985Z","steps":["trace[1667747677] 'agreement among raft nodes before linearized reading' (duration: 2.754274976s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:38.798013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.409767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T15:55:38.798031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:36.043605Z","time spent":"2.754418328s","remote":"127.0.0.1:32922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"info","ts":"2026-08-10T15:55:38.798076Z","caller":"traceutil/trace.go:171","msg":"trace[1612853896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"753.505219ms","start":"2026-08-10T15:55:38.044546Z","end":"2026-08-10T15:55:38.798051Z","steps":["trace[1612853896] 'agreement among raft nodes before linearized reading' (duration: 753.400697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:38.798087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.093586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-10T15:55:38.798111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:38.044523Z","time spent":"753.58052ms","remote":"127.0.0.1:60678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-10T15:55:38.798101Z","caller":"traceutil/trace.go:171","msg":"trace[675935558] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"927.340411ms","start":"2026-08-10T15:55:37.870724Z","end":"2026-08-10T15:55:38.798065Z","steps":["trace[675935558] 'process raft request' (duration: 926.73131ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:38.798149Z","caller":"traceutil/trace.go:171","msg":"trace[1753988414] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:601; }","duration":"461.204457ms","start":"2026-08-10T15:55:38.336929Z","end":"2026-08-10T15:55:38.798134Z","steps":["trace[1753988414] 'agreement among raft nodes before linearized reading' (duration: 461.014014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:38.798281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:38.336907Z","time spent":"461.362841ms","remote":"127.0.0.1:60720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"warn","ts":"2026-08-10T15:55:38.798283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:37.870701Z","time spent":"927.495813ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:55:41.043095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.437193ms","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-08-10T15:55:41.043169Z","caller":"traceutil/trace.go:171","msg":"trace[1893589604] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"296.564896ms","start":"2026-08-10T15:55:40.746588Z","end":"2026-08-10T15:55:41.043153Z","steps":["trace[1893589604] 'range keys from in-memory index tree' (duration: 296.182319ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:41.043198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.867393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-10T15:55:41.043238Z","caller":"traceutil/trace.go:171","msg":"trace[1662748303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"239.927883ms","start":"2026-08-10T15:55:40.803296Z","end":"2026-08-10T15:55:41.043223Z","steps":["trace[1662748303] 'range keys from in-memory index tree' (duration: 239.72681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:41.043104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.606977ms","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-08-10T15:55:41.043453Z","caller":"traceutil/trace.go:171","msg":"trace[676111298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"240.989413ms","start":"2026-08-10T15:55:40.802456Z","end":"2026-08-10T15:55:41.043446Z","steps":["trace[676111298] 'range keys from in-memory index tree' (duration: 240.464283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:41.0435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.53163ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:55:41.043662Z","caller":"traceutil/trace.go:171","msg":"trace[1506280409] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:623; }","duration":"155.699743ms","start":"2026-08-10T15:55:40.887932Z","end":"2026-08-10T15:55:41.043632Z","steps":["trace[1506280409] 'range keys from in-memory index tree' (duration: 155.51335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:41.043791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.574235ms","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-08-10T15:55:41.043827Z","caller":"traceutil/trace.go:171","msg":"trace[197786699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"240.621686ms","start":"2026-08-10T15:55:40.803197Z","end":"2026-08-10T15:55:41.043819Z","steps":["trace[197786699] 'range keys from in-memory index tree' (duration: 240.499853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:43.902659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.764866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4trxs\" ","response":"range_response_count:1 size:18067"} {"level":"info","ts":"2026-08-10T15:55:43.902801Z","caller":"traceutil/trace.go:171","msg":"trace[1126059960] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4trxs; range_end:; response_count:1; response_revision:673; }","duration":"592.911398ms","start":"2026-08-10T15:55:43.309836Z","end":"2026-08-10T15:55:43.902747Z","steps":["trace[1126059960] 'range keys from in-memory index tree' (duration: 592.575562ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:43.902818Z","caller":"traceutil/trace.go:171","msg":"trace[645701618] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"592.206095ms","start":"2026-08-10T15:55:43.310578Z","end":"2026-08-10T15:55:43.902784Z","steps":["trace[645701618] 'process raft request' (duration: 559.073588ms)","trace[645701618] 'compare' (duration: 32.806311ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:55:43.902838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:43.309804Z","time spent":"593.027171ms","remote":"127.0.0.1:60840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18090,"request content":"key:\"/registry/pods/kube-system/cilium-4trxs\" "} {"level":"info","ts":"2026-08-10T15:55:43.902707Z","caller":"traceutil/trace.go:171","msg":"trace[1200473991] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"413.309128ms","start":"2026-08-10T15:55:43.489341Z","end":"2026-08-10T15:55:43.90265Z","steps":["trace[1200473991] 'read index received' (duration: 380.518807ms)","trace[1200473991] 'applied index is now lower than readState.Index' (duration: 32.788891ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:55:43.903031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:43.310532Z","time spent":"592.333127ms","remote":"127.0.0.1:60754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-10T15:55:43.903429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.044511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-08-10T15:55:43.903525Z","caller":"traceutil/trace.go:171","msg":"trace[1181525303] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:674; }","duration":"414.231414ms","start":"2026-08-10T15:55:43.489275Z","end":"2026-08-10T15:55:43.903507Z","steps":["trace[1181525303] 'agreement among raft nodes before linearized reading' (duration: 413.760486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:43.903601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:43.489246Z","time spent":"414.341997ms","remote":"127.0.0.1:60738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-08-10T15:55:43.915347Z","caller":"traceutil/trace.go:171","msg":"trace[116562722] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"217.195495ms","start":"2026-08-10T15:55:43.698114Z","end":"2026-08-10T15:55:43.91531Z","steps":["trace[116562722] 'process raft request' (duration: 216.973991ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:44.063853Z","caller":"traceutil/trace.go:171","msg":"trace[1772066237] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"130.739517ms","start":"2026-08-10T15:55:43.933056Z","end":"2026-08-10T15:55:44.063795Z","steps":["trace[1772066237] 'process raft request' (duration: 73.270966ms)","trace[1772066237] 'compare' (duration: 57.109865ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:55:44.183997Z","caller":"traceutil/trace.go:171","msg":"trace[2119263645] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"106.429733ms","start":"2026-08-10T15:55:44.077469Z","end":"2026-08-10T15:55:44.183899Z","steps":["trace[2119263645] 'process raft request' (duration: 86.031294ms)","trace[2119263645] 'compare' (duration: 20.083874ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:55:44.642379Z","caller":"traceutil/trace.go:171","msg":"trace[1527736072] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"319.94493ms","start":"2026-08-10T15:55:44.322416Z","end":"2026-08-10T15:55:44.642361Z","steps":["trace[1527736072] 'read index received' (duration: 319.555233ms)","trace[1527736072] 'applied index is now lower than readState.Index' (duration: 388.727µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:55:44.642435Z","caller":"traceutil/trace.go:171","msg":"trace[1244929863] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"321.665931ms","start":"2026-08-10T15:55:44.320739Z","end":"2026-08-10T15:55:44.642405Z","steps":["trace[1244929863] 'process raft request' (duration: 321.453787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:44.642568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.43582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"warn","ts":"2026-08-10T15:55:44.64256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:44.320689Z","time spent":"321.808783ms","remote":"127.0.0.1:60738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-10T15:55:44.642598Z","caller":"traceutil/trace.go:171","msg":"trace[500826036] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:681; }","duration":"153.517922ms","start":"2026-08-10T15:55:44.489072Z","end":"2026-08-10T15:55:44.64259Z","steps":["trace[500826036] 'agreement among raft nodes before linearized reading' (duration: 153.38967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:44.642661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.226335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4trxs\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-08-10T15:55:44.642749Z","caller":"traceutil/trace.go:171","msg":"trace[2105267793] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4trxs; range_end:; response_count:1; response_revision:681; }","duration":"320.368987ms","start":"2026-08-10T15:55:44.322362Z","end":"2026-08-10T15:55:44.642731Z","steps":["trace[2105267793] 'agreement among raft nodes before linearized reading' (duration: 320.084932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:44.642802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:44.322335Z","time spent":"320.456488ms","remote":"127.0.0.1:60840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18360,"request content":"key:\"/registry/pods/kube-system/cilium-4trxs\" "} {"level":"warn","ts":"2026-08-10T15:55:55.764043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196308902,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T15:55:56.264361Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196308902,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T15:55:56.779395Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196308902,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T15:55:56.863727Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.65059829s","expected-duration":"1s"} {"level":"info","ts":"2026-08-10T15:55:56.864108Z","caller":"traceutil/trace.go:171","msg":"trace[2036164205] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"1.600833818s","start":"2026-08-10T15:55:55.26325Z","end":"2026-08-10T15:55:56.864084Z","steps":["trace[2036164205] 'read index received' (duration: 1.600594835s)","trace[2036164205] 'applied index is now lower than readState.Index' (duration: 237.723µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:55:56.864189Z","caller":"traceutil/trace.go:171","msg":"trace[1372561458] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"1.65118635s","start":"2026-08-10T15:55:55.21298Z","end":"2026-08-10T15:55:56.864167Z","steps":["trace[1372561458] 'process raft request' (duration: 1.650947926s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:56.864322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:55.21294Z","time spent":"1.651308821s","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:55:56.864373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.481744331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7hcgf\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-08-10T15:55:56.864408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.564386566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-10T15:55:56.86444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.577204228s","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-08-10T15:55:56.864463Z","caller":"traceutil/trace.go:171","msg":"trace[1527387777] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"1.564461377s","start":"2026-08-10T15:55:55.299988Z","end":"2026-08-10T15:55:56.864449Z","steps":["trace[1527387777] 'agreement among raft nodes before linearized reading' (duration: 1.564299495s)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:56.864495Z","caller":"traceutil/trace.go:171","msg":"trace[1161901405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:766; }","duration":"1.577307619s","start":"2026-08-10T15:55:55.287175Z","end":"2026-08-10T15:55:56.864483Z","steps":["trace[1161901405] 'agreement among raft nodes before linearized reading' (duration: 1.577150656s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:56.864499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:55.299976Z","time spent":"1.564515678s","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-10T15:55:56.864531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:55.287163Z","time spent":"1.57736029s","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-10T15:55:56.864445Z","caller":"traceutil/trace.go:171","msg":"trace[1472831120] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7hcgf; range_end:; response_count:1; response_revision:766; }","duration":"1.481857373s","start":"2026-08-10T15:55:55.382568Z","end":"2026-08-10T15:55:56.864426Z","steps":["trace[1472831120] 'agreement among raft nodes before linearized reading' (duration: 1.48167074s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:56.864585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:55.382548Z","time spent":"1.482023445s","remote":"127.0.0.1:60840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7hcgf\" "} {"level":"warn","ts":"2026-08-10T15:55:56.864374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.601112223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-10T15:55:56.864643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.578916006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-10T15:55:56.864709Z","caller":"traceutil/trace.go:171","msg":"trace[137645757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"1.601481889s","start":"2026-08-10T15:55:55.263188Z","end":"2026-08-10T15:55:56.86467Z","steps":["trace[137645757] 'agreement among raft nodes before linearized reading' (duration: 1.601020091s)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:56.864679Z","caller":"traceutil/trace.go:171","msg":"trace[1578099393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"1.579007898s","start":"2026-08-10T15:55:55.285661Z","end":"2026-08-10T15:55:56.864669Z","steps":["trace[1578099393] 'agreement among raft nodes before linearized reading' (duration: 1.578557501s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:55:56.864768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:55.285642Z","time spent":"1.579119919s","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-10T15:55:56.864778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:55:55.263164Z","time spent":"1.60159927s","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-10T15:56:09.24772Z","caller":"traceutil/trace.go:171","msg":"trace[61529408] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"264.679742ms","start":"2026-08-10T15:56:08.98302Z","end":"2026-08-10T15:56:09.2477Z","steps":["trace[61529408] 'read index received' (duration: 264.582711ms)","trace[61529408] 'applied index is now lower than readState.Index' (duration: 95.931µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:56:09.247821Z","caller":"traceutil/trace.go:171","msg":"trace[559413782] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"265.035008ms","start":"2026-08-10T15:56:08.982778Z","end":"2026-08-10T15:56:09.247813Z","steps":["trace[559413782] 'process raft request' (duration: 264.793764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:09.248021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.985647ms","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-08-10T15:56:09.248092Z","caller":"traceutil/trace.go:171","msg":"trace[1832111133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:914; }","duration":"265.054917ms","start":"2026-08-10T15:56:08.982989Z","end":"2026-08-10T15:56:09.248044Z","steps":["trace[1832111133] 'agreement among raft nodes before linearized reading' (duration: 264.947016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:09.377866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.705127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:56:09.378055Z","caller":"traceutil/trace.go:171","msg":"trace[201030896] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:951; }","duration":"130.252914ms","start":"2026-08-10T15:56:09.247785Z","end":"2026-08-10T15:56:09.378038Z","steps":["trace[201030896] 'read index received' (duration: 22.308483ms)","trace[201030896] 'applied index is now lower than readState.Index' (duration: 107.943541ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:56:09.37808Z","caller":"traceutil/trace.go:171","msg":"trace[1724310205] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"394.953466ms","start":"2026-08-10T15:56:08.983105Z","end":"2026-08-10T15:56:09.378059Z","steps":["trace[1724310205] 'process raft request' (duration: 286.976665ms)","trace[1724310205] 'compare' (duration: 107.601045ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:56:09.378112Z","caller":"traceutil/trace.go:171","msg":"trace[72504722] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"394.997827ms","start":"2026-08-10T15:56:08.983105Z","end":"2026-08-10T15:56:09.378103Z","steps":["trace[72504722] 'process raft request' (duration: 394.848665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:09.378172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:08.983091Z","time spent":"395.037747ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:56:09.378192Z","caller":"traceutil/trace.go:171","msg":"trace[1061504817] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"126.249851ms","start":"2026-08-10T15:56:09.251925Z","end":"2026-08-10T15:56:09.378175Z","steps":["trace[1061504817] 'process raft request' (duration: 126.083488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:09.378233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.960019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T15:56:09.378171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:08.983087Z","time spent":"395.048187ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:56:09.378263Z","caller":"traceutil/trace.go:171","msg":"trace[1341403055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"368.012699ms","start":"2026-08-10T15:56:09.010239Z","end":"2026-08-10T15:56:09.378252Z","steps":["trace[1341403055] 'agreement among raft nodes before linearized reading' (duration: 367.957378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:09.378295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:09.010226Z","time spent":"368.063ms","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-10T15:56:09.378379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.322451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-10T15:56:09.37841Z","caller":"traceutil/trace.go:171","msg":"trace[1774832130] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:917; }","duration":"395.366522ms","start":"2026-08-10T15:56:08.983034Z","end":"2026-08-10T15:56:09.3784Z","steps":["trace[1774832130] 'agreement among raft nodes before linearized reading' (duration: 395.051968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:09.378436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:08.983022Z","time spent":"395.407033ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-10T15:56:09.793717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.216356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:56:09.79385Z","caller":"traceutil/trace.go:171","msg":"trace[2085114460] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"487.473777ms","start":"2026-08-10T15:56:09.306336Z","end":"2026-08-10T15:56:09.793809Z","steps":["trace[2085114460] 'process raft request' (duration: 267.080658ms)","trace[2085114460] 'compare' (duration: 220.111164ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:56:09.793974Z","caller":"traceutil/trace.go:171","msg":"trace[1410837080] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"135.978661ms","start":"2026-08-10T15:56:09.657978Z","end":"2026-08-10T15:56:09.793957Z","steps":["trace[1410837080] 'process raft request' (duration: 135.92843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:09.794009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:09.306308Z","time spent":"487.612779ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:56:09.794139Z","caller":"traceutil/trace.go:171","msg":"trace[1179139157] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"411.578268ms","start":"2026-08-10T15:56:09.382551Z","end":"2026-08-10T15:56:09.794129Z","steps":["trace[1179139157] 'process raft request' (duration: 411.266234ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:09.794189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:09.382533Z","time spent":"411.62776ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:56:09.794205Z","caller":"traceutil/trace.go:171","msg":"trace[1217010962] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"414.911831ms","start":"2026-08-10T15:56:09.379274Z","end":"2026-08-10T15:56:09.794186Z","steps":["trace[1217010962] 'read index received' (duration: 194.228778ms)","trace[1217010962] 'applied index is now lower than readState.Index' (duration: 220.680603ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:56:09.794198Z","caller":"traceutil/trace.go:171","msg":"trace[2129886474] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"274.505212ms","start":"2026-08-10T15:56:09.51965Z","end":"2026-08-10T15:56:09.794155Z","steps":["trace[2129886474] 'process raft request' (duration: 274.217678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:09.794346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.070665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:56:09.79438Z","caller":"traceutil/trace.go:171","msg":"trace[219912232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"415.147555ms","start":"2026-08-10T15:56:09.379222Z","end":"2026-08-10T15:56:09.794369Z","steps":["trace[219912232] 'agreement among raft nodes before linearized reading' (duration: 415.067553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:09.794414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:09.379202Z","time spent":"415.201107ms","remote":"127.0.0.1:60678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-10T15:56:11.508129Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196309188,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-10T15:56:11.642299Z","caller":"traceutil/trace.go:171","msg":"trace[943607245] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"634.555478ms","start":"2026-08-10T15:56:11.007721Z","end":"2026-08-10T15:56:11.642276Z","steps":["trace[943607245] 'read index received' (duration: 634.418456ms)","trace[943607245] 'applied index is now lower than readState.Index' (duration: 135.962µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:56:11.642629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.446109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-10T15:56:11.642641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.475633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-10T15:56:11.642626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.585964ms","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-08-10T15:56:11.642692Z","caller":"traceutil/trace.go:171","msg":"trace[798971246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:923; }","duration":"260.52704ms","start":"2026-08-10T15:56:11.382151Z","end":"2026-08-10T15:56:11.642678Z","steps":["trace[798971246] 'agreement among raft nodes before linearized reading' (duration: 260.374408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:11.642689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.916556ms","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-08-10T15:56:11.642769Z","caller":"traceutil/trace.go:171","msg":"trace[1292096851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:923; }","duration":"259.757068ms","start":"2026-08-10T15:56:11.382982Z","end":"2026-08-10T15:56:11.642739Z","steps":["trace[1292096851] 'agreement among raft nodes before linearized reading' (duration: 259.445253ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:11.642707Z","caller":"traceutil/trace.go:171","msg":"trace[1106969947] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:923; }","duration":"236.561304ms","start":"2026-08-10T15:56:11.406134Z","end":"2026-08-10T15:56:11.642695Z","steps":["trace[1106969947] 'agreement among raft nodes before linearized reading' (duration: 236.463803ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:11.642858Z","caller":"traceutil/trace.go:171","msg":"trace[2114378238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:923; }","duration":"261.081278ms","start":"2026-08-10T15:56:11.38172Z","end":"2026-08-10T15:56:11.642801Z","steps":["trace[2114378238] 'agreement among raft nodes before linearized reading' (duration: 260.725162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:11.642634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.755639ms","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-08-10T15:56:11.642969Z","caller":"traceutil/trace.go:171","msg":"trace[1310465953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:923; }","duration":"392.139435ms","start":"2026-08-10T15:56:11.250816Z","end":"2026-08-10T15:56:11.642955Z","steps":["trace[1310465953] 'agreement among raft nodes before linearized reading' (duration: 391.680798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:11.643021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:11.250802Z","time spent":"392.203976ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-10T15:56:11.642396Z","caller":"traceutil/trace.go:171","msg":"trace[303628254] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"734.364645ms","start":"2026-08-10T15:56:10.908013Z","end":"2026-08-10T15:56:11.642378Z","steps":["trace[303628254] 'process raft request' (duration: 734.131402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:11.643253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.529863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T15:56:11.643519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:10.907988Z","time spent":"735.345692ms","remote":"127.0.0.1:60840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:56:11.643541Z","caller":"traceutil/trace.go:171","msg":"trace[2033847425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:923; }","duration":"635.836148ms","start":"2026-08-10T15:56:11.007686Z","end":"2026-08-10T15:56:11.643522Z","steps":["trace[2033847425] 'agreement among raft nodes before linearized reading' (duration: 635.443172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:11.643632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:11.007671Z","time spent":"635.94673ms","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-10T15:56:11.643085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.847919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T15:56:11.646289Z","caller":"traceutil/trace.go:171","msg":"trace[410423765] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:923; }","duration":"471.692978ms","start":"2026-08-10T15:56:11.17458Z","end":"2026-08-10T15:56:11.646273Z","steps":["trace[410423765] 'agreement among raft nodes before linearized reading' (duration: 467.829739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:11.646379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:11.17456Z","time spent":"471.80098ms","remote":"127.0.0.1:32940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-08-10T15:56:12.146865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.361075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:56:12.146999Z","caller":"traceutil/trace.go:171","msg":"trace[253572614] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"497.048653ms","start":"2026-08-10T15:56:11.649931Z","end":"2026-08-10T15:56:12.146979Z","steps":["trace[253572614] 'process raft request' (duration: 204.493755ms)","trace[253572614] 'compare' (duration: 292.236063ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:56:12.147071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:11.649911Z","time spent":"497.132365ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:56:12.355746Z","caller":"traceutil/trace.go:171","msg":"trace[1464833170] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"705.255542ms","start":"2026-08-10T15:56:11.650445Z","end":"2026-08-10T15:56:12.355701Z","steps":["trace[1464833170] 'process raft request' (duration: 699.491485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:12.355894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:11.650436Z","time spent":"705.384704ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:56:12.356185Z","caller":"traceutil/trace.go:171","msg":"trace[1927403066] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"695.936069ms","start":"2026-08-10T15:56:11.660227Z","end":"2026-08-10T15:56:12.356163Z","steps":["trace[1927403066] 'process raft request' (duration: 695.727916ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:12.356211Z","caller":"traceutil/trace.go:171","msg":"trace[1323981879] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"702.450369ms","start":"2026-08-10T15:56:11.653743Z","end":"2026-08-10T15:56:12.356193Z","steps":["trace[1323981879] 'process raft request' (duration: 701.944422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:12.356242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:11.660217Z","time spent":"695.996249ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:56:12.356269Z","caller":"traceutil/trace.go:171","msg":"trace[1534162241] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"672.672495ms","start":"2026-08-10T15:56:11.683587Z","end":"2026-08-10T15:56:12.35626Z","steps":["trace[1534162241] 'process raft request' (duration: 672.473332ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:12.35621Z","caller":"traceutil/trace.go:171","msg":"trace[1853827331] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"673.00738ms","start":"2026-08-10T15:56:11.68318Z","end":"2026-08-10T15:56:12.356187Z","steps":["trace[1853827331] 'process raft request' (duration: 672.831717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:12.356325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:11.683167Z","time spent":"673.137612ms","remote":"127.0.0.1:32890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5192,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:56:12.356349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:11.653733Z","time spent":"702.53779ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:56:12.356349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:11.683582Z","time spent":"672.712356ms","remote":"127.0.0.1:60828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:56:12.35639Z","caller":"traceutil/trace.go:171","msg":"trace[1329166598] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"672.217448ms","start":"2026-08-10T15:56:11.684156Z","end":"2026-08-10T15:56:12.356374Z","steps":["trace[1329166598] 'process raft request' (duration: 671.978444ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:12.356492Z","caller":"traceutil/trace.go:171","msg":"trace[79287145] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:960; }","duration":"558.090028ms","start":"2026-08-10T15:56:11.79839Z","end":"2026-08-10T15:56:12.35648Z","steps":["trace[79287145] 'read index received' (duration: 56.032873ms)","trace[79287145] 'applied index is now lower than readState.Index' (duration: 502.056235ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:56:12.356502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:11.684134Z","time spent":"672.305399ms","remote":"127.0.0.1:60938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1628,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:56:12.356532Z","caller":"traceutil/trace.go:171","msg":"trace[1186718134] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"556.83428ms","start":"2026-08-10T15:56:11.799679Z","end":"2026-08-10T15:56:12.356514Z","steps":["trace[1186718134] 'process raft request' (duration: 556.618857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:12.356621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.236951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-10T15:56:12.356638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:11.799652Z","time spent":"556.928471ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:56:12.356653Z","caller":"traceutil/trace.go:171","msg":"trace[1385367614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:931; }","duration":"558.308162ms","start":"2026-08-10T15:56:11.798336Z","end":"2026-08-10T15:56:12.356644Z","steps":["trace[1385367614] 'agreement among raft nodes before linearized reading' (duration: 558.20194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:12.356675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:11.798314Z","time spent":"558.357002ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-10T15:56:12.830368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.414552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:56:12.830486Z","caller":"traceutil/trace.go:171","msg":"trace[80154641] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"473.950468ms","start":"2026-08-10T15:56:12.356514Z","end":"2026-08-10T15:56:12.830465Z","steps":["trace[80154641] 'read index received' (duration: 201.364284ms)","trace[80154641] 'applied index is now lower than readState.Index' (duration: 272.584394ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:56:12.830654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.527082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:56:12.830692Z","caller":"traceutil/trace.go:171","msg":"trace[1868468427] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:932; }","duration":"719.570353ms","start":"2026-08-10T15:56:12.111113Z","end":"2026-08-10T15:56:12.830683Z","steps":["trace[1868468427] 'agreement among raft nodes before linearized reading' (duration: 719.478331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:12.830721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:12.111074Z","time spent":"719.639284ms","remote":"127.0.0.1:60814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-08-10T15:56:12.830721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.366665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:56:12.830759Z","caller":"traceutil/trace.go:171","msg":"trace[1623861301] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:932; }","duration":"897.447747ms","start":"2026-08-10T15:56:11.933301Z","end":"2026-08-10T15:56:12.830749Z","steps":["trace[1623861301] 'agreement among raft nodes before linearized reading' (duration: 897.222663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:12.830801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:11.933278Z","time spent":"897.514177ms","remote":"127.0.0.1:60952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-10T15:56:12.830778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.049347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:56:12.83063Z","caller":"traceutil/trace.go:171","msg":"trace[593545113] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"783.122428ms","start":"2026-08-10T15:56:12.047484Z","end":"2026-08-10T15:56:12.830606Z","steps":["trace[593545113] 'process raft request' (duration: 510.395181ms)","trace[593545113] 'compare' (duration: 272.28819ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:56:12.830911Z","caller":"traceutil/trace.go:171","msg":"trace[550314362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"820.191259ms","start":"2026-08-10T15:56:12.010673Z","end":"2026-08-10T15:56:12.830864Z","steps":["trace[550314362] 'agreement among raft nodes before linearized reading' (duration: 820.000587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:12.830938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:12.047452Z","time spent":"783.429792ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:56:12.830989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:12.010654Z","time spent":"820.299041ms","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-10T15:56:13.214229Z","caller":"traceutil/trace.go:171","msg":"trace[1824580202] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"849.339847ms","start":"2026-08-10T15:56:12.364866Z","end":"2026-08-10T15:56:13.214206Z","steps":["trace[1824580202] 'process raft request' (duration: 846.343862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:13.214407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:12.364846Z","time spent":"849.468909ms","remote":"127.0.0.1:32854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:56:13.214415Z","caller":"traceutil/trace.go:171","msg":"trace[2060000780] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:969; }","duration":"381.588103ms","start":"2026-08-10T15:56:12.832801Z","end":"2026-08-10T15:56:13.214389Z","steps":["trace[2060000780] 'read index received' (duration: 378.273503ms)","trace[2060000780] 'applied index is now lower than readState.Index' (duration: 3.31277ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:56:13.214537Z","caller":"traceutil/trace.go:171","msg":"trace[1841278181] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"848.84185ms","start":"2026-08-10T15:56:12.365684Z","end":"2026-08-10T15:56:13.214526Z","steps":["trace[1841278181] 'process raft request' (duration: 848.455214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:13.214638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:12.365668Z","time spent":"848.89532ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:56:13.214761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.235897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-whjvs\" ","response":"range_response_count:1 size:2276"} {"level":"info","ts":"2026-08-10T15:56:13.214848Z","caller":"traceutil/trace.go:171","msg":"trace[2020208330] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-whjvs; range_end:; response_count:1; response_revision:934; }","duration":"288.358169ms","start":"2026-08-10T15:56:12.926467Z","end":"2026-08-10T15:56:13.214825Z","steps":["trace[2020208330] 'agreement among raft nodes before linearized reading' (duration: 288.147575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:13.214989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.175692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:56:13.215028Z","caller":"traceutil/trace.go:171","msg":"trace[1279414641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"382.239373ms","start":"2026-08-10T15:56:12.832776Z","end":"2026-08-10T15:56:13.215015Z","steps":["trace[1279414641] 'agreement among raft nodes before linearized reading' (duration: 382.135482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:13.215059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:12.832762Z","time spent":"382.288784ms","remote":"127.0.0.1:60678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-10T15:56:13.215172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.716425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-whjvs\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-08-10T15:56:13.215217Z","caller":"traceutil/trace.go:171","msg":"trace[279408335] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-whjvs; range_end:; response_count:1; response_revision:934; }","duration":"228.798766ms","start":"2026-08-10T15:56:12.986406Z","end":"2026-08-10T15:56:13.215204Z","steps":["trace[279408335] 'agreement among raft nodes before linearized reading' (duration: 228.634863ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:13.375821Z","caller":"traceutil/trace.go:171","msg":"trace[785109957] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"153.168213ms","start":"2026-08-10T15:56:13.222628Z","end":"2026-08-10T15:56:13.375796Z","steps":["trace[785109957] 'read index received' (duration: 153.020461ms)","trace[785109957] 'applied index is now lower than readState.Index' (duration: 146.192µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:56:13.375914Z","caller":"traceutil/trace.go:171","msg":"trace[836926283] transaction","detail":"{read_only:false; number_of_response:1; response_revision:935; }","duration":"157.987465ms","start":"2026-08-10T15:56:13.217901Z","end":"2026-08-10T15:56:13.375889Z","steps":["trace[836926283] 'process raft request' (duration: 157.755132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:13.376039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.386466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:56:13.376401Z","caller":"traceutil/trace.go:171","msg":"trace[1372123921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"153.754641ms","start":"2026-08-10T15:56:13.222602Z","end":"2026-08-10T15:56:13.376357Z","steps":["trace[1372123921] 'agreement among raft nodes before linearized reading' (duration: 153.371826ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:13.534821Z","caller":"traceutil/trace.go:171","msg":"trace[1388499065] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"312.160014ms","start":"2026-08-10T15:56:13.222637Z","end":"2026-08-10T15:56:13.534797Z","steps":["trace[1388499065] 'process raft request' (duration: 312.016972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:13.534998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:13.222616Z","time spent":"312.277236ms","remote":"127.0.0.1:60840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:56:13.970961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.34753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:56:13.971088Z","caller":"traceutil/trace.go:171","msg":"trace[1661133536] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"429.754488ms","start":"2026-08-10T15:56:13.541309Z","end":"2026-08-10T15:56:13.971064Z","steps":["trace[1661133536] 'process raft request' (duration: 128.232516ms)","trace[1661133536] 'compare' (duration: 301.102766ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:56:13.971198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:13.541289Z","time spent":"429.84768ms","remote":"127.0.0.1:60938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1449,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:56:13.971436Z","caller":"traceutil/trace.go:171","msg":"trace[945065967] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"427.12816ms","start":"2026-08-10T15:56:13.5443Z","end":"2026-08-10T15:56:13.971428Z","steps":["trace[945065967] 'read index received' (duration: 125.16589ms)","trace[945065967] 'applied index is now lower than readState.Index' (duration: 301.96116ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:56:14.287685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.608821ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-10T15:56:14.287905Z","caller":"traceutil/trace.go:171","msg":"trace[273760822] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"277.629284ms","start":"2026-08-10T15:56:14.010257Z","end":"2026-08-10T15:56:14.287886Z","steps":["trace[273760822] 'read index received' (duration: 66.131µs)","trace[273760822] 'applied index is now lower than readState.Index' (duration: 277.561513ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:56:14.288072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.806527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T15:56:14.288036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.709241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-whjvs\" ","response":"range_response_count:1 size:5072"} {"level":"info","ts":"2026-08-10T15:56:14.288179Z","caller":"traceutil/trace.go:171","msg":"trace[192306430] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-whjvs; range_end:; response_count:1; response_revision:937; }","duration":"743.900334ms","start":"2026-08-10T15:56:13.54425Z","end":"2026-08-10T15:56:14.28815Z","steps":["trace[192306430] 'agreement among raft nodes before linearized reading' (duration: 427.205701ms)","trace[192306430] 'range keys from in-memory index tree' (duration: 316.368918ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:56:14.28826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:13.544235Z","time spent":"744.011485ms","remote":"127.0.0.1:60840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5095,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-whjvs\" "} {"level":"warn","ts":"2026-08-10T15:56:14.288304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.409726ms","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-08-10T15:56:14.288112Z","caller":"traceutil/trace.go:171","msg":"trace[319499480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"277.910788ms","start":"2026-08-10T15:56:14.010193Z","end":"2026-08-10T15:56:14.288104Z","steps":["trace[319499480] 'agreement among raft nodes before linearized reading' (duration: 277.815107ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:14.288334Z","caller":"traceutil/trace.go:171","msg":"trace[985144095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:937; }","duration":"137.495737ms","start":"2026-08-10T15:56:14.150832Z","end":"2026-08-10T15:56:14.288327Z","steps":["trace[985144095] 'agreement among raft nodes before linearized reading' (duration: 137.323475ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:14.652376Z","caller":"traceutil/trace.go:171","msg":"trace[1864696261] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"362.215145ms","start":"2026-08-10T15:56:14.290136Z","end":"2026-08-10T15:56:14.652351Z","steps":["trace[1864696261] 'process raft request' (duration: 362.064843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:14.652551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:14.290098Z","time spent":"362.370898ms","remote":"127.0.0.1:60840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:56:14.806588Z","caller":"traceutil/trace.go:171","msg":"trace[2126581930] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:975; }","duration":"445.808961ms","start":"2026-08-10T15:56:14.360761Z","end":"2026-08-10T15:56:14.80657Z","steps":["trace[2126581930] 'read index received' (duration: 291.438241ms)","trace[2126581930] 'applied index is now lower than readState.Index' (duration: 154.36989ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:56:14.806666Z","caller":"traceutil/trace.go:171","msg":"trace[1417651897] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"444.575562ms","start":"2026-08-10T15:56:14.362084Z","end":"2026-08-10T15:56:14.80666Z","steps":["trace[1417651897] 'process raft request' (duration: 444.452ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:14.806659Z","caller":"traceutil/trace.go:171","msg":"trace[32105812] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"513.273665ms","start":"2026-08-10T15:56:14.293349Z","end":"2026-08-10T15:56:14.806623Z","steps":["trace[32105812] 'process raft request' (duration: 481.909694ms)","trace[32105812] 'compare' (duration: 31.158958ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:56:14.806738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:14.362068Z","time spent":"444.614133ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:56:14.806837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:14.293308Z","time spent":"513.425717ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:56:14.806883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.105385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-10T15:56:14.806951Z","caller":"traceutil/trace.go:171","msg":"trace[1277874222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:940; }","duration":"446.221167ms","start":"2026-08-10T15:56:14.360712Z","end":"2026-08-10T15:56:14.806933Z","steps":["trace[1277874222] 'agreement among raft nodes before linearized reading' (duration: 445.984874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:14.80699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:14.360688Z","time spent":"446.294238ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-10T15:56:14.834486Z","caller":"traceutil/trace.go:171","msg":"trace[515950280] transaction","detail":"{read_only:false; number_of_response:1; response_revision:941; }","duration":"179.220864ms","start":"2026-08-10T15:56:14.655242Z","end":"2026-08-10T15:56:14.834463Z","steps":["trace[515950280] 'process raft request' (duration: 179.057702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:14.834588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.600654ms","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-08-10T15:56:14.834658Z","caller":"traceutil/trace.go:171","msg":"trace[594595498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:941; }","duration":"472.714456ms","start":"2026-08-10T15:56:14.361926Z","end":"2026-08-10T15:56:14.83464Z","steps":["trace[594595498] 'agreement among raft nodes before linearized reading' (duration: 472.544153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:14.834696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:14.361909Z","time spent":"472.779477ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-10T15:56:14.834591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.754441ms","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-08-10T15:56:14.834783Z","caller":"traceutil/trace.go:171","msg":"trace[1974257119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:941; }","duration":"473.964544ms","start":"2026-08-10T15:56:14.360802Z","end":"2026-08-10T15:56:14.834766Z","steps":["trace[1974257119] 'agreement among raft nodes before linearized reading' (duration: 473.626709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:14.834828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:14.360797Z","time spent":"474.020844ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-10T15:56:15.238803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.042801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:56:15.238921Z","caller":"traceutil/trace.go:171","msg":"trace[688828938] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"398.92572ms","start":"2026-08-10T15:56:14.839976Z","end":"2026-08-10T15:56:15.238901Z","steps":["trace[688828938] 'process raft request' (duration: 255.704087ms)","trace[688828938] 'compare' (duration: 142.925189ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:56:15.238983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:14.839942Z","time spent":"399.012641ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:56:15.656019Z","caller":"traceutil/trace.go:171","msg":"trace[1174774472] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:980; }","duration":"621.209356ms","start":"2026-08-10T15:56:15.034789Z","end":"2026-08-10T15:56:15.655999Z","steps":["trace[1174774472] 'read index received' (duration: 60.79433ms)","trace[1174774472] 'applied index is now lower than readState.Index' (duration: 560.414196ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:56:15.656095Z","caller":"traceutil/trace.go:171","msg":"trace[1423229420] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"815.811019ms","start":"2026-08-10T15:56:14.840223Z","end":"2026-08-10T15:56:15.656034Z","steps":["trace[1423229420] 'process raft request' (duration: 743.723869ms)","trace[1423229420] 'compare' (duration: 71.827726ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:56:15.656287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.897913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-10T15:56:15.656287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:14.840199Z","time spent":"815.984522ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:56:15.656155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.374079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:56:15.656341Z","caller":"traceutil/trace.go:171","msg":"trace[2112439349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"621.633373ms","start":"2026-08-10T15:56:15.034704Z","end":"2026-08-10T15:56:15.656337Z","steps":["trace[2112439349] 'agreement among raft nodes before linearized reading' (duration: 621.40931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:15.656358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:15.034673Z","time spent":"621.680343ms","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-10T15:56:15.656314Z","caller":"traceutil/trace.go:171","msg":"trace[720654128] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:944; }","duration":"435.985365ms","start":"2026-08-10T15:56:15.220323Z","end":"2026-08-10T15:56:15.656309Z","steps":["trace[720654128] 'agreement among raft nodes before linearized reading' (duration: 435.847343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:15.656471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:15.220289Z","time spent":"436.164398ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-10T15:56:23.053459Z","caller":"traceutil/trace.go:171","msg":"trace[623643478] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1011; }","duration":"203.87811ms","start":"2026-08-10T15:56:22.849553Z","end":"2026-08-10T15:56:23.053431Z","steps":["trace[623643478] 'read index received' (duration: 203.594896ms)","trace[623643478] 'applied index is now lower than readState.Index' (duration: 281.404µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:56:23.053725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.924748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-10T15:56:23.05384Z","caller":"traceutil/trace.go:171","msg":"trace[1617635602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:973; }","duration":"177.073479ms","start":"2026-08-10T15:56:22.876745Z","end":"2026-08-10T15:56:23.053819Z","steps":["trace[1617635602] 'agreement among raft nodes before linearized reading' (duration: 176.867556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:23.053851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.248735ms","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-08-10T15:56:23.05379Z","caller":"traceutil/trace.go:171","msg":"trace[310487964] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"217.559328ms","start":"2026-08-10T15:56:22.835961Z","end":"2026-08-10T15:56:23.05352Z","steps":["trace[310487964] 'process raft request' (duration: 217.278904ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:23.053967Z","caller":"traceutil/trace.go:171","msg":"trace[1872678681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"204.414338ms","start":"2026-08-10T15:56:22.849504Z","end":"2026-08-10T15:56:23.053919Z","steps":["trace[1872678681] 'agreement among raft nodes before linearized reading' (duration: 204.069723ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:23.348694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.950242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:56:23.348809Z","caller":"traceutil/trace.go:171","msg":"trace[1138175211] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"123.168572ms","start":"2026-08-10T15:56:23.225619Z","end":"2026-08-10T15:56:23.348787Z","steps":["trace[1138175211] 'process raft request' (duration: 15.055177ms)","trace[1138175211] 'compare' (duration: 107.829371ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:56:23.550509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.271033ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-10T15:56:23.550884Z","caller":"traceutil/trace.go:171","msg":"trace[1272622180] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"198.206878ms","start":"2026-08-10T15:56:23.352657Z","end":"2026-08-10T15:56:23.550864Z","steps":["trace[1272622180] 'process raft request' (duration: 197.967084ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:43.854805Z","caller":"traceutil/trace.go:171","msg":"trace[587051234] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"154.740254ms","start":"2026-08-10T15:56:43.700004Z","end":"2026-08-10T15:56:43.854744Z","steps":["trace[587051234] 'process raft request' (duration: 154.561002ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:43.869792Z","caller":"traceutil/trace.go:171","msg":"trace[1909889514] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"128.273797ms","start":"2026-08-10T15:56:43.741488Z","end":"2026-08-10T15:56:43.869762Z","steps":["trace[1909889514] 'process raft request' (duration: 128.057745ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:57:00.139935Z","caller":"traceutil/trace.go:171","msg":"trace[1865075761] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1145; }","duration":"136.39013ms","start":"2026-08-10T15:57:00.003522Z","end":"2026-08-10T15:57:00.139912Z","steps":["trace[1865075761] 'read index received' (duration: 136.172048ms)","trace[1865075761] 'applied index is now lower than readState.Index' (duration: 216.872µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:57:00.14006Z","caller":"traceutil/trace.go:171","msg":"trace[1807440926] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"168.514134ms","start":"2026-08-10T15:56:59.971535Z","end":"2026-08-10T15:57:00.14005Z","steps":["trace[1807440926] 'process raft request' (duration: 168.19689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:57:00.140387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.842607ms","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-08-10T15:57:00.140463Z","caller":"traceutil/trace.go:171","msg":"trace[111353505] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1099; }","duration":"136.965259ms","start":"2026-08-10T15:57:00.00348Z","end":"2026-08-10T15:57:00.140446Z","steps":["trace[111353505] 'agreement among raft nodes before linearized reading' (duration: 136.811337ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:57:00.39295Z","caller":"traceutil/trace.go:171","msg":"trace[1680651019] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"248.205428ms","start":"2026-08-10T15:57:00.144723Z","end":"2026-08-10T15:57:00.392929Z","steps":["trace[1680651019] 'process raft request' (duration: 248.087886ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:57:00.405212Z","caller":"traceutil/trace.go:171","msg":"trace[58314266] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"258.888904ms","start":"2026-08-10T15:57:00.146292Z","end":"2026-08-10T15:57:00.405181Z","steps":["trace[58314266] 'process raft request' (duration: 258.660841ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:57:04.628959Z","caller":"traceutil/trace.go:171","msg":"trace[119785536] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"136.84146ms","start":"2026-08-10T15:57:04.492087Z","end":"2026-08-10T15:57:04.628929Z","steps":["trace[119785536] 'process raft request' (duration: 90.44249ms)","trace[119785536] 'compare' (duration: 46.110717ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:57:04.629441Z","caller":"traceutil/trace.go:171","msg":"trace[260827147] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"130.187045ms","start":"2026-08-10T15:57:04.49922Z","end":"2026-08-10T15:57:04.629407Z","steps":["trace[260827147] 'process raft request' (duration: 130.094224ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:57:05.530969Z","caller":"traceutil/trace.go:171","msg":"trace[770377529] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"190.590158ms","start":"2026-08-10T15:57:05.340343Z","end":"2026-08-10T15:57:05.530933Z","steps":["trace[770377529] 'process raft request' (duration: 190.373065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:57:05.671666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.73174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:57:05.671845Z","caller":"traceutil/trace.go:171","msg":"trace[288801512] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"135.189295ms","start":"2026-08-10T15:57:05.536639Z","end":"2026-08-10T15:57:05.671828Z","steps":["trace[288801512] 'process raft request' (duration: 135.124904ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:57:05.671901Z","caller":"traceutil/trace.go:171","msg":"trace[692845364] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"136.711354ms","start":"2026-08-10T15:57:05.535152Z","end":"2026-08-10T15:57:05.671863Z","steps":["trace[692845364] 'process raft request' (duration: 34.705781ms)","trace[692845364] 'compare' (duration: 101.632509ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:57:07.661366Z","caller":"traceutil/trace.go:171","msg":"trace[43997665] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"125.025408ms","start":"2026-08-10T15:57:07.536313Z","end":"2026-08-10T15:57:07.661339Z","steps":["trace[43997665] 'process raft request' (duration: 124.875917ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:57:09.793367Z","caller":"traceutil/trace.go:171","msg":"trace[30461693] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1177; }","duration":"108.704686ms","start":"2026-08-10T15:57:09.684642Z","end":"2026-08-10T15:57:09.793347Z","steps":["trace[30461693] 'read index received' (duration: 108.516164ms)","trace[30461693] 'applied index is now lower than readState.Index' (duration: 187.762µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:57:09.793599Z","caller":"traceutil/trace.go:171","msg":"trace[1688166654] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"127.23825ms","start":"2026-08-10T15:57:09.666349Z","end":"2026-08-10T15:57:09.793587Z","steps":["trace[1688166654] 'process raft request' (duration: 126.865424ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:57:09.793855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.197933ms","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-08-10T15:57:09.793897Z","caller":"traceutil/trace.go:171","msg":"trace[726822480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1129; }","duration":"109.289283ms","start":"2026-08-10T15:57:09.684598Z","end":"2026-08-10T15:57:09.793887Z","steps":["trace[726822480] 'agreement among raft nodes before linearized reading' (duration: 109.172352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:57:09.794072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.15786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-10T15:57:09.794107Z","caller":"traceutil/trace.go:171","msg":"trace[274709749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1129; }","duration":"108.225901ms","start":"2026-08-10T15:57:09.685873Z","end":"2026-08-10T15:57:09.794099Z","steps":["trace[274709749] 'agreement among raft nodes before linearized reading' (duration: 108.1428ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:57:10.788738Z","caller":"traceutil/trace.go:171","msg":"trace[1006506533] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"233.725632ms","start":"2026-08-10T15:57:10.554989Z","end":"2026-08-10T15:57:10.788715Z","steps":["trace[1006506533] 'process raft request' (duration: 213.839403ms)","trace[1006506533] 'compare' (duration: 19.245701ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:57:34.332668Z","caller":"traceutil/trace.go:171","msg":"trace[1697771246] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"194.615302ms","start":"2026-08-10T15:57:34.137988Z","end":"2026-08-10T15:57:34.332604Z","steps":["trace[1697771246] 'process raft request' (duration: 193.711332ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:57:34.338438Z","caller":"traceutil/trace.go:171","msg":"trace[1350748828] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"198.5504ms","start":"2026-08-10T15:57:34.139876Z","end":"2026-08-10T15:57:34.338427Z","steps":["trace[1350748828] 'process raft request' (duration: 198.495479ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:57:35.961405Z","caller":"traceutil/trace.go:171","msg":"trace[690952960] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"112.558488ms","start":"2026-08-10T15:57:35.848814Z","end":"2026-08-10T15:57:35.961373Z","steps":["trace[690952960] 'process raft request' (duration: 112.358586ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:57:36.683497Z","caller":"traceutil/trace.go:171","msg":"trace[951326137] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"134.217523ms","start":"2026-08-10T15:57:36.54925Z","end":"2026-08-10T15:57:36.683468Z","steps":["trace[951326137] 'process raft request' (duration: 133.699617ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:57:53.653473Z","caller":"traceutil/trace.go:171","msg":"trace[184285205] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"241.613071ms","start":"2026-08-10T15:57:53.411834Z","end":"2026-08-10T15:57:53.653447Z","steps":["trace[184285205] 'read index received' (duration: 241.460819ms)","trace[184285205] 'applied index is now lower than readState.Index' (duration: 151.062µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:57:53.653769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.919114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-10T15:57:53.653806Z","caller":"traceutil/trace.go:171","msg":"trace[1266992519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1306; }","duration":"242.021406ms","start":"2026-08-10T15:57:53.411773Z","end":"2026-08-10T15:57:53.653795Z","steps":["trace[1266992519] 'agreement among raft nodes before linearized reading' (duration: 241.856754ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:57:53.653893Z","caller":"traceutil/trace.go:171","msg":"trace[1894826326] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"336.834622ms","start":"2026-08-10T15:57:53.317024Z","end":"2026-08-10T15:57:53.653858Z","steps":["trace[1894826326] 'process raft request' (duration: 336.236846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:57:53.654129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:57:53.317002Z","time spent":"337.015324ms","remote":"127.0.0.1:60830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5719,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:57:57.057889Z","caller":"traceutil/trace.go:171","msg":"trace[1351546809] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1377; }","duration":"110.92154ms","start":"2026-08-10T15:57:56.946947Z","end":"2026-08-10T15:57:57.057869Z","steps":["trace[1351546809] 'read index received' (duration: 110.753167ms)","trace[1351546809] 'applied index is now lower than readState.Index' (duration: 167.423µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:57:57.057971Z","caller":"traceutil/trace.go:171","msg":"trace[1499216343] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"165.761469ms","start":"2026-08-10T15:57:56.892185Z","end":"2026-08-10T15:57:57.057946Z","steps":["trace[1499216343] 'process raft request' (duration: 165.559016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:57:57.058068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.108112ms","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-08-10T15:57:57.058114Z","caller":"traceutil/trace.go:171","msg":"trace[1480377964] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1318; }","duration":"111.172313ms","start":"2026-08-10T15:57:56.946927Z","end":"2026-08-10T15:57:57.0581Z","steps":["trace[1480377964] 'agreement among raft nodes before linearized reading' (duration: 111.088922ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:57:57.058145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.968641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:57:57.058203Z","caller":"traceutil/trace.go:171","msg":"trace[1227229575] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1318; }","duration":"105.042062ms","start":"2026-08-10T15:57:56.953149Z","end":"2026-08-10T15:57:57.058191Z","steps":["trace[1227229575] 'agreement among raft nodes before linearized reading' (duration: 104.94264ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:02.741027Z","caller":"traceutil/trace.go:171","msg":"trace[569684111] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"101.039498ms","start":"2026-08-10T15:58:02.63996Z","end":"2026-08-10T15:58:02.741Z","steps":["trace[569684111] 'process raft request' (duration: 100.865096ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:04.313273Z","caller":"traceutil/trace.go:171","msg":"trace[423884223] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"506.711445ms","start":"2026-08-10T15:58:03.806511Z","end":"2026-08-10T15:58:04.313222Z","steps":["trace[423884223] 'process raft request' (duration: 506.066598ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:04.312836Z","caller":"traceutil/trace.go:171","msg":"trace[541588682] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1407; }","duration":"324.208009ms","start":"2026-08-10T15:58:03.988592Z","end":"2026-08-10T15:58:04.3128Z","steps":["trace[541588682] 'read index received' (duration: 323.847765ms)","trace[541588682] 'applied index is now lower than readState.Index' (duration: 354.634µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:58:04.3136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.967038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T15:58:04.313719Z","caller":"traceutil/trace.go:171","msg":"trace[425438483] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1346; }","duration":"325.15484ms","start":"2026-08-10T15:58:03.988538Z","end":"2026-08-10T15:58:04.313693Z","steps":["trace[425438483] 'agreement among raft nodes before linearized reading' (duration: 324.848206ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:04.313805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:03.988516Z","time spent":"325.269752ms","remote":"127.0.0.1:32934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":114,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-08-10T15:58:04.313922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.282018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:58:04.314075Z","caller":"traceutil/trace.go:171","msg":"trace[1079341931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1346; }","duration":"303.571822ms","start":"2026-08-10T15:58:04.010407Z","end":"2026-08-10T15:58:04.313979Z","steps":["trace[1079341931] 'agreement among raft nodes before linearized reading' (duration: 303.264828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:04.314162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:04.010387Z","time spent":"303.760864ms","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-10T15:58:04.314678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:03.806489Z","time spent":"506.895718ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:58:11.924624Z","caller":"traceutil/trace.go:171","msg":"trace[1085380418] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"100.879966ms","start":"2026-08-10T15:58:11.823715Z","end":"2026-08-10T15:58:11.924594Z","steps":["trace[1085380418] 'process raft request' (duration: 100.509301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:12.104513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.614238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" limit:500 ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-10T15:58:12.104652Z","caller":"traceutil/trace.go:171","msg":"trace[759772185] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:1; response_revision:1376; }","duration":"118.809741ms","start":"2026-08-10T15:58:11.985822Z","end":"2026-08-10T15:58:12.104632Z","steps":["trace[759772185] 'range keys from in-memory index tree' (duration: 118.380146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:12.805057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.090632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:1 size:1107"} {"level":"info","ts":"2026-08-10T15:58:12.805154Z","caller":"traceutil/trace.go:171","msg":"trace[1411695365] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:1; response_revision:1377; }","duration":"271.222514ms","start":"2026-08-10T15:58:12.533899Z","end":"2026-08-10T15:58:12.805122Z","steps":["trace[1411695365] 'range keys from in-memory index tree' (duration: 270.90448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:12.80523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.122963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role\" ","response":"range_response_count:1 size:1944"} {"level":"info","ts":"2026-08-10T15:58:12.805309Z","caller":"traceutil/trace.go:171","msg":"trace[176858654] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role; range_end:; response_count:1; response_revision:1377; }","duration":"258.231474ms","start":"2026-08-10T15:58:12.547056Z","end":"2026-08-10T15:58:12.805287Z","steps":["trace[176858654] 'range keys from in-memory index tree' (duration: 257.959832ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:12.805586Z","caller":"traceutil/trace.go:171","msg":"trace[140306797] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"228.201601ms","start":"2026-08-10T15:58:12.577365Z","end":"2026-08-10T15:58:12.805567Z","steps":["trace[140306797] 'process raft request' (duration: 195.959541ms)","trace[140306797] 'compare' (duration: 31.705924ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:12.996169Z","caller":"traceutil/trace.go:171","msg":"trace[430147062] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"185.813797ms","start":"2026-08-10T15:58:12.810324Z","end":"2026-08-10T15:58:12.996138Z","steps":["trace[430147062] 'process raft request' (duration: 115.31998ms)","trace[430147062] 'compare' (duration: 70.110772ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:58:12.996441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.534556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-10T15:58:12.996486Z","caller":"traceutil/trace.go:171","msg":"trace[110391341] linearizableReadLoop","detail":"{readStateIndex:1442; appliedIndex:1441; }","duration":"159.165832ms","start":"2026-08-10T15:58:12.836883Z","end":"2026-08-10T15:58:12.996049Z","steps":["trace[110391341] 'read index received' (duration: 88.805197ms)","trace[110391341] 'applied index is now lower than readState.Index' (duration: 70.358755ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:12.996527Z","caller":"traceutil/trace.go:171","msg":"trace[673665648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1379; }","duration":"159.703438ms","start":"2026-08-10T15:58:12.836805Z","end":"2026-08-10T15:58:12.996509Z","steps":["trace[673665648] 'agreement among raft nodes before linearized reading' (duration: 159.439575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:12.99673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.768369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kubeadm:get-nodes\" ","response":"range_response_count:1 size:305"} {"level":"info","ts":"2026-08-10T15:58:12.996782Z","caller":"traceutil/trace.go:171","msg":"trace[383793894] range","detail":"{range_begin:/registry/clusterroles/kubeadm:get-nodes; range_end:; response_count:1; response_revision:1379; }","duration":"104.88525ms","start":"2026-08-10T15:58:12.891884Z","end":"2026-08-10T15:58:12.996769Z","steps":["trace[383793894] 'agreement among raft nodes before linearized reading' (duration: 104.751358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:12.99699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.972443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role\" ","response":"range_response_count:1 size:1944"} {"level":"info","ts":"2026-08-10T15:58:12.99709Z","caller":"traceutil/trace.go:171","msg":"trace[189441034] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role; range_end:; response_count:1; response_revision:1379; }","duration":"113.126744ms","start":"2026-08-10T15:58:12.88394Z","end":"2026-08-10T15:58:12.997066Z","steps":["trace[189441034] 'agreement among raft nodes before linearized reading' (duration: 112.78642ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:13.494643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.300231ms","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-08-10T15:58:13.494735Z","caller":"traceutil/trace.go:171","msg":"trace[540579989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1380; }","duration":"235.420742ms","start":"2026-08-10T15:58:13.259293Z","end":"2026-08-10T15:58:13.494714Z","steps":["trace[540579989] 'range keys from in-memory index tree' (duration: 234.962367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:13.494405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.222843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:1 size:840"} {"level":"warn","ts":"2026-08-10T15:58:13.494822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.980744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/local-path-provisioner\" ","response":"range_response_count:1 size:1106"} {"level":"info","ts":"2026-08-10T15:58:13.494872Z","caller":"traceutil/trace.go:171","msg":"trace[1116732572] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:1; response_revision:1380; }","duration":"321.76146ms","start":"2026-08-10T15:58:13.173079Z","end":"2026-08-10T15:58:13.494841Z","steps":["trace[1116732572] 'range keys from in-memory index tree' (duration: 321.052801ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:13.494885Z","caller":"traceutil/trace.go:171","msg":"trace[439007913] range","detail":"{range_begin:/registry/clusterroles/local-path-provisioner; range_end:; response_count:1; response_revision:1380; }","duration":"373.088796ms","start":"2026-08-10T15:58:13.12178Z","end":"2026-08-10T15:58:13.494869Z","steps":["trace[439007913] 'range keys from in-memory index tree' (duration: 372.735052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:13.494934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:13.173053Z","time spent":"321.866441ms","remote":"127.0.0.1:60994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":863,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-08-10T15:58:13.49495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:13.121722Z","time spent":"373.218587ms","remote":"127.0.0.1:60994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1129,"request content":"key:\"/registry/clusterroles/local-path-provisioner\" "} {"level":"warn","ts":"2026-08-10T15:58:13.97135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.775236ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-10T15:58:13.97217Z","caller":"traceutil/trace.go:171","msg":"trace[2000723197] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"470.103465ms","start":"2026-08-10T15:58:13.502044Z","end":"2026-08-10T15:58:13.972148Z","steps":["trace[2000723197] 'process raft request' (duration: 469.508518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:13.972312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:13.502011Z","time spent":"470.223716ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:58:14.100857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.596959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:58:14.100995Z","caller":"traceutil/trace.go:171","msg":"trace[1619720399] linearizableReadLoop","detail":"{readStateIndex:1446; appliedIndex:1443; }","duration":"526.267096ms","start":"2026-08-10T15:58:13.574706Z","end":"2026-08-10T15:58:14.100973Z","steps":["trace[1619720399] 'read index received' (duration: 395.076218ms)","trace[1619720399] 'applied index is now lower than readState.Index' (duration: 131.189048ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:14.101152Z","caller":"traceutil/trace.go:171","msg":"trace[1472073281] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"598.636344ms","start":"2026-08-10T15:58:13.502497Z","end":"2026-08-10T15:58:14.101134Z","steps":["trace[1472073281] 'process raft request' (duration: 469.63544ms)","trace[1472073281] 'compare' (duration: 128.408267ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:58:14.101248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:13.502471Z","time spent":"598.728484ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:58:14.101476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.401304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:1 size:840"} {"level":"warn","ts":"2026-08-10T15:58:14.101465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.750592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/local-path-provisioner\" ","response":"range_response_count:1 size:1106"} {"level":"info","ts":"2026-08-10T15:58:14.101931Z","caller":"traceutil/trace.go:171","msg":"trace[2131651314] range","detail":"{range_begin:/registry/clusterroles/local-path-provisioner; range_end:; response_count:1; response_revision:1382; }","duration":"527.255787ms","start":"2026-08-10T15:58:13.574653Z","end":"2026-08-10T15:58:14.101909Z","steps":["trace[2131651314] 'agreement among raft nodes before linearized reading' (duration: 526.59041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:14.102022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:13.574627Z","time spent":"527.375449ms","remote":"127.0.0.1:60994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1129,"request content":"key:\"/registry/clusterroles/local-path-provisioner\" "} {"level":"info","ts":"2026-08-10T15:58:14.101552Z","caller":"traceutil/trace.go:171","msg":"trace[1180946903] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:1; response_revision:1382; }","duration":"523.523075ms","start":"2026-08-10T15:58:13.578012Z","end":"2026-08-10T15:58:14.101535Z","steps":["trace[1180946903] 'agreement among raft nodes before linearized reading' (duration: 523.156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:14.102331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:13.57799Z","time spent":"524.200112ms","remote":"127.0.0.1:60994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":863,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-08-10T15:58:24.294803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.608036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-08-10T15:58:24.294923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.650291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-public\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-10T15:58:24.295011Z","caller":"traceutil/trace.go:171","msg":"trace[1807108195] range","detail":"{range_begin:/registry/namespaces/kube-public; range_end:; response_count:1; response_revision:1414; }","duration":"145.799222ms","start":"2026-08-10T15:58:24.149192Z","end":"2026-08-10T15:58:24.294992Z","steps":["trace[1807108195] 'range keys from in-memory index tree' (duration: 145.530519ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:24.295027Z","caller":"traceutil/trace.go:171","msg":"trace[1500870103] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:1414; }","duration":"142.816178ms","start":"2026-08-10T15:58:24.152142Z","end":"2026-08-10T15:58:24.294958Z","steps":["trace[1500870103] 'range keys from in-memory index tree' (duration: 142.519775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:24.294917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.965186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-10T15:58:24.295206Z","caller":"traceutil/trace.go:171","msg":"trace[217386923] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1414; }","duration":"125.312649ms","start":"2026-08-10T15:58:24.169878Z","end":"2026-08-10T15:58:24.29519Z","steps":["trace[217386923] 'range keys from in-memory index tree' (duration: 124.766653ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:24.80823Z","caller":"traceutil/trace.go:171","msg":"trace[467587528] linearizableReadLoop","detail":"{readStateIndex:1482; appliedIndex:1481; }","duration":"384.054172ms","start":"2026-08-10T15:58:24.42415Z","end":"2026-08-10T15:58:24.808204Z","steps":["trace[467587528] 'read index received' (duration: 381.771686ms)","trace[467587528] 'applied index is now lower than readState.Index' (duration: 2.281466ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:24.808519Z","caller":"traceutil/trace.go:171","msg":"trace[146468644] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"444.718689ms","start":"2026-08-10T15:58:24.363789Z","end":"2026-08-10T15:58:24.808507Z","steps":["trace[146468644] 'process raft request' (duration: 442.15242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:24.808637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:24.363766Z","time spent":"444.79558ms","remote":"127.0.0.1:60830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5898,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:58:24.808844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.104632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-10T15:58:24.808914Z","caller":"traceutil/trace.go:171","msg":"trace[1177875794] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1416; }","duration":"211.228364ms","start":"2026-08-10T15:58:24.597668Z","end":"2026-08-10T15:58:24.808896Z","steps":["trace[1177875794] 'agreement among raft nodes before linearized reading' (duration: 211.007892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:24.809154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.986732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/local-path-storage\" ","response":"range_response_count:1 size:404"} {"level":"info","ts":"2026-08-10T15:58:24.809225Z","caller":"traceutil/trace.go:171","msg":"trace[187783498] range","detail":"{range_begin:/registry/namespaces/local-path-storage; range_end:; response_count:1; response_revision:1416; }","duration":"385.062413ms","start":"2026-08-10T15:58:24.424144Z","end":"2026-08-10T15:58:24.809207Z","steps":["trace[187783498] 'agreement among raft nodes before linearized reading' (duration: 384.83434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:24.809269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:24.424096Z","time spent":"385.164614ms","remote":"127.0.0.1:60780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":427,"request content":"key:\"/registry/namespaces/local-path-storage\" "} {"level":"warn","ts":"2026-08-10T15:58:26.361722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.792046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/envoy-gateway-system/\" range_end:\"/registry/daemonsets/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T15:58:26.361751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.758751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/envoy-gateway-system/\" range_end:\"/registry/events/envoy-gateway-system0\" limit:500 ","response":"range_response_count:15 size:12285"} {"level":"info","ts":"2026-08-10T15:58:26.361782Z","caller":"traceutil/trace.go:171","msg":"trace[1530450669] range","detail":"{range_begin:/registry/daemonsets/envoy-gateway-system/; range_end:/registry/daemonsets/envoy-gateway-system0; response_count:0; response_revision:1421; }","duration":"119.908527ms","start":"2026-08-10T15:58:26.241859Z","end":"2026-08-10T15:58:26.361768Z","steps":["trace[1530450669] 'range keys from in-memory index tree' (duration: 119.692035ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:26.361809Z","caller":"traceutil/trace.go:171","msg":"trace[105630355] range","detail":"{range_begin:/registry/events/envoy-gateway-system/; range_end:/registry/events/envoy-gateway-system0; response_count:15; response_revision:1421; }","duration":"123.861231ms","start":"2026-08-10T15:58:26.237934Z","end":"2026-08-10T15:58:26.361795Z","steps":["trace[105630355] 'range keys from in-memory index tree' (duration: 123.588118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:27.526991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.088406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:58:27.527071Z","caller":"traceutil/trace.go:171","msg":"trace[2087506119] linearizableReadLoop","detail":"{readStateIndex:1490; appliedIndex:1489; }","duration":"573.594255ms","start":"2026-08-10T15:58:26.953464Z","end":"2026-08-10T15:58:27.527058Z","steps":["trace[2087506119] 'read index received' (duration: 255.369738ms)","trace[2087506119] 'applied index is now lower than readState.Index' (duration: 318.223207ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:27.527123Z","caller":"traceutil/trace.go:171","msg":"trace[1573847047] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"613.506997ms","start":"2026-08-10T15:58:26.913609Z","end":"2026-08-10T15:58:27.527116Z","steps":["trace[1573847047] 'process raft request' (duration: 295.212739ms)","trace[1573847047] 'compare' (duration: 317.827953ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:58:27.527173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:26.913574Z","time spent":"613.577827ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:58:27.527347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.887708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/\" range_end:\"/registry/pods/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:58:27.527388Z","caller":"traceutil/trace.go:171","msg":"trace[2029019827] range","detail":"{range_begin:/registry/pods/default/; range_end:/registry/pods/default0; response_count:0; response_revision:1424; }","duration":"573.94445ms","start":"2026-08-10T15:58:26.953419Z","end":"2026-08-10T15:58:27.527364Z","steps":["trace[2029019827] 'agreement among raft nodes before linearized reading' (duration: 573.894419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:27.52741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:26.9534Z","time spent":"574.00492ms","remote":"127.0.0.1:60840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/pods/default/\" range_end:\"/registry/pods/default0\" limit:500 "} {"level":"warn","ts":"2026-08-10T15:58:27.540606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.333075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T15:58:27.540613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.392073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-10T15:58:27.540634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.032668ms","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-08-10T15:58:27.540673Z","caller":"traceutil/trace.go:171","msg":"trace[1232476649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1425; }","duration":"359.501204ms","start":"2026-08-10T15:58:27.181157Z","end":"2026-08-10T15:58:27.540658Z","steps":["trace[1232476649] 'agreement among raft nodes before linearized reading' (duration: 359.348962ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:27.540693Z","caller":"traceutil/trace.go:171","msg":"trace[2030974728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1425; }","duration":"336.13964ms","start":"2026-08-10T15:58:27.20454Z","end":"2026-08-10T15:58:27.540679Z","steps":["trace[2030974728] 'agreement among raft nodes before linearized reading' (duration: 335.949528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:27.540707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:27.181136Z","time spent":"359.565344ms","remote":"127.0.0.1:60930","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-08-10T15:58:27.540731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:27.204515Z","time spent":"336.209881ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-10T15:58:27.540832Z","caller":"traceutil/trace.go:171","msg":"trace[1503420860] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"401.334027ms","start":"2026-08-10T15:58:27.139488Z","end":"2026-08-10T15:58:27.540822Z","steps":["trace[1503420860] 'process raft request' (duration: 400.794461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:27.54095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:27.13946Z","time spent":"401.425947ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:58:27.540668Z","caller":"traceutil/trace.go:171","msg":"trace[1048333275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1425; }","duration":"530.427356ms","start":"2026-08-10T15:58:27.010224Z","end":"2026-08-10T15:58:27.540651Z","steps":["trace[1048333275] 'agreement among raft nodes before linearized reading' (duration: 530.321705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:27.54133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.387316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-rhqdt\" ","response":"range_response_count:1 size:5487"} {"level":"info","ts":"2026-08-10T15:58:27.541367Z","caller":"traceutil/trace.go:171","msg":"trace[475438875] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-rhqdt; range_end:; response_count:1; response_revision:1425; }","duration":"538.459988ms","start":"2026-08-10T15:58:27.002896Z","end":"2026-08-10T15:58:27.541356Z","steps":["trace[475438875] 'agreement among raft nodes before linearized reading' (duration: 537.556488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:27.541388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:27.002877Z","time spent":"538.506628ms","remote":"127.0.0.1:60840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5510,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-rhqdt\" "} {"level":"warn","ts":"2026-08-10T15:58:27.541336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:27.010207Z","time spent":"531.106744ms","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-10T15:58:28.335496Z","caller":"traceutil/trace.go:171","msg":"trace[1436076183] linearizableReadLoop","detail":"{readStateIndex:1494; appliedIndex:1493; }","duration":"175.342611ms","start":"2026-08-10T15:58:28.16013Z","end":"2026-08-10T15:58:28.335473Z","steps":["trace[1436076183] 'read index received' (duration: 175.132759ms)","trace[1436076183] 'applied index is now lower than readState.Index' (duration: 206.642µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:28.335631Z","caller":"traceutil/trace.go:171","msg":"trace[1216588461] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"181.065506ms","start":"2026-08-10T15:58:28.154514Z","end":"2026-08-10T15:58:28.33558Z","steps":["trace[1216588461] 'process raft request' (duration: 180.773023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:28.335752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.800358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T15:58:28.335766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.603136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:58:28.335835Z","caller":"traceutil/trace.go:171","msg":"trace[1100444088] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1428; }","duration":"130.930479ms","start":"2026-08-10T15:58:28.204887Z","end":"2026-08-10T15:58:28.335817Z","steps":["trace[1100444088] 'agreement among raft nodes before linearized reading' (duration: 130.777667ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:28.335859Z","caller":"traceutil/trace.go:171","msg":"trace[1564640930] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:1428; }","duration":"161.742227ms","start":"2026-08-10T15:58:28.174096Z","end":"2026-08-10T15:58:28.335838Z","steps":["trace[1564640930] 'agreement among raft nodes before linearized reading' (duration: 161.563915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:28.335776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.962912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/envoy-gateway-system/\" range_end:\"/registry/statefulsets/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:58:28.335925Z","caller":"traceutil/trace.go:171","msg":"trace[14941521] range","detail":"{range_begin:/registry/statefulsets/envoy-gateway-system/; range_end:/registry/statefulsets/envoy-gateway-system0; response_count:0; response_revision:1428; }","duration":"133.178255ms","start":"2026-08-10T15:58:28.202732Z","end":"2026-08-10T15:58:28.33591Z","steps":["trace[14941521] 'agreement among raft nodes before linearized reading' (duration: 132.981913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:28.335972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.923505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-role\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-08-10T15:58:28.336012Z","caller":"traceutil/trace.go:171","msg":"trace[1900289909] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-role; range_end:; response_count:1; response_revision:1428; }","duration":"158.987806ms","start":"2026-08-10T15:58:28.177012Z","end":"2026-08-10T15:58:28.336Z","steps":["trace[1900289909] 'agreement among raft nodes before linearized reading' (duration: 158.811694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:28.336518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.390772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" ","response":"range_response_count:1 size:1304"} {"level":"info","ts":"2026-08-10T15:58:28.336566Z","caller":"traceutil/trace.go:171","msg":"trace[739875077] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; range_end:; response_count:1; response_revision:1428; }","duration":"176.481314ms","start":"2026-08-10T15:58:28.160072Z","end":"2026-08-10T15:58:28.336553Z","steps":["trace[739875077] 'agreement among raft nodes before linearized reading' (duration: 176.297062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:28.550974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.536901ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-10T15:58:28.551123Z","caller":"traceutil/trace.go:171","msg":"trace[589980536] linearizableReadLoop","detail":"{readStateIndex:1495; appliedIndex:1494; }","duration":"162.687478ms","start":"2026-08-10T15:58:28.388416Z","end":"2026-08-10T15:58:28.551103Z","steps":["trace[589980536] 'read index received' (duration: 26.924534ms)","trace[589980536] 'applied index is now lower than readState.Index' (duration: 135.760844ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:58:28.55143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.002907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/\" range_end:\"/registry/serviceaccounts/default0\" limit:500 ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-08-10T15:58:28.55147Z","caller":"traceutil/trace.go:171","msg":"trace[588474091] range","detail":"{range_begin:/registry/serviceaccounts/default/; range_end:/registry/serviceaccounts/default0; response_count:1; response_revision:1428; }","duration":"136.107768ms","start":"2026-08-10T15:58:28.415352Z","end":"2026-08-10T15:58:28.551459Z","steps":["trace[588474091] 'agreement among raft nodes before linearized reading' (duration: 135.872656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:28.551447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.102884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/\" range_end:\"/registry/services/specs/default0\" limit:500 ","response":"range_response_count:1 size:704"} {"level":"warn","ts":"2026-08-10T15:58:28.551472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.957651ms","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-08-10T15:58:28.551497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.355647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-role\" ","response":"range_response_count:1 size:1131"} {"level":"warn","ts":"2026-08-10T15:58:28.55154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.963733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" ","response":"range_response_count:1 size:1304"} {"level":"info","ts":"2026-08-10T15:58:28.551551Z","caller":"traceutil/trace.go:171","msg":"trace[523318475] range","detail":"{range_begin:/registry/services/specs/default/; range_end:/registry/services/specs/default0; response_count:1; response_revision:1428; }","duration":"125.236775ms","start":"2026-08-10T15:58:28.426289Z","end":"2026-08-10T15:58:28.551526Z","steps":["trace[523318475] 'agreement among raft nodes before linearized reading' (duration: 125.014982ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:28.551557Z","caller":"traceutil/trace.go:171","msg":"trace[453511763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1428; }","duration":"163.161123ms","start":"2026-08-10T15:58:28.388376Z","end":"2026-08-10T15:58:28.551537Z","steps":["trace[453511763] 'agreement among raft nodes before linearized reading' (duration: 162.839169ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:28.551591Z","caller":"traceutil/trace.go:171","msg":"trace[802262202] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-role; range_end:; response_count:1; response_revision:1428; }","duration":"118.488218ms","start":"2026-08-10T15:58:28.433082Z","end":"2026-08-10T15:58:28.55157Z","steps":["trace[802262202] 'agreement among raft nodes before linearized reading' (duration: 118.175905ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:28.551598Z","caller":"traceutil/trace.go:171","msg":"trace[1832450011] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; range_end:; response_count:1; response_revision:1428; }","duration":"133.055024ms","start":"2026-08-10T15:58:28.418528Z","end":"2026-08-10T15:58:28.551583Z","steps":["trace[1832450011] 'agreement among raft nodes before linearized reading' (duration: 132.794701ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:28.737899Z","caller":"traceutil/trace.go:171","msg":"trace[744066058] linearizableReadLoop","detail":"{readStateIndex:1497; appliedIndex:1495; }","duration":"181.332697ms","start":"2026-08-10T15:58:28.556535Z","end":"2026-08-10T15:58:28.737867Z","steps":["trace[744066058] 'read index received' (duration: 51.105427ms)","trace[744066058] 'applied index is now lower than readState.Index' (duration: 130.22611ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:28.738059Z","caller":"traceutil/trace.go:171","msg":"trace[1911642978] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"181.59119ms","start":"2026-08-10T15:58:28.556428Z","end":"2026-08-10T15:58:28.73802Z","steps":["trace[1911642978] 'process raft request' (duration: 132.426795ms)","trace[1911642978] 'compare' (duration: 48.915852ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:58:28.738147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.53893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" ","response":"range_response_count:1 size:1304"} {"level":"info","ts":"2026-08-10T15:58:28.738193Z","caller":"traceutil/trace.go:171","msg":"trace[360573557] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; range_end:; response_count:1; response_revision:1430; }","duration":"181.624871ms","start":"2026-08-10T15:58:28.556555Z","end":"2026-08-10T15:58:28.73818Z","steps":["trace[360573557] 'agreement among raft nodes before linearized reading' (duration: 181.453559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:28.738209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.653221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-role\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-08-10T15:58:28.738291Z","caller":"traceutil/trace.go:171","msg":"trace[1759190976] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-role; range_end:; response_count:1; response_revision:1430; }","duration":"181.771124ms","start":"2026-08-10T15:58:28.556502Z","end":"2026-08-10T15:58:28.738273Z","steps":["trace[1759190976] 'agreement among raft nodes before linearized reading' (duration: 181.49311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:28.738442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.21499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/\" range_end:\"/registry/configmaps/kube-node-lease0\" limit:500 ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-08-10T15:58:28.738483Z","caller":"traceutil/trace.go:171","msg":"trace[54419671] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/; range_end:/registry/configmaps/kube-node-lease0; response_count:1; response_revision:1430; }","duration":"113.26706ms","start":"2026-08-10T15:58:28.625206Z","end":"2026-08-10T15:58:28.738473Z","steps":["trace[54419671] 'agreement among raft nodes before linearized reading' (duration: 113.192839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:28.738573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.685054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-10T15:58:28.738605Z","caller":"traceutil/trace.go:171","msg":"trace[336436359] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1430; }","duration":"128.753914ms","start":"2026-08-10T15:58:28.609841Z","end":"2026-08-10T15:58:28.738595Z","steps":["trace[336436359] 'agreement among raft nodes before linearized reading' (duration: 128.687694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:28.738732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.129603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/default/\" range_end:\"/registry/statefulsets/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:58:28.738763Z","caller":"traceutil/trace.go:171","msg":"trace[1399217844] range","detail":"{range_begin:/registry/statefulsets/default/; range_end:/registry/statefulsets/default0; response_count:0; response_revision:1430; }","duration":"116.176873ms","start":"2026-08-10T15:58:28.622578Z","end":"2026-08-10T15:58:28.738754Z","steps":["trace[1399217844] 'agreement among raft nodes before linearized reading' (duration: 116.125053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:30.047951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196311055,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T15:58:30.548179Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196311055,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T15:58:30.604539Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.072326121s","expected-duration":"1s"} {"level":"info","ts":"2026-08-10T15:58:30.605002Z","caller":"traceutil/trace.go:171","msg":"trace[1655419451] linearizableReadLoop","detail":"{readStateIndex:1499; appliedIndex:1498; }","duration":"1.057951929s","start":"2026-08-10T15:58:29.547027Z","end":"2026-08-10T15:58:30.604979Z","steps":["trace[1655419451] 'read index received' (duration: 1.057644136s)","trace[1655419451] 'applied index is now lower than readState.Index' (duration: 306.903µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:30.605076Z","caller":"traceutil/trace.go:171","msg":"trace[403113257] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"1.072924238s","start":"2026-08-10T15:58:29.532102Z","end":"2026-08-10T15:58:30.605026Z","steps":["trace[403113257] 'process raft request' (duration: 1.072686185s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:30.605199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058159021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-oidc-hmac\" ","response":"range_response_count:1 size:452"} {"level":"info","ts":"2026-08-10T15:58:30.605236Z","caller":"traceutil/trace.go:171","msg":"trace[115500105] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-oidc-hmac; range_end:; response_count:1; response_revision:1432; }","duration":"1.058258302s","start":"2026-08-10T15:58:29.546966Z","end":"2026-08-10T15:58:30.605224Z","steps":["trace[115500105] 'agreement among raft nodes before linearized reading' (duration: 1.05811134s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:30.605242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051333434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-public/\" range_end:\"/registry/pods/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T15:58:30.605274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:29.546934Z","time spent":"1.058331393s","remote":"127.0.0.1:60762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":475,"request content":"key:\"/registry/secrets/envoy-gateway-system/envoy-oidc-hmac\" "} {"level":"warn","ts":"2026-08-10T15:58:30.605277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:29.532077Z","time spent":"1.07312069s","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:58:30.60532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010299581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-10T15:58:30.60533Z","caller":"traceutil/trace.go:171","msg":"trace[1737142014] range","detail":"{range_begin:/registry/pods/kube-public/; range_end:/registry/pods/kube-public0; response_count:0; response_revision:1432; }","duration":"1.051482795s","start":"2026-08-10T15:58:29.553824Z","end":"2026-08-10T15:58:30.605307Z","steps":["trace[1737142014] 'agreement among raft nodes before linearized reading' (duration: 1.051318163s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:30.605387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:29.5538Z","time spent":"1.051575177s","remote":"127.0.0.1:60840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/pods/kube-public/\" range_end:\"/registry/pods/kube-public0\" limit:500 "} {"level":"info","ts":"2026-08-10T15:58:30.605382Z","caller":"traceutil/trace.go:171","msg":"trace[931141437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1432; }","duration":"1.010376531s","start":"2026-08-10T15:58:29.594983Z","end":"2026-08-10T15:58:30.60536Z","steps":["trace[931141437] 'agreement among raft nodes before linearized reading' (duration: 1.010191569s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:30.605261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.226815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T15:58:30.605425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.992658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-10T15:58:30.605451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:29.594972Z","time spent":"1.010463412s","remote":"127.0.0.1:60930","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-08-10T15:58:30.605462Z","caller":"traceutil/trace.go:171","msg":"trace[713971816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1432; }","duration":"264.063448ms","start":"2026-08-10T15:58:30.341386Z","end":"2026-08-10T15:58:30.605449Z","steps":["trace[713971816] 'agreement among raft nodes before linearized reading' (duration: 263.966757ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:30.605462Z","caller":"traceutil/trace.go:171","msg":"trace[306165774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1432; }","duration":"595.463077ms","start":"2026-08-10T15:58:30.009976Z","end":"2026-08-10T15:58:30.605439Z","steps":["trace[306165774] 'agreement among raft nodes before linearized reading' (duration: 595.185334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:30.605478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020776648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-rate-limit\" ","response":"range_response_count:1 size:4460"} {"level":"warn","ts":"2026-08-10T15:58:30.605514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:30.009954Z","time spent":"595.546029ms","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-10T15:58:30.60533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.26917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-node-lease/\" range_end:\"/registry/persistentvolumeclaims/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:58:30.605564Z","caller":"traceutil/trace.go:171","msg":"trace[469718597] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-node-lease/; range_end:/registry/persistentvolumeclaims/kube-node-lease0; response_count:0; response_revision:1432; }","duration":"193.549554ms","start":"2026-08-10T15:58:30.412002Z","end":"2026-08-10T15:58:30.605551Z","steps":["trace[469718597] 'agreement among raft nodes before linearized reading' (duration: 193.296501ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:30.605568Z","caller":"traceutil/trace.go:171","msg":"trace[844878382] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-rate-limit; range_end:; response_count:1; response_revision:1432; }","duration":"1.0209297s","start":"2026-08-10T15:58:29.584614Z","end":"2026-08-10T15:58:30.605544Z","steps":["trace[844878382] 'agreement among raft nodes before linearized reading' (duration: 1.020602287s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:30.605621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:29.584581Z","time spent":"1.021031501s","remote":"127.0.0.1:60762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4483,"request content":"key:\"/registry/secrets/envoy-gateway-system/envoy-rate-limit\" "} {"level":"warn","ts":"2026-08-10T15:58:30.605618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009008316s","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-08-10T15:58:30.605663Z","caller":"traceutil/trace.go:171","msg":"trace[2049289423] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1432; }","duration":"1.009068647s","start":"2026-08-10T15:58:29.596583Z","end":"2026-08-10T15:58:30.605651Z","steps":["trace[2049289423] 'agreement among raft nodes before linearized reading' (duration: 1.008954685s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:30.605693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:29.596575Z","time spent":"1.009112017s","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-10T15:58:31.12573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.521969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-08-10T15:58:31.12582Z","caller":"traceutil/trace.go:171","msg":"trace[1734659913] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:1437; }","duration":"104.669041ms","start":"2026-08-10T15:58:31.021133Z","end":"2026-08-10T15:58:31.125802Z","steps":["trace[1734659913] 'range keys from in-memory index tree' (duration: 104.431759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:32.722181Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054623196311146,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-10T15:58:32.970029Z","caller":"traceutil/trace.go:171","msg":"trace[1521987969] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"791.265624ms","start":"2026-08-10T15:58:32.178736Z","end":"2026-08-10T15:58:32.970002Z","steps":["trace[1521987969] 'process raft request' (duration: 791.075341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:32.970204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:32.178709Z","time spent":"791.402165ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:58:33.609214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.943629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:58:33.609327Z","caller":"traceutil/trace.go:171","msg":"trace[881427198] linearizableReadLoop","detail":"{readStateIndex:1506; appliedIndex:1504; }","duration":"1.388212104s","start":"2026-08-10T15:58:32.221094Z","end":"2026-08-10T15:58:33.609306Z","steps":["trace[881427198] 'read index received' (duration: 748.815096ms)","trace[881427198] 'applied index is now lower than readState.Index' (duration: 639.395658ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:33.60944Z","caller":"traceutil/trace.go:171","msg":"trace[338932517] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"996.609272ms","start":"2026-08-10T15:58:32.612797Z","end":"2026-08-10T15:58:33.609406Z","steps":["trace[338932517] 'process raft request' (duration: 861.38054ms)","trace[338932517] 'compare' (duration: 134.824308ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:58:33.609644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:32.612778Z","time spent":"996.780304ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:58:33.609468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.388371996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-public/default\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-08-10T15:58:33.610076Z","caller":"traceutil/trace.go:171","msg":"trace[397960999] range","detail":"{range_begin:/registry/serviceaccounts/kube-public/default; range_end:; response_count:1; response_revision:1439; }","duration":"1.388989114s","start":"2026-08-10T15:58:32.221051Z","end":"2026-08-10T15:58:33.61004Z","steps":["trace[397960999] 'agreement among raft nodes before linearized reading' (duration: 1.388350516s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:33.61039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:32.221032Z","time spent":"1.389315367s","remote":"127.0.0.1:60858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":202,"request content":"key:\"/registry/serviceaccounts/kube-public/default\" "} {"level":"warn","ts":"2026-08-10T15:58:33.653646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.322064ms","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-08-10T15:58:33.653742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.414314118s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-system/\" range_end:\"/registry/persistentvolumeclaims/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T15:58:33.653787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034156526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-10T15:58:33.653809Z","caller":"traceutil/trace.go:171","msg":"trace[1824367943] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-system/; range_end:/registry/persistentvolumeclaims/kube-system0; response_count:0; response_revision:1439; }","duration":"1.41440315s","start":"2026-08-10T15:58:32.239383Z","end":"2026-08-10T15:58:33.653786Z","steps":["trace[1824367943] 'agreement among raft nodes before linearized reading' (duration: 1.414332719s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:33.653839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.064808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:58:33.65384Z","caller":"traceutil/trace.go:171","msg":"trace[650078343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1439; }","duration":"1.034236736s","start":"2026-08-10T15:58:32.619589Z","end":"2026-08-10T15:58:33.653826Z","steps":["trace[650078343] 'agreement among raft nodes before linearized reading' (duration: 1.034118015s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:33.653865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:32.239349Z","time spent":"1.414505131s","remote":"127.0.0.1:60816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/kube-system/\" range_end:\"/registry/persistentvolumeclaims/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-08-10T15:58:33.653765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033987113s","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-08-10T15:58:33.653773Z","caller":"traceutil/trace.go:171","msg":"trace[559194299] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1439; }","duration":"873.501536ms","start":"2026-08-10T15:58:32.780255Z","end":"2026-08-10T15:58:33.653757Z","steps":["trace[559194299] 'agreement among raft nodes before linearized reading' (duration: 873.215812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:33.653979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:32.780232Z","time spent":"873.732569ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-10T15:58:33.653976Z","caller":"traceutil/trace.go:171","msg":"trace[151693450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1439; }","duration":"1.034254877s","start":"2026-08-10T15:58:32.619696Z","end":"2026-08-10T15:58:33.653951Z","steps":["trace[151693450] 'agreement among raft nodes before linearized reading' (duration: 1.033860592s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:33.654033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.633937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-10T15:58:33.654089Z","caller":"traceutil/trace.go:171","msg":"trace[1014061876] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1439; }","duration":"723.717908ms","start":"2026-08-10T15:58:32.930355Z","end":"2026-08-10T15:58:33.654073Z","steps":["trace[1014061876] 'agreement among raft nodes before linearized reading' (duration: 723.595027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:33.653717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.753473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T15:58:33.654148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:32.619665Z","time spent":"1.034428708s","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-10T15:58:33.654156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:32.930335Z","time spent":"723.805639ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-10T15:58:33.653985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.424123259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/default\" ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-08-10T15:58:33.654151Z","caller":"traceutil/trace.go:171","msg":"trace[1134054380] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1439; }","duration":"123.262098ms","start":"2026-08-10T15:58:33.530879Z","end":"2026-08-10T15:58:33.654141Z","steps":["trace[1134054380] 'agreement among raft nodes before linearized reading' (duration: 122.720642ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:33.65443Z","caller":"traceutil/trace.go:171","msg":"trace[1758631180] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/default; range_end:; response_count:1; response_revision:1439; }","duration":"1.424579004s","start":"2026-08-10T15:58:32.229831Z","end":"2026-08-10T15:58:33.65441Z","steps":["trace[1758631180] 'agreement among raft nodes before linearized reading' (duration: 1.424105859s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:33.653905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:32.619575Z","time spent":"1.034322067s","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-10T15:58:33.65464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:32.229818Z","time spent":"1.424802817s","remote":"127.0.0.1:60858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":210,"request content":"key:\"/registry/serviceaccounts/kube-node-lease/default\" "} {"level":"warn","ts":"2026-08-10T15:58:33.653656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056950382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/\" range_end:\"/registry/rolebindings/kube-system0\" limit:500 ","response":"range_response_count:11 size:7467"} {"level":"info","ts":"2026-08-10T15:58:33.654888Z","caller":"traceutil/trace.go:171","msg":"trace[998828335] range","detail":"{range_begin:/registry/rolebindings/kube-system/; range_end:/registry/rolebindings/kube-system0; response_count:11; response_revision:1439; }","duration":"1.058244448s","start":"2026-08-10T15:58:32.59662Z","end":"2026-08-10T15:58:33.654865Z","steps":["trace[998828335] 'agreement among raft nodes before linearized reading' (duration: 1.056877172s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:33.655011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:32.596597Z","time spent":"1.058388269s","remote":"127.0.0.1:60982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":11,"response size":7490,"request content":"key:\"/registry/rolebindings/kube-system/\" range_end:\"/registry/rolebindings/kube-system0\" limit:500 "} {"level":"info","ts":"2026-08-10T15:58:33.6539Z","caller":"traceutil/trace.go:171","msg":"trace[238840059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1439; }","duration":"635.12589ms","start":"2026-08-10T15:58:33.018755Z","end":"2026-08-10T15:58:33.653881Z","steps":["trace[238840059] 'agreement among raft nodes before linearized reading' (duration: 635.041819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:33.653672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034980604s","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-08-10T15:58:33.655322Z","caller":"traceutil/trace.go:171","msg":"trace[1565770043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1439; }","duration":"1.036671924s","start":"2026-08-10T15:58:32.618635Z","end":"2026-08-10T15:58:33.655307Z","steps":["trace[1565770043] 'agreement among raft nodes before linearized reading' (duration: 1.034869953s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:33.655517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:32.618613Z","time spent":"1.036806936s","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-10T15:58:33.655833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:33.018738Z","time spent":"637.083222ms","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-10T15:58:34.344682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.833008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:58:34.345215Z","caller":"traceutil/trace.go:171","msg":"trace[919282992] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"684.438482ms","start":"2026-08-10T15:58:33.660742Z","end":"2026-08-10T15:58:34.345181Z","steps":["trace[919282992] 'process raft request' (duration: 571.999617ms)","trace[919282992] 'compare' (duration: 111.727997ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:58:34.345546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:33.660734Z","time spent":"684.693996ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:58:34.345629Z","caller":"traceutil/trace.go:171","msg":"trace[2028595948] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"684.743465ms","start":"2026-08-10T15:58:33.66084Z","end":"2026-08-10T15:58:34.345583Z","steps":["trace[2028595948] 'process raft request' (duration: 683.941437ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:34.345721Z","caller":"traceutil/trace.go:171","msg":"trace[449154817] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"683.884327ms","start":"2026-08-10T15:58:33.661804Z","end":"2026-08-10T15:58:34.345688Z","steps":["trace[449154817] 'process raft request' (duration: 683.787146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:34.345789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:33.660811Z","time spent":"684.893628ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:58:34.345876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:33.661795Z","time spent":"684.005668ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:58:34.345899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.566304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/default\" ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-08-10T15:58:34.345669Z","caller":"traceutil/trace.go:171","msg":"trace[675466351] linearizableReadLoop","detail":"{readStateIndex:1512; appliedIndex:1508; }","duration":"608.544048ms","start":"2026-08-10T15:58:33.73711Z","end":"2026-08-10T15:58:34.345654Z","steps":["trace[675466351] 'read index received' (duration: 495.562368ms)","trace[675466351] 'applied index is now lower than readState.Index' (duration: 112.98048ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:34.345933Z","caller":"traceutil/trace.go:171","msg":"trace[1660054730] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/default; range_end:; response_count:1; response_revision:1444; }","duration":"684.623544ms","start":"2026-08-10T15:58:33.661302Z","end":"2026-08-10T15:58:34.345925Z","steps":["trace[1660054730] 'agreement among raft nodes before linearized reading' (duration: 684.389632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:34.345984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:33.661264Z","time spent":"684.712156ms","remote":"127.0.0.1:60858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":210,"request content":"key:\"/registry/serviceaccounts/kube-node-lease/default\" "} {"level":"info","ts":"2026-08-10T15:58:34.346018Z","caller":"traceutil/trace.go:171","msg":"trace[1084642654] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"685.009469ms","start":"2026-08-10T15:58:33.660998Z","end":"2026-08-10T15:58:34.346008Z","steps":["trace[1084642654] 'process raft request' (duration: 683.826126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:34.346093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:33.660976Z","time spent":"685.08441ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:58:34.346226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.38402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-10T15:58:34.346247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.346029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-08-10T15:58:34.346293Z","caller":"traceutil/trace.go:171","msg":"trace[1061730332] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1444; }","duration":"594.437469ms","start":"2026-08-10T15:58:33.751841Z","end":"2026-08-10T15:58:34.346278Z","steps":["trace[1061730332] 'agreement among raft nodes before linearized reading' (duration: 594.165516ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:34.346327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:33.751815Z","time spent":"594.50322ms","remote":"127.0.0.1:60762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":4,"response size":329975,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-08-10T15:58:34.346344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.066363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-08-10T15:58:34.346675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.466516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/\" range_end:\"/registry/roles/kube-system0\" limit:500 ","response":"range_response_count:11 size:5916"} {"level":"info","ts":"2026-08-10T15:58:34.347652Z","caller":"traceutil/trace.go:171","msg":"trace[971340427] range","detail":"{range_begin:/registry/roles/kube-system/; range_end:/registry/roles/kube-system0; response_count:11; response_revision:1444; }","duration":"623.470537ms","start":"2026-08-10T15:58:33.724167Z","end":"2026-08-10T15:58:34.347637Z","steps":["trace[971340427] 'agreement among raft nodes before linearized reading' (duration: 621.556995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:34.3479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:33.724089Z","time spent":"623.797751ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":11,"response size":5939,"request content":"key:\"/registry/roles/kube-system/\" range_end:\"/registry/roles/kube-system0\" limit:500 "} {"level":"info","ts":"2026-08-10T15:58:34.346259Z","caller":"traceutil/trace.go:171","msg":"trace[884414496] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1444; }","duration":"405.474532ms","start":"2026-08-10T15:58:33.940774Z","end":"2026-08-10T15:58:34.346249Z","steps":["trace[884414496] 'agreement among raft nodes before linearized reading' (duration: 405.403341ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:34.346373Z","caller":"traceutil/trace.go:171","msg":"trace[499772499] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:1444; }","duration":"529.138344ms","start":"2026-08-10T15:58:33.817225Z","end":"2026-08-10T15:58:34.346363Z","steps":["trace[499772499] 'agreement among raft nodes before linearized reading' (duration: 529.071563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:34.348024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:33.817202Z","time spent":"530.812003ms","remote":"127.0.0.1:60764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6554,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"warn","ts":"2026-08-10T15:58:34.348392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:33.940743Z","time spent":"407.401343ms","remote":"127.0.0.1:60830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-08-10T15:58:36.072072Z","caller":"traceutil/trace.go:171","msg":"trace[339748949] linearizableReadLoop","detail":"{readStateIndex:1513; appliedIndex:1512; }","duration":"400.642744ms","start":"2026-08-10T15:58:35.671407Z","end":"2026-08-10T15:58:36.07205Z","steps":["trace[339748949] 'read index received' (duration: 400.402251ms)","trace[339748949] 'applied index is now lower than readState.Index' (duration: 238.933µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:36.072147Z","caller":"traceutil/trace.go:171","msg":"trace[1607655551] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"457.598575ms","start":"2026-08-10T15:58:35.61452Z","end":"2026-08-10T15:58:36.072119Z","steps":["trace[1607655551] 'process raft request' (duration: 457.387692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:36.072344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.908548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4219"} {"level":"warn","ts":"2026-08-10T15:58:36.072331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:35.614504Z","time spent":"457.707417ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:58:36.072406Z","caller":"traceutil/trace.go:171","msg":"trace[1803316011] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:1445; }","duration":"401.03652ms","start":"2026-08-10T15:58:35.671354Z","end":"2026-08-10T15:58:36.07239Z","steps":["trace[1803316011] 'agreement among raft nodes before linearized reading' (duration: 400.831088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:36.07245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:35.671336Z","time spent":"401.10067ms","remote":"127.0.0.1:32854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4242,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-08-10T15:58:36.072439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.611445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-10T15:58:36.072557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.871252ms","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-08-10T15:58:36.072645Z","caller":"traceutil/trace.go:171","msg":"trace[699540250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1445; }","duration":"332.996873ms","start":"2026-08-10T15:58:35.739628Z","end":"2026-08-10T15:58:36.072625Z","steps":["trace[699540250] 'agreement among raft nodes before linearized reading' (duration: 332.72249ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:36.072672Z","caller":"traceutil/trace.go:171","msg":"trace[1357758777] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1445; }","duration":"184.744767ms","start":"2026-08-10T15:58:35.887755Z","end":"2026-08-10T15:58:36.0725Z","steps":["trace[1357758777] 'agreement among raft nodes before linearized reading' (duration: 184.580995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:36.072699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:35.739618Z","time spent":"333.071854ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-10T15:58:36.072961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.666256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-dns\" ","response":"range_response_count:1 size:757"} {"level":"info","ts":"2026-08-10T15:58:36.073066Z","caller":"traceutil/trace.go:171","msg":"trace[18926717] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-dns; range_end:; response_count:1; response_revision:1445; }","duration":"393.732687ms","start":"2026-08-10T15:58:35.679278Z","end":"2026-08-10T15:58:36.073011Z","steps":["trace[18926717] 'agreement among raft nodes before linearized reading' (duration: 393.020469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:36.07315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:35.679204Z","time spent":"393.933329ms","remote":"127.0.0.1:60828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":780,"request content":"key:\"/registry/services/endpoints/kube-system/kube-dns\" "} {"level":"info","ts":"2026-08-10T15:58:36.262267Z","caller":"traceutil/trace.go:171","msg":"trace[330754029] linearizableReadLoop","detail":"{readStateIndex:1514; appliedIndex:1513; }","duration":"180.009113ms","start":"2026-08-10T15:58:36.081857Z","end":"2026-08-10T15:58:36.261867Z","steps":["trace[330754029] 'read index received' (duration: 92.943265ms)","trace[330754029] 'applied index is now lower than readState.Index' (duration: 87.064608ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:36.262339Z","caller":"traceutil/trace.go:171","msg":"trace[1696795008] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"184.419123ms","start":"2026-08-10T15:58:36.077524Z","end":"2026-08-10T15:58:36.261944Z","steps":["trace[1696795008] 'process raft request' (duration: 97.313274ms)","trace[1696795008] 'compare' (duration: 86.857466ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:58:36.262487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.603519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:70 size:51927"} {"level":"info","ts":"2026-08-10T15:58:36.262567Z","caller":"traceutil/trace.go:171","msg":"trace[259954254] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:70; response_revision:1446; }","duration":"180.72215ms","start":"2026-08-10T15:58:36.081828Z","end":"2026-08-10T15:58:36.26255Z","steps":["trace[259954254] 'agreement among raft nodes before linearized reading' (duration: 180.208065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:36.263028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.08856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:70 size:51927"} {"level":"info","ts":"2026-08-10T15:58:36.263221Z","caller":"traceutil/trace.go:171","msg":"trace[307970626] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:70; response_revision:1446; }","duration":"177.320943ms","start":"2026-08-10T15:58:36.085884Z","end":"2026-08-10T15:58:36.263205Z","steps":["trace[307970626] 'agreement among raft nodes before linearized reading' (duration: 176.572004ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:42.678094Z","caller":"traceutil/trace.go:171","msg":"trace[863199562] linearizableReadLoop","detail":"{readStateIndex:1536; appliedIndex:1535; }","duration":"210.575949ms","start":"2026-08-10T15:58:42.467488Z","end":"2026-08-10T15:58:42.678064Z","steps":["trace[863199562] 'read index received' (duration: 95.612µs)","trace[863199562] 'applied index is now lower than readState.Index' (duration: 210.479096ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:42.678244Z","caller":"traceutil/trace.go:171","msg":"trace[146662618] transaction","detail":"{read_only:false; response_revision:1467; number_of_response:1; }","duration":"210.74461ms","start":"2026-08-10T15:58:42.467458Z","end":"2026-08-10T15:58:42.678203Z","steps":["trace[146662618] 'process raft request' (duration: 210.442317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:42.678274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.76062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-08-10T15:58:42.678427Z","caller":"traceutil/trace.go:171","msg":"trace[1566189941] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:1467; }","duration":"210.923572ms","start":"2026-08-10T15:58:42.467481Z","end":"2026-08-10T15:58:42.678404Z","steps":["trace[1566189941] 'agreement among raft nodes before linearized reading' (duration: 210.72546ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:42.811213Z","caller":"traceutil/trace.go:171","msg":"trace[39907720] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"340.217818ms","start":"2026-08-10T15:58:42.470963Z","end":"2026-08-10T15:58:42.811181Z","steps":["trace[39907720] 'process raft request' (duration: 251.688538ms)","trace[39907720] 'compare' (duration: 88.345628ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:42.811394Z","caller":"traceutil/trace.go:171","msg":"trace[330316241] linearizableReadLoop","detail":"{readStateIndex:1538; appliedIndex:1536; }","duration":"133.17102ms","start":"2026-08-10T15:58:42.678174Z","end":"2026-08-10T15:58:42.811345Z","steps":["trace[330316241] 'read index received' (duration: 44.419007ms)","trace[330316241] 'applied index is now lower than readState.Index' (duration: 88.750913ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:42.811534Z","caller":"traceutil/trace.go:171","msg":"trace[1618484164] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"340.480981ms","start":"2026-08-10T15:58:42.471043Z","end":"2026-08-10T15:58:42.811524Z","steps":["trace[1618484164] 'process raft request' (duration: 340.096947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:42.811607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:42.470946Z","time spent":"340.45273ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:58:42.811755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.48855ms","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":"info","ts":"2026-08-10T15:58:42.811788Z","caller":"traceutil/trace.go:171","msg":"trace[1337778819] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:1469; }","duration":"306.561022ms","start":"2026-08-10T15:58:42.505217Z","end":"2026-08-10T15:58:42.811778Z","steps":["trace[1337778819] 'agreement among raft nodes before linearized reading' (duration: 306.479581ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:42.811806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:42.505194Z","time spent":"306.608852ms","remote":"127.0.0.1:60858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-08-10T15:58:42.811645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:42.471036Z","time spent":"340.522971ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:58:42.81184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.142981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-10T15:58:42.811924Z","caller":"traceutil/trace.go:171","msg":"trace[576138291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1469; }","duration":"343.258172ms","start":"2026-08-10T15:58:42.468646Z","end":"2026-08-10T15:58:42.811904Z","steps":["trace[576138291] 'agreement among raft nodes before linearized reading' (duration: 343.026979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:42.812072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:42.468635Z","time spent":"343.334693ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-10T15:58:42.812253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.481601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-08-10T15:58:42.812287Z","caller":"traceutil/trace.go:171","msg":"trace[1626710476] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1469; }","duration":"130.543032ms","start":"2026-08-10T15:58:42.681734Z","end":"2026-08-10T15:58:42.812277Z","steps":["trace[1626710476] 'agreement among raft nodes before linearized reading' (duration: 130.324239ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:45.006094Z","caller":"traceutil/trace.go:171","msg":"trace[83378483] linearizableReadLoop","detail":"{readStateIndex:1546; appliedIndex:1544; }","duration":"158.080899ms","start":"2026-08-10T15:58:44.84799Z","end":"2026-08-10T15:58:45.006071Z","steps":["trace[83378483] 'read index received' (duration: 72.919046ms)","trace[83378483] 'applied index is now lower than readState.Index' (duration: 85.160503ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:45.006186Z","caller":"traceutil/trace.go:171","msg":"trace[473058943] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"161.351656ms","start":"2026-08-10T15:58:44.844798Z","end":"2026-08-10T15:58:45.00615Z","steps":["trace[473058943] 'process raft request' (duration: 95.797312ms)","trace[473058943] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:419; } (duration: 65.349882ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:58:45.00629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.280921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-10T15:58:45.006326Z","caller":"traceutil/trace.go:171","msg":"trace[1642875530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1476; }","duration":"158.358081ms","start":"2026-08-10T15:58:44.847958Z","end":"2026-08-10T15:58:45.006316Z","steps":["trace[1642875530] 'agreement among raft nodes before linearized reading' (duration: 158.20418ms)"],"step_count":1}