{"level":"warn","ts":"2026-02-10T15:00:43.955797Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T15:00:43.965508Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.188:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.188:2380","--initial-cluster=instance=https://199.19.213.188:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.188:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.188: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-02-10T15:00:43.965649Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T15:00:43.965662Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.188:2380"]} {"level":"info","ts":"2026-02-10T15:00:43.965696Z","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-02-10T15:00:43.966344Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.188:2379"]} {"level":"info","ts":"2026-02-10T15:00:43.971905Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.188:2380"],"listen-peer-urls":["https://199.19.213.188:2380"],"advertise-client-urls":["https://199.19.213.188:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.188:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.188: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-02-10T15:00:43.979525Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.320564ms"} {"level":"info","ts":"2026-02-10T15:00:43.989694Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"201fe5d2d7e0161a","cluster-id":"92c0fc3a33fabd38"} {"level":"info","ts":"2026-02-10T15:00:43.989761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T15:00:43.989822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became follower at term 0"} {"level":"info","ts":"2026-02-10T15:00:43.989832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 201fe5d2d7e0161a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T15:00:43.989838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became follower at term 1"} {"level":"info","ts":"2026-02-10T15:00:43.989938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a switched to configuration voters=(2314821427219404314)"} {"level":"warn","ts":"2026-02-10T15:00:43.999429Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T15:00:44.005183Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T15:00:44.011751Z","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-02-10T15:00:44.019197Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"201fe5d2d7e0161a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T15:00:44.024757Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"201fe5d2d7e0161a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T15:00:44.024886Z","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-02-10T15:00:44.024918Z","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-02-10T15:00:44.024925Z","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-02-10T15:00:44.025386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a switched to configuration voters=(2314821427219404314)"} {"level":"info","ts":"2026-02-10T15:00:44.025467Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92c0fc3a33fabd38","local-member-id":"201fe5d2d7e0161a","added-peer-id":"201fe5d2d7e0161a","added-peer-peer-urls":["https://199.19.213.188:2380"]} {"level":"info","ts":"2026-02-10T15:00:44.026792Z","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-02-10T15:00:44.027039Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.188:2380"} {"level":"info","ts":"2026-02-10T15:00:44.027052Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.188:2380"} {"level":"info","ts":"2026-02-10T15:00:44.036223Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"201fe5d2d7e0161a","initial-advertise-peer-urls":["https://199.19.213.188:2380"],"listen-peer-urls":["https://199.19.213.188:2380"],"advertise-client-urls":["https://199.19.213.188:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.188:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T15:00:44.036261Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T15:00:44.790105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T15:00:44.790268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T15:00:44.790339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a received MsgPreVoteResp from 201fe5d2d7e0161a at term 1"} {"level":"info","ts":"2026-02-10T15:00:44.790432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became candidate at term 2"} {"level":"info","ts":"2026-02-10T15:00:44.790484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a received MsgVoteResp from 201fe5d2d7e0161a at term 2"} {"level":"info","ts":"2026-02-10T15:00:44.790534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became leader at term 2"} {"level":"info","ts":"2026-02-10T15:00:44.790591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 201fe5d2d7e0161a elected leader 201fe5d2d7e0161a at term 2"} {"level":"info","ts":"2026-02-10T15:00:44.798349Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T15:00:44.800908Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"201fe5d2d7e0161a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.188:2379]}","request-path":"/0/members/201fe5d2d7e0161a/attributes","cluster-id":"92c0fc3a33fabd38","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T15:00:44.800933Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T15:00:44.80115Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T15:00:44.802978Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.188:2379"} {"level":"info","ts":"2026-02-10T15:00:44.803913Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T15:00:44.804008Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T15:00:44.804305Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T15:00:44.804446Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92c0fc3a33fabd38","local-member-id":"201fe5d2d7e0161a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T15:00:44.804577Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T15:00:44.804644Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-10T15:00:53.16936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.518837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:53.169463Z","caller":"traceutil/trace.go:171","msg":"trace[1219934687] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:0; response_revision:286; }","duration":"233.695771ms","start":"2026-02-10T15:00:52.935748Z","end":"2026-02-10T15:00:53.169443Z","steps":["trace[1219934687] 'range keys from in-memory index tree' (duration: 233.395095ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:00.504884Z","caller":"traceutil/trace.go:171","msg":"trace[603195481] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"189.204818ms","start":"2026-02-10T15:01:00.315664Z","end":"2026-02-10T15:01:00.504868Z","steps":["trace[603195481] 'process raft request' (duration: 189.104426ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:00.589667Z","caller":"traceutil/trace.go:171","msg":"trace[664853657] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"223.403626ms","start":"2026-02-10T15:01:00.366244Z","end":"2026-02-10T15:01:00.589647Z","steps":["trace[664853657] 'process raft request' (duration: 168.781983ms)","trace[664853657] 'compare' (duration: 54.49227ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:09.054121Z","caller":"traceutil/trace.go:171","msg":"trace[1103127639] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"185.485375ms","start":"2026-02-10T15:01:08.868618Z","end":"2026-02-10T15:01:09.054104Z","steps":["trace[1103127639] 'process raft request' (duration: 185.393483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:11.253319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.165926ms","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-02-10T15:01:11.253395Z","caller":"traceutil/trace.go:171","msg":"trace[1218641293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:402; }","duration":"196.281519ms","start":"2026-02-10T15:01:11.057095Z","end":"2026-02-10T15:01:11.253377Z","steps":["trace[1218641293] 'range keys from in-memory index tree' (duration: 196.095845ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:17.510992Z","caller":"traceutil/trace.go:171","msg":"trace[1301256669] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:471; }","duration":"222.060019ms","start":"2026-02-10T15:01:17.28891Z","end":"2026-02-10T15:01:17.51097Z","steps":["trace[1301256669] 'read index received' (duration: 221.914996ms)","trace[1301256669] 'applied index is now lower than readState.Index' (duration: 144.203µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:17.511104Z","caller":"traceutil/trace.go:171","msg":"trace[349832454] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"685.356118ms","start":"2026-02-10T15:01:16.825739Z","end":"2026-02-10T15:01:17.511095Z","steps":["trace[349832454] 'process raft request' (duration: 685.098333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:17.511597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.674391ms","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-02-10T15:01:17.51162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:16.825716Z","time spent":"685.41129ms","remote":"127.0.0.1:56622","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-02-10T15:01:17.511663Z","caller":"traceutil/trace.go:171","msg":"trace[139227749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:456; }","duration":"222.752523ms","start":"2026-02-10T15:01:17.288876Z","end":"2026-02-10T15:01:17.511629Z","steps":["trace[139227749] 'agreement among raft nodes before linearized reading' (duration: 222.58745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:17.873956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.547481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:01:17.874244Z","caller":"traceutil/trace.go:171","msg":"trace[1141849633] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"357.232918ms","start":"2026-02-10T15:01:17.516992Z","end":"2026-02-10T15:01:17.874225Z","steps":["trace[1141849633] 'process raft request' (duration: 252.410291ms)","trace[1141849633] 'compare' (duration: 103.415979ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:17.874416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:17.51697Z","time spent":"357.35782ms","remote":"127.0.0.1:56622","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-02-10T15:01:17.875907Z","caller":"traceutil/trace.go:171","msg":"trace[1268254278] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:472; }","duration":"358.3688ms","start":"2026-02-10T15:01:17.51752Z","end":"2026-02-10T15:01:17.875889Z","steps":["trace[1268254278] 'read index received' (duration: 251.8464ms)","trace[1268254278] 'applied index is now lower than readState.Index' (duration: 106.52081ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:17.876063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.533504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:17.876113Z","caller":"traceutil/trace.go:171","msg":"trace[769523257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:457; }","duration":"358.583624ms","start":"2026-02-10T15:01:17.517498Z","end":"2026-02-10T15:01:17.876082Z","steps":["trace[769523257] 'agreement among raft nodes before linearized reading' (duration: 358.505483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:17.876127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.197235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-10T15:01:17.876143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:17.51749Z","time spent":"358.646745ms","remote":"127.0.0.1:50882","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-02-10T15:01:17.87617Z","caller":"traceutil/trace.go:171","msg":"trace[860996228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:457; }","duration":"313.271347ms","start":"2026-02-10T15:01:17.562888Z","end":"2026-02-10T15:01:17.87616Z","steps":["trace[860996228] 'agreement among raft nodes before linearized reading' (duration: 313.137314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:17.876193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:17.562874Z","time spent":"313.314387ms","remote":"127.0.0.1:56622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T15:01:17.876379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.877398ms","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-02-10T15:01:17.876393Z","caller":"traceutil/trace.go:171","msg":"trace[1779596565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:457; }","duration":"312.910778ms","start":"2026-02-10T15:01:17.563478Z","end":"2026-02-10T15:01:17.876388Z","steps":["trace[1779596565] 'agreement among raft nodes before linearized reading' (duration: 312.876777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:17.876405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:17.563468Z","time spent":"312.934368ms","remote":"127.0.0.1:56622","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-02-10T15:01:18.034724Z","caller":"traceutil/trace.go:171","msg":"trace[2066518777] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"147.379321ms","start":"2026-02-10T15:01:17.887319Z","end":"2026-02-10T15:01:18.034698Z","steps":["trace[2066518777] 'process raft request' (duration: 147.33021ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:18.035614Z","caller":"traceutil/trace.go:171","msg":"trace[1621297923] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"148.340989ms","start":"2026-02-10T15:01:17.887242Z","end":"2026-02-10T15:01:18.035583Z","steps":["trace[1621297923] 'process raft request' (duration: 120.063609ms)","trace[1621297923] 'compare' (duration: 27.23262ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:21.039865Z","caller":"traceutil/trace.go:171","msg":"trace[218508837] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"347.208819ms","start":"2026-02-10T15:01:20.692638Z","end":"2026-02-10T15:01:21.039846Z","steps":["trace[218508837] 'process raft request' (duration: 347.086257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:21.039979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:20.692622Z","time spent":"347.289451ms","remote":"127.0.0.1:56548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:01:22.108411Z","caller":"traceutil/trace.go:171","msg":"trace[1075512531] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"128.295801ms","start":"2026-02-10T15:01:21.980093Z","end":"2026-02-10T15:01:22.108388Z","steps":["trace[1075512531] 'process raft request' (duration: 114.61997ms)","trace[1075512531] 'compare' (duration: 13.290214ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:25.360133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.46733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ld4rs\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-10T15:01:25.360207Z","caller":"traceutil/trace.go:171","msg":"trace[1645313968] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ld4rs; range_end:; response_count:1; response_revision:478; }","duration":"106.579432ms","start":"2026-02-10T15:01:25.253609Z","end":"2026-02-10T15:01:25.360188Z","steps":["trace[1645313968] 'range keys from in-memory index tree' (duration: 106.312297ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:25.679753Z","caller":"traceutil/trace.go:171","msg":"trace[97300548] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"299.270799ms","start":"2026-02-10T15:01:25.380464Z","end":"2026-02-10T15:01:25.679735Z","steps":["trace[97300548] 'process raft request' (duration: 299.167517ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:27.412167Z","caller":"traceutil/trace.go:171","msg":"trace[1242160081] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"101.812347ms","start":"2026-02-10T15:01:27.310333Z","end":"2026-02-10T15:01:27.412146Z","steps":["trace[1242160081] 'process raft request' (duration: 77.789712ms)","trace[1242160081] 'compare' (duration: 23.948664ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:34.26637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.615972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1668"} {"level":"info","ts":"2026-02-10T15:01:34.266444Z","caller":"traceutil/trace.go:171","msg":"trace[168713246] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:586; }","duration":"103.716385ms","start":"2026-02-10T15:01:34.162713Z","end":"2026-02-10T15:01:34.26643Z","steps":["trace[168713246] 'range keys from in-memory index tree' (duration: 103.521671ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:34.267239Z","caller":"traceutil/trace.go:171","msg":"trace[1046639166] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"101.742635ms","start":"2026-02-10T15:01:34.165482Z","end":"2026-02-10T15:01:34.267224Z","steps":["trace[1046639166] 'process raft request' (duration: 101.542601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:36.828089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.706275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:01:36.828222Z","caller":"traceutil/trace.go:171","msg":"trace[897526502] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"493.773773ms","start":"2026-02-10T15:01:36.33443Z","end":"2026-02-10T15:01:36.828203Z","steps":["trace[897526502] 'read index received' (duration: 27µs)","trace[897526502] 'applied index is now lower than readState.Index' (duration: 493.745393ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:36.828453Z","caller":"traceutil/trace.go:171","msg":"trace[804649363] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"657.944156ms","start":"2026-02-10T15:01:36.170492Z","end":"2026-02-10T15:01:36.828436Z","steps":["trace[804649363] 'process raft request' (duration: 131.829012ms)","trace[804649363] 'compare' (duration: 525.207745ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:36.828545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:36.170443Z","time spent":"658.058568ms","remote":"127.0.0.1:56622","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-02-10T15:01:36.828847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.409945ms","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-02-10T15:01:36.828895Z","caller":"traceutil/trace.go:171","msg":"trace[2136995311] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"494.482987ms","start":"2026-02-10T15:01:36.334399Z","end":"2026-02-10T15:01:36.828882Z","steps":["trace[2136995311] 'agreement among raft nodes before linearized reading' (duration: 494.339454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:36.828938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:36.334385Z","time spent":"494.541938ms","remote":"127.0.0.1:56622","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-02-10T15:01:36.904318Z","caller":"traceutil/trace.go:171","msg":"trace[1623873265] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"361.339679ms","start":"2026-02-10T15:01:36.542964Z","end":"2026-02-10T15:01:36.904304Z","steps":["trace[1623873265] 'process raft request' (duration: 361.263607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:36.90441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:36.542938Z","time spent":"361.426431ms","remote":"127.0.0.1:38262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T15:01:36.906881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.255309ms","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-02-10T15:01:36.906907Z","caller":"traceutil/trace.go:171","msg":"trace[86271836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"560.293951ms","start":"2026-02-10T15:01:36.346606Z","end":"2026-02-10T15:01:36.9069Z","steps":["trace[86271836] 'agreement among raft nodes before linearized reading' (duration: 560.185519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:36.906923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:36.346603Z","time spent":"560.317231ms","remote":"127.0.0.1:56622","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-02-10T15:01:36.907084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.74506ms","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-02-10T15:01:36.907105Z","caller":"traceutil/trace.go:171","msg":"trace[2100750496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"560.812151ms","start":"2026-02-10T15:01:36.346286Z","end":"2026-02-10T15:01:36.907098Z","steps":["trace[2100750496] 'agreement among raft nodes before linearized reading' (duration: 558.451984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:36.907126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:36.346274Z","time spent":"560.841081ms","remote":"127.0.0.1:56622","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-02-10T15:01:41.082526Z","caller":"traceutil/trace.go:171","msg":"trace[1320694248] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"130.074139ms","start":"2026-02-10T15:01:40.952437Z","end":"2026-02-10T15:01:41.082511Z","steps":["trace[1320694248] 'read index received' (duration: 86.595354ms)","trace[1320694248] 'applied index is now lower than readState.Index' (duration: 43.478245ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:41.082658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.203652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-10T15:01:41.082674Z","caller":"traceutil/trace.go:171","msg":"trace[770150916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"130.247472ms","start":"2026-02-10T15:01:40.952421Z","end":"2026-02-10T15:01:41.082669Z","steps":["trace[770150916] 'agreement among raft nodes before linearized reading' (duration: 130.14609ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:41.082763Z","caller":"traceutil/trace.go:171","msg":"trace[1733909997] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"133.04908ms","start":"2026-02-10T15:01:40.94969Z","end":"2026-02-10T15:01:41.082739Z","steps":["trace[1733909997] 'process raft request' (duration: 132.733293ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:42.742794Z","caller":"traceutil/trace.go:171","msg":"trace[1244069254] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"329.350019ms","start":"2026-02-10T15:01:42.413418Z","end":"2026-02-10T15:01:42.742768Z","steps":["trace[1244069254] 'read index received' (duration: 329.189686ms)","trace[1244069254] 'applied index is now lower than readState.Index' (duration: 159.293µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:42.742936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.492912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:42.742969Z","caller":"traceutil/trace.go:171","msg":"trace[1931573110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"329.572304ms","start":"2026-02-10T15:01:42.413386Z","end":"2026-02-10T15:01:42.742959Z","steps":["trace[1931573110] 'agreement among raft nodes before linearized reading' (duration: 329.479622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:42.743019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:42.41337Z","time spent":"329.624334ms","remote":"127.0.0.1:50866","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-02-10T15:01:42.743053Z","caller":"traceutil/trace.go:171","msg":"trace[1141583231] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"716.694298ms","start":"2026-02-10T15:01:42.026335Z","end":"2026-02-10T15:01:42.74303Z","steps":["trace[1141583231] 'process raft request' (duration: 716.2771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:42.743153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:42.026318Z","time spent":"716.78031ms","remote":"127.0.0.1:56622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:01:44.861916Z","caller":"traceutil/trace.go:171","msg":"trace[1495475554] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"103.313614ms","start":"2026-02-10T15:01:44.758581Z","end":"2026-02-10T15:01:44.861895Z","steps":["trace[1495475554] 'process raft request' (duration: 103.12737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:59.567387Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592757251577043994,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T15:02:00.06828Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592757251577043994,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T15:02:00.275648Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.358219645s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T15:02:00.27599Z","caller":"traceutil/trace.go:171","msg":"trace[370770844] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:740; }","duration":"1.209409585s","start":"2026-02-10T15:01:59.066564Z","end":"2026-02-10T15:02:00.275973Z","steps":["trace[370770844] 'read index received' (duration: 1.20919751s)","trace[370770844] 'applied index is now lower than readState.Index' (duration: 211.475µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:00.276068Z","caller":"traceutil/trace.go:171","msg":"trace[851307446] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"1.358770416s","start":"2026-02-10T15:01:58.917273Z","end":"2026-02-10T15:02:00.276043Z","steps":["trace[851307446] 'process raft request' (duration: 1.358521551s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:00.276153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209566008s","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-02-10T15:02:00.276177Z","caller":"traceutil/trace.go:171","msg":"trace[717029230] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:715; }","duration":"1.20963218s","start":"2026-02-10T15:01:59.066538Z","end":"2026-02-10T15:02:00.27617Z","steps":["trace[717029230] 'agreement among raft nodes before linearized reading' (duration: 1.209502517s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:00.276197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:58.917257Z","time spent":"1.358863869s","remote":"127.0.0.1:56622","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-02-10T15:02:00.27626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170076743s","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-02-10T15:02:00.276278Z","caller":"traceutil/trace.go:171","msg":"trace[600472302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:715; }","duration":"1.170116775s","start":"2026-02-10T15:01:59.106156Z","end":"2026-02-10T15:02:00.276273Z","steps":["trace[600472302] 'agreement among raft nodes before linearized reading' (duration: 1.170065814s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:00.276294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.106143Z","time spent":"1.170148045s","remote":"127.0.0.1:56622","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":"warn","ts":"2026-02-10T15:02:00.276202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.066526Z","time spent":"1.20967045s","remote":"127.0.0.1:56622","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-02-10T15:02:00.276507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058767686s","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-02-10T15:02:00.276524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124957535s","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-02-10T15:02:00.276534Z","caller":"traceutil/trace.go:171","msg":"trace[234693708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:715; }","duration":"1.058829958s","start":"2026-02-10T15:01:59.217696Z","end":"2026-02-10T15:02:00.276526Z","steps":["trace[234693708] 'agreement among raft nodes before linearized reading' (duration: 1.058745956s)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:00.276546Z","caller":"traceutil/trace.go:171","msg":"trace[255602629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:715; }","duration":"1.125008246s","start":"2026-02-10T15:01:59.15153Z","end":"2026-02-10T15:02:00.276538Z","steps":["trace[255602629] 'agreement among raft nodes before linearized reading' (duration: 1.124952485s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:00.276553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.217676Z","time spent":"1.058873138s","remote":"127.0.0.1:56622","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-02-10T15:02:00.276564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.151514Z","time spent":"1.125046436s","remote":"127.0.0.1:56622","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-02-10T15:02:00.276655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.630312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:00.276672Z","caller":"traceutil/trace.go:171","msg":"trace[920716614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:715; }","duration":"864.680453ms","start":"2026-02-10T15:01:59.411986Z","end":"2026-02-10T15:02:00.276667Z","steps":["trace[920716614] 'agreement among raft nodes before linearized reading' (duration: 864.654112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:00.276687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.411968Z","time spent":"864.716264ms","remote":"127.0.0.1:50866","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-02-10T15:02:02.528448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.735969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:02:02.528644Z","caller":"traceutil/trace.go:171","msg":"trace[1921405472] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"189.531791ms","start":"2026-02-10T15:02:02.3391Z","end":"2026-02-10T15:02:02.528631Z","steps":["trace[1921405472] 'process raft request' (duration: 189.46461ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:02.528927Z","caller":"traceutil/trace.go:171","msg":"trace[2071167874] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"192.784827ms","start":"2026-02-10T15:02:02.336134Z","end":"2026-02-10T15:02:02.528918Z","steps":["trace[2071167874] 'process raft request' (duration: 16.525027ms)","trace[2071167874] 'compare' (duration: 175.645758ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:02.529017Z","caller":"traceutil/trace.go:171","msg":"trace[890182873] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"192.822788ms","start":"2026-02-10T15:02:02.336189Z","end":"2026-02-10T15:02:02.529012Z","steps":["trace[890182873] 'process raft request' (duration: 192.325418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:04.162683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.162614ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T15:02:04.73019Z","caller":"traceutil/trace.go:171","msg":"trace[1140444504] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:753; }","duration":"315.970396ms","start":"2026-02-10T15:02:04.414202Z","end":"2026-02-10T15:02:04.730173Z","steps":["trace[1140444504] 'read index received' (duration: 315.803333ms)","trace[1140444504] 'applied index is now lower than readState.Index' (duration: 166.403µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:04.730266Z","caller":"traceutil/trace.go:171","msg":"trace[1551772304] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"345.260453ms","start":"2026-02-10T15:02:04.384997Z","end":"2026-02-10T15:02:04.730257Z","steps":["trace[1551772304] 'process raft request' (duration: 345.067129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:04.730365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:04.384983Z","time spent":"345.310214ms","remote":"127.0.0.1:56622","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-02-10T15:02:04.730424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.81348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-10T15:02:04.730477Z","caller":"traceutil/trace.go:171","msg":"trace[1473494196] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:727; }","duration":"197.895561ms","start":"2026-02-10T15:02:04.532571Z","end":"2026-02-10T15:02:04.730466Z","steps":["trace[1473494196] 'agreement among raft nodes before linearized reading' (duration: 197.770828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:04.730551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.348714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:04.730563Z","caller":"traceutil/trace.go:171","msg":"trace[508185299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"316.401105ms","start":"2026-02-10T15:02:04.414158Z","end":"2026-02-10T15:02:04.730559Z","steps":["trace[508185299] 'agreement among raft nodes before linearized reading' (duration: 316.381674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:04.730575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:04.414137Z","time spent":"316.434775ms","remote":"127.0.0.1:50866","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-02-10T15:02:04.730675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.566374ms","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-02-10T15:02:04.730689Z","caller":"traceutil/trace.go:171","msg":"trace[727917743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:727; }","duration":"196.605964ms","start":"2026-02-10T15:02:04.534078Z","end":"2026-02-10T15:02:04.730684Z","steps":["trace[727917743] 'agreement among raft nodes before linearized reading' (duration: 196.566684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:04.730777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.413252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-10T15:02:04.730804Z","caller":"traceutil/trace.go:171","msg":"trace[1932542474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:727; }","duration":"197.445952ms","start":"2026-02-10T15:02:04.533348Z","end":"2026-02-10T15:02:04.730794Z","steps":["trace[1932542474] 'agreement among raft nodes before linearized reading' (duration: 197.355551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:08.586224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.658312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:02:08.586404Z","caller":"traceutil/trace.go:171","msg":"trace[675256431] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:763; }","duration":"188.951548ms","start":"2026-02-10T15:02:08.397434Z","end":"2026-02-10T15:02:08.586386Z","steps":["trace[675256431] 'read index received' (duration: 123.400673ms)","trace[675256431] 'applied index is now lower than readState.Index' (duration: 65.549785ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:08.58649Z","caller":"traceutil/trace.go:171","msg":"trace[677281696] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"261.789592ms","start":"2026-02-10T15:02:08.32469Z","end":"2026-02-10T15:02:08.58648Z","steps":["trace[677281696] 'process raft request' (duration: 72.785473ms)","trace[677281696] 'compare' (duration: 188.55087ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:08.586882Z","caller":"traceutil/trace.go:171","msg":"trace[1501336326] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"204.085537ms","start":"2026-02-10T15:02:08.382786Z","end":"2026-02-10T15:02:08.586871Z","steps":["trace[1501336326] 'process raft request' (duration: 203.529816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:08.587082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.643292ms","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-02-10T15:02:08.58711Z","caller":"traceutil/trace.go:171","msg":"trace[1222002906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:737; }","duration":"189.672832ms","start":"2026-02-10T15:02:08.397429Z","end":"2026-02-10T15:02:08.587101Z","steps":["trace[1222002906] 'agreement among raft nodes before linearized reading' (duration: 189.57132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:08.587545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.6187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:08.587584Z","caller":"traceutil/trace.go:171","msg":"trace[717619686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"179.956867ms","start":"2026-02-10T15:02:08.407617Z","end":"2026-02-10T15:02:08.587574Z","steps":["trace[717619686] 'agreement among raft nodes before linearized reading' (duration: 179.60676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:09.003542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.29678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:02:09.004142Z","caller":"traceutil/trace.go:171","msg":"trace[413719717] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"411.142854ms","start":"2026-02-10T15:02:08.592898Z","end":"2026-02-10T15:02:09.004041Z","steps":["trace[413719717] 'read index received' (duration: 100.206851ms)","trace[413719717] 'applied index is now lower than readState.Index' (duration: 310.933603ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:09.004627Z","caller":"traceutil/trace.go:171","msg":"trace[509893091] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"412.31706ms","start":"2026-02-10T15:02:08.592289Z","end":"2026-02-10T15:02:09.004606Z","steps":["trace[509893091] 'process raft request' (duration: 100.867785ms)","trace[509893091] 'compare' (duration: 309.930273ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:09.005784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:08.592282Z","time spent":"413.427272ms","remote":"127.0.0.1:56622","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-02-10T15:02:09.005045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.597494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-10T15:02:09.00616Z","caller":"traceutil/trace.go:171","msg":"trace[31477409] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:738; }","duration":"221.738406ms","start":"2026-02-10T15:02:08.78441Z","end":"2026-02-10T15:02:09.006149Z","steps":["trace[31477409] 'agreement among raft nodes before linearized reading' (duration: 220.500592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:09.005112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.208017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-10T15:02:09.006272Z","caller":"traceutil/trace.go:171","msg":"trace[1407280642] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:738; }","duration":"413.39186ms","start":"2026-02-10T15:02:08.592874Z","end":"2026-02-10T15:02:09.006266Z","steps":["trace[1407280642] 'agreement among raft nodes before linearized reading' (duration: 412.203686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:09.006321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:08.592863Z","time spent":"413.447871ms","remote":"127.0.0.1:56626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-10T15:02:09.005222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.221747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-10T15:02:09.006555Z","caller":"traceutil/trace.go:171","msg":"trace[2100884196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:738; }","duration":"224.555004ms","start":"2026-02-10T15:02:08.78198Z","end":"2026-02-10T15:02:09.006535Z","steps":["trace[2100884196] 'agreement among raft nodes before linearized reading' (duration: 223.167986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:09.005327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.167657ms","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-02-10T15:02:09.006761Z","caller":"traceutil/trace.go:171","msg":"trace[1580032682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:738; }","duration":"226.627277ms","start":"2026-02-10T15:02:08.780124Z","end":"2026-02-10T15:02:09.006751Z","steps":["trace[1580032682] 'agreement among raft nodes before linearized reading' (duration: 225.133597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:09.005481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.514418ms","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-02-10T15:02:09.006867Z","caller":"traceutil/trace.go:171","msg":"trace[240081099] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:738; }","duration":"263.940286ms","start":"2026-02-10T15:02:08.742921Z","end":"2026-02-10T15:02:09.006862Z","steps":["trace[240081099] 'agreement among raft nodes before linearized reading' (duration: 261.908525ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:09.143631Z","caller":"traceutil/trace.go:171","msg":"trace[1846650802] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"131.654992ms","start":"2026-02-10T15:02:09.011953Z","end":"2026-02-10T15:02:09.143608Z","steps":["trace[1846650802] 'process raft request' (duration: 131.531939ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:09.151026Z","caller":"traceutil/trace.go:171","msg":"trace[1807696670] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"137.512901ms","start":"2026-02-10T15:02:09.013492Z","end":"2026-02-10T15:02:09.151005Z","steps":["trace[1807696670] 'process raft request' (duration: 137.052102ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:09.151313Z","caller":"traceutil/trace.go:171","msg":"trace[415241752] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"137.825247ms","start":"2026-02-10T15:02:09.013471Z","end":"2026-02-10T15:02:09.151296Z","steps":["trace[415241752] 'process raft request' (duration: 136.949119ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:09.151713Z","caller":"traceutil/trace.go:171","msg":"trace[223706914] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"137.421369ms","start":"2026-02-10T15:02:09.014278Z","end":"2026-02-10T15:02:09.151699Z","steps":["trace[223706914] 'process raft request' (duration: 136.317817ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:13.417058Z","caller":"traceutil/trace.go:171","msg":"trace[1409852090] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"179.241601ms","start":"2026-02-10T15:02:13.237802Z","end":"2026-02-10T15:02:13.417044Z","steps":["trace[1409852090] 'process raft request' (duration: 179.132069ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:13.417284Z","caller":"traceutil/trace.go:171","msg":"trace[421191507] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"178.149998ms","start":"2026-02-10T15:02:13.239125Z","end":"2026-02-10T15:02:13.417275Z","steps":["trace[421191507] 'process raft request' (duration: 177.897263ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:13.417571Z","caller":"traceutil/trace.go:171","msg":"trace[1445812684] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"175.905193ms","start":"2026-02-10T15:02:13.241658Z","end":"2026-02-10T15:02:13.417563Z","steps":["trace[1445812684] 'process raft request' (duration: 175.581347ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:21.218064Z","caller":"traceutil/trace.go:171","msg":"trace[1843477840] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:804; }","duration":"140.391569ms","start":"2026-02-10T15:02:21.077637Z","end":"2026-02-10T15:02:21.218029Z","steps":["trace[1843477840] 'read index received' (duration: 45.908026ms)","trace[1843477840] 'applied index is now lower than readState.Index' (duration: 94.483033ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:21.218262Z","caller":"traceutil/trace.go:171","msg":"trace[2060660209] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"185.56821ms","start":"2026-02-10T15:02:21.032686Z","end":"2026-02-10T15:02:21.218254Z","steps":["trace[2060660209] 'process raft request' (duration: 90.868272ms)","trace[2060660209] 'compare' (duration: 94.182308ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:21.218488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.844958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:02:21.21851Z","caller":"traceutil/trace.go:171","msg":"trace[586882180] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:774; }","duration":"141.003063ms","start":"2026-02-10T15:02:21.077502Z","end":"2026-02-10T15:02:21.218505Z","steps":["trace[586882180] 'agreement among raft nodes before linearized reading' (duration: 140.92087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:21.47577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.888414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:02:21.475888Z","caller":"traceutil/trace.go:171","msg":"trace[1233239123] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"118.85549ms","start":"2026-02-10T15:02:21.357016Z","end":"2026-02-10T15:02:21.475871Z","steps":["trace[1233239123] 'read index received' (duration: 32.62µs)","trace[1233239123] 'applied index is now lower than readState.Index' (duration: 118.82166ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:21.475987Z","caller":"traceutil/trace.go:171","msg":"trace[1898505133] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"193.868528ms","start":"2026-02-10T15:02:21.282106Z","end":"2026-02-10T15:02:21.475975Z","steps":["trace[1898505133] 'process raft request' (duration: 39.673798ms)","trace[1898505133] 'compare' (duration: 153.758702ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:21.476251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.103115ms","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":"info","ts":"2026-02-10T15:02:21.476321Z","caller":"traceutil/trace.go:171","msg":"trace[1866158074] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:781; }","duration":"119.181508ms","start":"2026-02-10T15:02:21.35712Z","end":"2026-02-10T15:02:21.476301Z","steps":["trace[1866158074] 'agreement among raft nodes before linearized reading' (duration: 119.015474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:21.476717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.667037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-10T15:02:21.476768Z","caller":"traceutil/trace.go:171","msg":"trace[378346417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:781; }","duration":"119.77128ms","start":"2026-02-10T15:02:21.35698Z","end":"2026-02-10T15:02:21.476751Z","steps":["trace[378346417] 'agreement among raft nodes before linearized reading' (duration: 119.625207ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:22.022532Z","caller":"traceutil/trace.go:171","msg":"trace[875620517] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"102.526319ms","start":"2026-02-10T15:02:21.919986Z","end":"2026-02-10T15:02:22.022512Z","steps":["trace[875620517] 'process raft request' (duration: 68.305001ms)","trace[875620517] 'compare' (duration: 33.927111ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:22.022804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.872946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-02-10T15:02:22.022862Z","caller":"traceutil/trace.go:171","msg":"trace[947278830] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:823; }","duration":"102.947037ms","start":"2026-02-10T15:02:21.919905Z","end":"2026-02-10T15:02:22.022852Z","steps":["trace[947278830] 'agreement among raft nodes before linearized reading' (duration: 102.799045ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:23.179218Z","caller":"traceutil/trace.go:171","msg":"trace[694251336] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"121.551926ms","start":"2026-02-10T15:02:23.057645Z","end":"2026-02-10T15:02:23.179197Z","steps":["trace[694251336] 'process raft request' (duration: 121.383062ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:23.316519Z","caller":"traceutil/trace.go:171","msg":"trace[447332120] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"126.560667ms","start":"2026-02-10T15:02:23.189943Z","end":"2026-02-10T15:02:23.316504Z","steps":["trace[447332120] 'process raft request' (duration: 126.153439ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:23.45717Z","caller":"traceutil/trace.go:171","msg":"trace[207211005] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"131.947658ms","start":"2026-02-10T15:02:23.325192Z","end":"2026-02-10T15:02:23.45714Z","steps":["trace[207211005] 'process raft request' (duration: 99.16558ms)","trace[207211005] 'compare' (duration: 31.379939ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:29.383591Z","caller":"traceutil/trace.go:171","msg":"trace[1681653693] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"460.161063ms","start":"2026-02-10T15:02:28.923399Z","end":"2026-02-10T15:02:29.38356Z","steps":["trace[1681653693] 'process raft request' (duration: 460.068921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:29.383706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:28.923384Z","time spent":"460.268015ms","remote":"127.0.0.1:56622","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-02-10T15:02:29.599865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.773871ms","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-02-10T15:02:29.599913Z","caller":"traceutil/trace.go:171","msg":"trace[394826474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:844; }","duration":"127.885264ms","start":"2026-02-10T15:02:29.472016Z","end":"2026-02-10T15:02:29.599901Z","steps":["trace[394826474] 'range keys from in-memory index tree' (duration: 127.6852ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:35.91413Z","caller":"traceutil/trace.go:171","msg":"trace[1341895433] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:895; }","duration":"214.619661ms","start":"2026-02-10T15:02:35.699491Z","end":"2026-02-10T15:02:35.914111Z","steps":["trace[1341895433] 'read index received' (duration: 214.411867ms)","trace[1341895433] 'applied index is now lower than readState.Index' (duration: 206.864µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:35.914464Z","caller":"traceutil/trace.go:171","msg":"trace[1538235811] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"217.180243ms","start":"2026-02-10T15:02:35.697276Z","end":"2026-02-10T15:02:35.914456Z","steps":["trace[1538235811] 'process raft request' (duration: 216.686233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:35.914736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.229334ms","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-02-10T15:02:35.914779Z","caller":"traceutil/trace.go:171","msg":"trace[1213341717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:861; }","duration":"215.294505ms","start":"2026-02-10T15:02:35.699475Z","end":"2026-02-10T15:02:35.914769Z","steps":["trace[1213341717] 'agreement among raft nodes before linearized reading' (duration: 215.155122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:35.914934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.760613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-10T15:02:35.914952Z","caller":"traceutil/trace.go:171","msg":"trace[880886082] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:861; }","duration":"211.792885ms","start":"2026-02-10T15:02:35.703154Z","end":"2026-02-10T15:02:35.914947Z","steps":["trace[880886082] 'agreement among raft nodes before linearized reading' (duration: 211.727553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:35.915697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.833603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-10T15:02:35.91572Z","caller":"traceutil/trace.go:171","msg":"trace[436564807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:861; }","duration":"126.898124ms","start":"2026-02-10T15:02:35.788816Z","end":"2026-02-10T15:02:35.915714Z","steps":["trace[436564807] 'agreement among raft nodes before linearized reading' (duration: 126.7176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:35.915997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.080619ms","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-02-10T15:02:35.91602Z","caller":"traceutil/trace.go:171","msg":"trace[1275466132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:861; }","duration":"211.13575ms","start":"2026-02-10T15:02:35.704877Z","end":"2026-02-10T15:02:35.916012Z","steps":["trace[1275466132] 'agreement among raft nodes before linearized reading' (duration: 211.053178ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:36.237361Z","caller":"traceutil/trace.go:171","msg":"trace[1919350388] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"315.17517ms","start":"2026-02-10T15:02:35.92217Z","end":"2026-02-10T15:02:36.237345Z","steps":["trace[1919350388] 'process raft request' (duration: 296.371347ms)","trace[1919350388] 'compare' (duration: 18.609269ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:36.237444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:35.922155Z","time spent":"315.244811ms","remote":"127.0.0.1:56622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:36.240234Z","caller":"traceutil/trace.go:171","msg":"trace[1106790157] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"317.764773ms","start":"2026-02-10T15:02:35.922459Z","end":"2026-02-10T15:02:36.240223Z","steps":["trace[1106790157] 'process raft request' (duration: 317.656671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:36.240279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:35.922451Z","time spent":"317.805364ms","remote":"127.0.0.1:56622","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-02-10T15:02:36.240562Z","caller":"traceutil/trace.go:171","msg":"trace[1774658597] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"309.567886ms","start":"2026-02-10T15:02:35.930988Z","end":"2026-02-10T15:02:36.240556Z","steps":["trace[1774658597] 'process raft request' (duration: 309.184048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:36.240606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:35.930974Z","time spent":"309.600276ms","remote":"127.0.0.1:56622","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-02-10T15:02:36.240766Z","caller":"traceutil/trace.go:171","msg":"trace[1463122357] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"307.001743ms","start":"2026-02-10T15:02:35.933744Z","end":"2026-02-10T15:02:36.240746Z","steps":["trace[1463122357] 'process raft request' (duration: 306.446492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:36.240805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:35.933733Z","time spent":"307.051584ms","remote":"127.0.0.1:56622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:38.022821Z","caller":"traceutil/trace.go:171","msg":"trace[43713060] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"101.810663ms","start":"2026-02-10T15:02:37.920987Z","end":"2026-02-10T15:02:38.022797Z","steps":["trace[43713060] 'process raft request' (duration: 101.64511ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:38.512865Z","caller":"traceutil/trace.go:171","msg":"trace[818585808] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"267.044669ms","start":"2026-02-10T15:02:38.245804Z","end":"2026-02-10T15:02:38.512849Z","steps":["trace[818585808] 'read index received' (duration: 266.963857ms)","trace[818585808] 'applied index is now lower than readState.Index' (duration: 79.922µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:38.513032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.211572ms","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-02-10T15:02:38.513049Z","caller":"traceutil/trace.go:171","msg":"trace[969496360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:866; }","duration":"267.256293ms","start":"2026-02-10T15:02:38.245787Z","end":"2026-02-10T15:02:38.513044Z","steps":["trace[969496360] 'agreement among raft nodes before linearized reading' (duration: 267.140481ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:38.705709Z","caller":"traceutil/trace.go:171","msg":"trace[309560863] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"458.329686ms","start":"2026-02-10T15:02:38.247356Z","end":"2026-02-10T15:02:38.705685Z","steps":["trace[309560863] 'process raft request' (duration: 451.317043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:38.705832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:38.247347Z","time spent":"458.420398ms","remote":"127.0.0.1:56622","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-02-10T15:02:38.706078Z","caller":"traceutil/trace.go:171","msg":"trace[453642999] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"457.735323ms","start":"2026-02-10T15:02:38.248339Z","end":"2026-02-10T15:02:38.706074Z","steps":["trace[453642999] 'process raft request' (duration: 457.071029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:38.706105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:38.24833Z","time spent":"457.759253ms","remote":"127.0.0.1:56622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:38.706267Z","caller":"traceutil/trace.go:171","msg":"trace[1519986533] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"456.742113ms","start":"2026-02-10T15:02:38.24952Z","end":"2026-02-10T15:02:38.706262Z","steps":["trace[1519986533] 'process raft request' (duration: 456.504178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:38.7063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:38.249514Z","time spent":"456.764173ms","remote":"127.0.0.1:56622","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-02-10T15:02:38.70641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.998936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:02:38.706427Z","caller":"traceutil/trace.go:171","msg":"trace[1391387377] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:869; }","duration":"343.016597ms","start":"2026-02-10T15:02:38.363405Z","end":"2026-02-10T15:02:38.706422Z","steps":["trace[1391387377] 'agreement among raft nodes before linearized reading' (duration: 342.966796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:38.706441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:38.363389Z","time spent":"343.048257ms","remote":"127.0.0.1:56554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-02-10T15:02:38.706054Z","caller":"traceutil/trace.go:171","msg":"trace[638698607] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:902; }","duration":"193.144754ms","start":"2026-02-10T15:02:38.512903Z","end":"2026-02-10T15:02:38.706048Z","steps":["trace[638698607] 'read index received' (duration: 185.772484ms)","trace[638698607] 'applied index is now lower than readState.Index' (duration: 7.37187ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:38.706592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.272571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:38.706606Z","caller":"traceutil/trace.go:171","msg":"trace[1604808086] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:869; }","duration":"343.314152ms","start":"2026-02-10T15:02:38.363288Z","end":"2026-02-10T15:02:38.706602Z","steps":["trace[1604808086] 'agreement among raft nodes before linearized reading' (duration: 343.289412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:38.706632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:38.363274Z","time spent":"343.354063ms","remote":"127.0.0.1:56454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-02-10T15:02:38.717162Z","caller":"traceutil/trace.go:171","msg":"trace[1808803235] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"201.654118ms","start":"2026-02-10T15:02:38.515494Z","end":"2026-02-10T15:02:38.717148Z","steps":["trace[1808803235] 'process raft request' (duration: 201.620957ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:38.717186Z","caller":"traceutil/trace.go:171","msg":"trace[1792736005] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"203.565587ms","start":"2026-02-10T15:02:38.513596Z","end":"2026-02-10T15:02:38.717162Z","steps":["trace[1792736005] 'process raft request' (duration: 203.458545ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:42.373608Z","caller":"traceutil/trace.go:171","msg":"trace[1433704552] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"148.825301ms","start":"2026-02-10T15:02:42.22476Z","end":"2026-02-10T15:02:42.373585Z","steps":["trace[1433704552] 'process raft request' (duration: 133.727194ms)","trace[1433704552] 'compare' (duration: 14.997745ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:46.299739Z","caller":"traceutil/trace.go:171","msg":"trace[873703668] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"130.172891ms","start":"2026-02-10T15:02:46.169551Z","end":"2026-02-10T15:02:46.299723Z","steps":["trace[873703668] 'process raft request' (duration: 130.08371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:54.535974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.340943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:54.536066Z","caller":"traceutil/trace.go:171","msg":"trace[1542799105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"123.498896ms","start":"2026-02-10T15:02:54.41255Z","end":"2026-02-10T15:02:54.536049Z","steps":["trace[1542799105] 'range keys from in-memory index tree' (duration: 123.275542ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:58.536757Z","caller":"traceutil/trace.go:171","msg":"trace[2083360065] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"125.792872ms","start":"2026-02-10T15:02:58.410941Z","end":"2026-02-10T15:02:58.536734Z","steps":["trace[2083360065] 'read index received' (duration: 125.549347ms)","trace[2083360065] 'applied index is now lower than readState.Index' (duration: 242.645µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:58.53696Z","caller":"traceutil/trace.go:171","msg":"trace[17637740] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"159.936392ms","start":"2026-02-10T15:02:58.376994Z","end":"2026-02-10T15:02:58.53693Z","steps":["trace[17637740] 'process raft request' (duration: 159.553644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:58.537071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.116999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:58.537544Z","caller":"traceutil/trace.go:171","msg":"trace[941091933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"126.628348ms","start":"2026-02-10T15:02:58.410897Z","end":"2026-02-10T15:02:58.537526Z","steps":["trace[941091933] 'agreement among raft nodes before linearized reading' (duration: 126.125429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:01.634797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.563398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-10T15:03:01.634935Z","caller":"traceutil/trace.go:171","msg":"trace[1435023993] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:970; }","duration":"124.741061ms","start":"2026-02-10T15:03:01.510174Z","end":"2026-02-10T15:03:01.634915Z","steps":["trace[1435023993] 'range keys from in-memory index tree' (duration: 120.26944ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:59.210991Z","caller":"traceutil/trace.go:171","msg":"trace[1957889199] linearizableReadLoop","detail":"{readStateIndex:1811; appliedIndex:1810; }","duration":"146.476986ms","start":"2026-02-10T15:05:59.064469Z","end":"2026-02-10T15:05:59.210946Z","steps":["trace[1957889199] 'read index received' (duration: 31.283111ms)","trace[1957889199] 'applied index is now lower than readState.Index' (duration: 115.192335ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:05:59.214652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.159689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-02-10T15:05:59.214831Z","caller":"traceutil/trace.go:171","msg":"trace[1803623271] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1734; }","duration":"150.372104ms","start":"2026-02-10T15:05:59.064443Z","end":"2026-02-10T15:05:59.214815Z","steps":["trace[1803623271] 'agreement among raft nodes before linearized reading' (duration: 150.091159ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:59.210985Z","caller":"traceutil/trace.go:171","msg":"trace[1614250636] transaction","detail":"{read_only:false; response_revision:1734; number_of_response:1; }","duration":"154.664491ms","start":"2026-02-10T15:05:59.056294Z","end":"2026-02-10T15:05:59.210958Z","steps":["trace[1614250636] 'process raft request' (duration: 71.835199ms)","trace[1614250636] 'compare' (duration: 82.301471ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:06:03.53432Z","caller":"traceutil/trace.go:171","msg":"trace[1698904955] transaction","detail":"{read_only:false; response_revision:1756; number_of_response:1; }","duration":"117.084903ms","start":"2026-02-10T15:06:03.417192Z","end":"2026-02-10T15:06:03.534277Z","steps":["trace[1698904955] 'process raft request' (duration: 83.976005ms)","trace[1698904955] 'compare' (duration: 32.459005ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:06:06.478705Z","caller":"traceutil/trace.go:171","msg":"trace[622778162] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"105.892206ms","start":"2026-02-10T15:06:06.372787Z","end":"2026-02-10T15:06:06.478679Z","steps":["trace[622778162] 'process raft request' (duration: 105.746943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:06:20.26798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.061094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-10T15:06:20.26805Z","caller":"traceutil/trace.go:171","msg":"trace[1052669557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1834; }","duration":"181.184907ms","start":"2026-02-10T15:06:20.086847Z","end":"2026-02-10T15:06:20.268032Z","steps":["trace[1052669557] 'range keys from in-memory index tree' (duration: 180.851981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:06:20.268254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.78477ms","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-02-10T15:06:20.30184Z","caller":"traceutil/trace.go:171","msg":"trace[624673411] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1834; }","duration":"166.366458ms","start":"2026-02-10T15:06:20.135458Z","end":"2026-02-10T15:06:20.301825Z","steps":["trace[624673411] 'range keys from in-memory index tree' (duration: 132.77797ms)"],"step_count":1}