{"level":"warn","ts":"2026-02-08T21:41:14.794548Z","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-08T21:41:14.795157Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.212: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://162.253.55.212:2380","--initial-cluster=instance=https://162.253.55.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.212: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-08T21:41:14.795276Z","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-08T21:41:14.795332Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-08T21:41:14.795369Z","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-08T21:41:14.796068Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"]} {"level":"info","ts":"2026-02-08T21:41:14.799073Z","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://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.212: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-08T21:41:14.80701Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.993023ms"} {"level":"info","ts":"2026-02-08T21:41:14.817091Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d078b1e39c21939c","cluster-id":"cdc9ba8de57e3589"} {"level":"info","ts":"2026-02-08T21:41:14.817193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T21:41:14.817224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 0"} {"level":"info","ts":"2026-02-08T21:41:14.817243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d078b1e39c21939c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T21:41:14.817258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 1"} {"level":"info","ts":"2026-02-08T21:41:14.817306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"warn","ts":"2026-02-08T21:41:14.829983Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T21:41:14.832359Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T21:41:14.83877Z","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-08T21:41:14.848523Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d078b1e39c21939c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T21:41:14.855047Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d078b1e39c21939c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T21:41:14.855306Z","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-08T21:41:14.855366Z","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-08T21:41:14.855386Z","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-08T21:41:14.855679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"info","ts":"2026-02-08T21:41:14.855779Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","added-peer-id":"d078b1e39c21939c","added-peer-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-08T21:41:14.868244Z","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-08T21:41:14.868595Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-08T21:41:14.86864Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-08T21:41:14.870572Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d078b1e39c21939c","initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T21:41:14.870634Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T21:41:15.117572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T21:41:15.117637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T21:41:15.117763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgPreVoteResp from d078b1e39c21939c at term 1"} {"level":"info","ts":"2026-02-08T21:41:15.117781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became candidate at term 2"} {"level":"info","ts":"2026-02-08T21:41:15.117825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgVoteResp from d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-08T21:41:15.11786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became leader at term 2"} {"level":"info","ts":"2026-02-08T21:41:15.117908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d078b1e39c21939c elected leader d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-08T21:41:15.119364Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d078b1e39c21939c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.212:2379]}","request-path":"/0/members/d078b1e39c21939c/attributes","cluster-id":"cdc9ba8de57e3589","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T21:41:15.120717Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:15.12087Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T21:41:15.12139Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T21:41:15.121455Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T21:41:15.121533Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T21:41:15.139485Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-08T21:41:15.139604Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:15.139672Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:15.139701Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:15.154411Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.212:2379"} {"level":"warn","ts":"2026-02-08T21:41:34.168384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.568731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-08T21:41:34.168459Z","caller":"traceutil/trace.go:171","msg":"trace[60444266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:449; }","duration":"841.684173ms","start":"2026-02-08T21:41:33.326764Z","end":"2026-02-08T21:41:34.168449Z","steps":["trace[60444266] 'range keys from in-memory index tree' (duration: 841.484968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:34.168501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:33.326746Z","time spent":"841.748705ms","remote":"127.0.0.1:56442","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-08T21:41:34.168835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208172008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-08T21:41:34.168857Z","caller":"traceutil/trace.go:171","msg":"trace[965171961] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:449; }","duration":"1.208221209s","start":"2026-02-08T21:41:32.960631Z","end":"2026-02-08T21:41:34.168852Z","steps":["trace[965171961] 'range keys from in-memory index tree' (duration: 1.208098347s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:34.168874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:32.960616Z","time spent":"1.20825106s","remote":"127.0.0.1:56362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6922,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-08T21:41:34.16906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.990178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-08T21:41:34.169032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015205992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-08T21:41:34.169082Z","caller":"traceutil/trace.go:171","msg":"trace[1074531051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:449; }","duration":"851.034939ms","start":"2026-02-08T21:41:33.318039Z","end":"2026-02-08T21:41:34.169074Z","steps":["trace[1074531051] 'range keys from in-memory index tree' (duration: 850.942867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:34.169097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:33.318025Z","time spent":"851.06855ms","remote":"127.0.0.1:56442","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":"info","ts":"2026-02-08T21:41:34.169106Z","caller":"traceutil/trace.go:171","msg":"trace[2045323616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:449; }","duration":"1.015322905s","start":"2026-02-08T21:41:33.153765Z","end":"2026-02-08T21:41:34.169088Z","steps":["trace[2045323616] 'range keys from in-memory index tree' (duration: 1.015112429s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:34.16915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:33.153749Z","time spent":"1.015391996s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:41:46.553153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.221071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:41:46.553318Z","caller":"traceutil/trace.go:171","msg":"trace[1138534025] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"260.4139ms","start":"2026-02-08T21:41:46.292886Z","end":"2026-02-08T21:41:46.5533Z","steps":["trace[1138534025] 'read index received' (duration: 142.140775ms)","trace[1138534025] 'applied index is now lower than readState.Index' (duration: 118.272065ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:41:46.553672Z","caller":"traceutil/trace.go:171","msg":"trace[893359096] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"276.628534ms","start":"2026-02-08T21:41:46.27703Z","end":"2026-02-08T21:41:46.553658Z","steps":["trace[893359096] 'process raft request' (duration: 158.042952ms)","trace[893359096] 'compare' (duration: 116.993626ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:46.555011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.281387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-08T21:41:46.555088Z","caller":"traceutil/trace.go:171","msg":"trace[288703135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"260.377589ms","start":"2026-02-08T21:41:46.294691Z","end":"2026-02-08T21:41:46.555069Z","steps":["trace[288703135] 'agreement among raft nodes before linearized reading' (duration: 260.195535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:46.555281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.554436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:41:46.555308Z","caller":"traceutil/trace.go:171","msg":"trace[1162849397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"185.602418ms","start":"2026-02-08T21:41:46.369697Z","end":"2026-02-08T21:41:46.5553Z","steps":["trace[1162849397] 'agreement among raft nodes before linearized reading' (duration: 185.562997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:46.559418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.522741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:41:46.559466Z","caller":"traceutil/trace.go:171","msg":"trace[772035645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"266.581832ms","start":"2026-02-08T21:41:46.292859Z","end":"2026-02-08T21:41:46.559441Z","steps":["trace[772035645] 'agreement among raft nodes before linearized reading' (duration: 261.005214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:47.075268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205512552,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T21:41:47.102371Z","caller":"traceutil/trace.go:171","msg":"trace[144181216] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"538.1933ms","start":"2026-02-08T21:41:46.564161Z","end":"2026-02-08T21:41:47.102354Z","steps":["trace[144181216] 'process raft request' (duration: 538.099268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:47.102648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:46.564142Z","time spent":"538.428036ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:47.104491Z","caller":"traceutil/trace.go:171","msg":"trace[1601322306] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"529.793867ms","start":"2026-02-08T21:41:46.574687Z","end":"2026-02-08T21:41:47.104481Z","steps":["trace[1601322306] 'read index received' (duration: 528.126928ms)","trace[1601322306] 'applied index is now lower than readState.Index' (duration: 1.666159ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:47.104668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.980281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4062"} {"level":"info","ts":"2026-02-08T21:41:47.104748Z","caller":"traceutil/trace.go:171","msg":"trace[1861339566] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:516; }","duration":"530.096064ms","start":"2026-02-08T21:41:46.574644Z","end":"2026-02-08T21:41:47.10474Z","steps":["trace[1861339566] 'agreement among raft nodes before linearized reading' (duration: 529.954401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:47.104851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:46.574631Z","time spent":"530.209727ms","remote":"127.0.0.1:56350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4086,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-08T21:41:47.105118Z","caller":"traceutil/trace.go:171","msg":"trace[1823642665] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"538.117489ms","start":"2026-02-08T21:41:46.566991Z","end":"2026-02-08T21:41:47.105109Z","steps":["trace[1823642665] 'process raft request' (duration: 537.443303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:47.105338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:46.566978Z","time spent":"538.240622ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:48.526846Z","caller":"traceutil/trace.go:171","msg":"trace[1914372261] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"158.995253ms","start":"2026-02-08T21:41:48.367829Z","end":"2026-02-08T21:41:48.526825Z","steps":["trace[1914372261] 'read index received' (duration: 158.869891ms)","trace[1914372261] 'applied index is now lower than readState.Index' (duration: 124.852µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:48.526922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.075935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:41:48.526975Z","caller":"traceutil/trace.go:171","msg":"trace[821871501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"159.169358ms","start":"2026-02-08T21:41:48.367801Z","end":"2026-02-08T21:41:48.52697Z","steps":["trace[821871501] 'agreement among raft nodes before linearized reading' (duration: 159.088325ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:48.527107Z","caller":"traceutil/trace.go:171","msg":"trace[1773659612] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"242.655291ms","start":"2026-02-08T21:41:48.284427Z","end":"2026-02-08T21:41:48.527083Z","steps":["trace[1773659612] 'process raft request' (duration: 242.306492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:48.834264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.211531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T21:41:48.834324Z","caller":"traceutil/trace.go:171","msg":"trace[558608462] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:520; }","duration":"219.294332ms","start":"2026-02-08T21:41:48.615011Z","end":"2026-02-08T21:41:48.834305Z","steps":["trace[558608462] 'range keys from in-memory index tree' (duration: 219.14925ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:49.513559Z","caller":"traceutil/trace.go:171","msg":"trace[1873842348] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"401.551213ms","start":"2026-02-08T21:41:49.111993Z","end":"2026-02-08T21:41:49.513544Z","steps":["trace[1873842348] 'read index received' (duration: 401.362229ms)","trace[1873842348] 'applied index is now lower than readState.Index' (duration: 188.494µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:41:49.513629Z","caller":"traceutil/trace.go:171","msg":"trace[1065822404] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"403.504117ms","start":"2026-02-08T21:41:49.11012Z","end":"2026-02-08T21:41:49.513624Z","steps":["trace[1065822404] 'process raft request' (duration: 403.303252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:49.513694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:49.110107Z","time spent":"403.535887ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:41:49.513804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.810519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-08T21:41:49.513819Z","caller":"traceutil/trace.go:171","msg":"trace[2057932881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"401.87294ms","start":"2026-02-08T21:41:49.111941Z","end":"2026-02-08T21:41:49.513814Z","steps":["trace[2057932881] 'agreement among raft nodes before linearized reading' (duration: 401.837479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:49.513833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:49.111925Z","time spent":"401.905081ms","remote":"127.0.0.1:56442","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-08T21:41:49.51391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.94889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:41:49.513967Z","caller":"traceutil/trace.go:171","msg":"trace[161876674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"145.011831ms","start":"2026-02-08T21:41:49.368943Z","end":"2026-02-08T21:41:49.513955Z","steps":["trace[161876674] 'agreement among raft nodes before linearized reading' (duration: 144.9324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:50.149411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.34993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:41:50.149494Z","caller":"traceutil/trace.go:171","msg":"trace[1700558789] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"632.061714ms","start":"2026-02-08T21:41:49.51742Z","end":"2026-02-08T21:41:50.149481Z","steps":["trace[1700558789] 'process raft request' (duration: 417.573841ms)","trace[1700558789] 'compare' (duration: 214.267048ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:50.149537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:49.517413Z","time spent":"632.105415ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:41:50.514176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.284132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:41:50.514238Z","caller":"traceutil/trace.go:171","msg":"trace[1238432765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"143.362193ms","start":"2026-02-08T21:41:50.370864Z","end":"2026-02-08T21:41:50.514226Z","steps":["trace[1238432765] 'range keys from in-memory index tree' (duration: 143.075676ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:50.686229Z","caller":"traceutil/trace.go:171","msg":"trace[303627892] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"103.182508ms","start":"2026-02-08T21:41:50.583027Z","end":"2026-02-08T21:41:50.68621Z","steps":["trace[303627892] 'process raft request' (duration: 35.54175ms)","trace[303627892] 'compare' (duration: 67.568656ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:55.518355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.935039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:41:55.518431Z","caller":"traceutil/trace.go:171","msg":"trace[1513429314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"148.053992ms","start":"2026-02-08T21:41:55.37036Z","end":"2026-02-08T21:41:55.518414Z","steps":["trace[1513429314] 'range keys from in-memory index tree' (duration: 147.870268ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:56.027603Z","caller":"traceutil/trace.go:171","msg":"trace[1713723142] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"421.242346ms","start":"2026-02-08T21:41:55.606341Z","end":"2026-02-08T21:41:56.027583Z","steps":["trace[1713723142] 'read index received' (duration: 421.090592ms)","trace[1713723142] 'applied index is now lower than readState.Index' (duration: 150.904µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:56.027812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.456931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vwdb5\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-08T21:41:56.027845Z","caller":"traceutil/trace.go:171","msg":"trace[1741911925] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vwdb5; range_end:; response_count:1; response_revision:538; }","duration":"421.537283ms","start":"2026-02-08T21:41:55.606298Z","end":"2026-02-08T21:41:56.027835Z","steps":["trace[1741911925] 'agreement among raft nodes before linearized reading' (duration: 421.362169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:56.027877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:55.606261Z","time spent":"421.608524ms","remote":"127.0.0.1:56362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-vwdb5\" "} {"level":"info","ts":"2026-02-08T21:41:56.028216Z","caller":"traceutil/trace.go:171","msg":"trace[1138127185] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"449.019366ms","start":"2026-02-08T21:41:55.579183Z","end":"2026-02-08T21:41:56.028203Z","steps":["trace[1138127185] 'process raft request' (duration: 448.2974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:56.028291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:55.579165Z","time spent":"449.076167ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:41:56.698049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205512614,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:41:57.133431Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.086729721s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T21:41:57.133743Z","caller":"traceutil/trace.go:171","msg":"trace[609351487] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"936.026747ms","start":"2026-02-08T21:41:56.197696Z","end":"2026-02-08T21:41:57.133723Z","steps":["trace[609351487] 'read index received' (duration: 935.850883ms)","trace[609351487] 'applied index is now lower than readState.Index' (duration: 174.994µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:41:57.133832Z","caller":"traceutil/trace.go:171","msg":"trace[401980156] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.087212792s","start":"2026-02-08T21:41:56.04661Z","end":"2026-02-08T21:41:57.133823Z","steps":["trace[401980156] 'process raft request' (duration: 1.086999777s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:57.134083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.630342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:41:57.134137Z","caller":"traceutil/trace.go:171","msg":"trace[2132823444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"762.717834ms","start":"2026-02-08T21:41:56.371406Z","end":"2026-02-08T21:41:57.134124Z","steps":["trace[2132823444] 'agreement among raft nodes before linearized reading' (duration: 762.629192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:57.134164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:56.371389Z","time spent":"762.769825ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:41:57.134354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.665722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-08T21:41:57.134371Z","caller":"traceutil/trace.go:171","msg":"trace[868673550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"936.723483ms","start":"2026-02-08T21:41:56.197642Z","end":"2026-02-08T21:41:57.134366Z","steps":["trace[868673550] 'agreement among raft nodes before linearized reading' (duration: 936.649271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:57.134387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:56.197622Z","time spent":"936.760464ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T21:41:57.134503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.099718ms","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-08T21:41:57.134521Z","caller":"traceutil/trace.go:171","msg":"trace[606540770] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"426.142409ms","start":"2026-02-08T21:41:56.708372Z","end":"2026-02-08T21:41:57.134514Z","steps":["trace[606540770] 'agreement among raft nodes before linearized reading' (duration: 426.089758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:57.134535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:56.708357Z","time spent":"426.17422ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:41:57.134634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.744859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-08T21:41:57.13465Z","caller":"traceutil/trace.go:171","msg":"trace[1286596103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:539; }","duration":"556.793259ms","start":"2026-02-08T21:41:56.577851Z","end":"2026-02-08T21:41:57.134645Z","steps":["trace[1286596103] 'agreement among raft nodes before linearized reading' (duration: 556.747498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:57.134664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:56.577834Z","time spent":"556.827181ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:41:57.133929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:56.046594Z","time spent":"1.087264732s","remote":"127.0.0.1:56362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:57.748339Z","caller":"traceutil/trace.go:171","msg":"trace[1550672553] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"116.637306ms","start":"2026-02-08T21:41:57.631684Z","end":"2026-02-08T21:41:57.748321Z","steps":["trace[1550672553] 'process raft request' (duration: 115.346617ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:00.258261Z","caller":"traceutil/trace.go:171","msg":"trace[674912791] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"157.175171ms","start":"2026-02-08T21:42:00.10106Z","end":"2026-02-08T21:42:00.258235Z","steps":["trace[674912791] 'process raft request' (duration: 157.027808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:00.872859Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205512650,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:42:01.222861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.619362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:01.222955Z","caller":"traceutil/trace.go:171","msg":"trace[574092913] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"850.866556ms","start":"2026-02-08T21:42:00.372077Z","end":"2026-02-08T21:42:01.222943Z","steps":["trace[574092913] 'read index received' (duration: 624.129591ms)","trace[574092913] 'applied index is now lower than readState.Index' (duration: 226.713664ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:01.223013Z","caller":"traceutil/trace.go:171","msg":"trace[508379711] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.121749268s","start":"2026-02-08T21:42:00.101257Z","end":"2026-02-08T21:42:01.223006Z","steps":["trace[508379711] 'process raft request' (duration: 894.917751ms)","trace[508379711] 'compare' (duration: 226.454627ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:01.223049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:00.101249Z","time spent":"1.121779458s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:01.223161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.746666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vwdb5\" ","response":"range_response_count:1 size:17171"} {"level":"info","ts":"2026-02-08T21:42:01.223224Z","caller":"traceutil/trace.go:171","msg":"trace[140499933] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vwdb5; range_end:; response_count:1; response_revision:557; }","duration":"453.842577ms","start":"2026-02-08T21:42:00.769363Z","end":"2026-02-08T21:42:01.223205Z","steps":["trace[140499933] 'agreement among raft nodes before linearized reading' (duration: 453.673254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:01.223259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:00.769346Z","time spent":"453.90603ms","remote":"127.0.0.1:56362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17195,"request content":"key:\"/registry/pods/kube-system/cilium-vwdb5\" "} {"level":"warn","ts":"2026-02-08T21:42:01.223403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.337877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:01.223426Z","caller":"traceutil/trace.go:171","msg":"trace[1212930369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"851.391167ms","start":"2026-02-08T21:42:00.372027Z","end":"2026-02-08T21:42:01.223418Z","steps":["trace[1212930369] 'agreement among raft nodes before linearized reading' (duration: 851.357017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:01.223443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:00.37201Z","time spent":"851.429969ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T21:42:04.635122Z","caller":"traceutil/trace.go:171","msg":"trace[832874717] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"426.682992ms","start":"2026-02-08T21:42:04.208416Z","end":"2026-02-08T21:42:04.635099Z","steps":["trace[832874717] 'read index received' (duration: 426.432165ms)","trace[832874717] 'applied index is now lower than readState.Index' (duration: 249.897µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:04.635402Z","caller":"traceutil/trace.go:171","msg":"trace[908255218] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"463.923189ms","start":"2026-02-08T21:42:04.171467Z","end":"2026-02-08T21:42:04.63539Z","steps":["trace[908255218] 'process raft request' (duration: 463.4923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:04.635708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:04.17145Z","time spent":"463.980211ms","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:04.635923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.50023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-08T21:42:04.635959Z","caller":"traceutil/trace.go:171","msg":"trace[1167588957] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:615; }","duration":"427.557902ms","start":"2026-02-08T21:42:04.208388Z","end":"2026-02-08T21:42:04.635946Z","steps":["trace[1167588957] 'agreement among raft nodes before linearized reading' (duration: 427.49135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:04.63599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:04.208375Z","time spent":"427.607813ms","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4204,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-08T21:42:04.636116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.306449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:04.63614Z","caller":"traceutil/trace.go:171","msg":"trace[1337522612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"267.352749ms","start":"2026-02-08T21:42:04.368779Z","end":"2026-02-08T21:42:04.636132Z","steps":["trace[1337522612] 'agreement among raft nodes before linearized reading' (duration: 267.317588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:04.640023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.963539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-08T21:42:04.640049Z","caller":"traceutil/trace.go:171","msg":"trace[1294458981] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:615; }","duration":"237.022081ms","start":"2026-02-08T21:42:04.403019Z","end":"2026-02-08T21:42:04.640041Z","steps":["trace[1294458981] 'agreement among raft nodes before linearized reading' (duration: 233.555551ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:05.488871Z","caller":"traceutil/trace.go:171","msg":"trace[1572142364] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"133.416284ms","start":"2026-02-08T21:42:05.355435Z","end":"2026-02-08T21:42:05.488852Z","steps":["trace[1572142364] 'process raft request' (duration: 127.288013ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:05.49059Z","caller":"traceutil/trace.go:171","msg":"trace[655926563] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:643; }","duration":"113.578868ms","start":"2026-02-08T21:42:05.376996Z","end":"2026-02-08T21:42:05.490575Z","steps":["trace[655926563] 'read index received' (duration: 105.735957ms)","trace[655926563] 'applied index is now lower than readState.Index' (duration: 7.842231ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:05.490699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.68613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:05.490728Z","caller":"traceutil/trace.go:171","msg":"trace[1300785229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"113.754421ms","start":"2026-02-08T21:42:05.376968Z","end":"2026-02-08T21:42:05.490722Z","steps":["trace[1300785229] 'agreement among raft nodes before linearized reading' (duration: 113.677089ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:05.491008Z","caller":"traceutil/trace.go:171","msg":"trace[294756118] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"127.561238ms","start":"2026-02-08T21:42:05.363441Z","end":"2026-02-08T21:42:05.491002Z","steps":["trace[294756118] 'process raft request' (duration: 124.653391ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:05.491086Z","caller":"traceutil/trace.go:171","msg":"trace[1338673690] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"127.568169ms","start":"2026-02-08T21:42:05.363512Z","end":"2026-02-08T21:42:05.491081Z","steps":["trace[1338673690] 'process raft request' (duration: 126.965125ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:05.693656Z","caller":"traceutil/trace.go:171","msg":"trace[89671006] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"134.835537ms","start":"2026-02-08T21:42:05.558803Z","end":"2026-02-08T21:42:05.693638Z","steps":["trace[89671006] 'process raft request' (duration: 123.566507ms)","trace[89671006] 'compare' (duration: 11.047625ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:11.331821Z","caller":"traceutil/trace.go:171","msg":"trace[1956275024] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"492.925068ms","start":"2026-02-08T21:42:10.838879Z","end":"2026-02-08T21:42:11.331804Z","steps":["trace[1956275024] 'process raft request' (duration: 492.801405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:11.331953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:10.838861Z","time spent":"493.01525ms","remote":"127.0.0.1:56362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:17.569984Z","caller":"traceutil/trace.go:171","msg":"trace[687377392] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:748; }","duration":"105.095991ms","start":"2026-02-08T21:42:17.464873Z","end":"2026-02-08T21:42:17.569969Z","steps":["trace[687377392] 'read index received' (duration: 104.957168ms)","trace[687377392] 'applied index is now lower than readState.Index' (duration: 138.283µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:17.570203Z","caller":"traceutil/trace.go:171","msg":"trace[972792254] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"166.141443ms","start":"2026-02-08T21:42:17.404044Z","end":"2026-02-08T21:42:17.570186Z","steps":["trace[972792254] 'process raft request' (duration: 165.826706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:17.57042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.540181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:17.570451Z","caller":"traceutil/trace.go:171","msg":"trace[839397915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"105.616033ms","start":"2026-02-08T21:42:17.464825Z","end":"2026-02-08T21:42:17.570441Z","steps":["trace[839397915] 'agreement among raft nodes before linearized reading' (duration: 105.537771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:18.089204Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205512959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:42:19.228773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.842253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:19.228883Z","caller":"traceutil/trace.go:171","msg":"trace[896976175] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"1.640617722s","start":"2026-02-08T21:42:17.588226Z","end":"2026-02-08T21:42:19.228844Z","steps":["trace[896976175] 'read index received' (duration: 683.652387ms)","trace[896976175] 'applied index is now lower than readState.Index' (duration: 956.964395ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:19.229064Z","caller":"traceutil/trace.go:171","msg":"trace[623383895] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"1.65557245s","start":"2026-02-08T21:42:17.57348Z","end":"2026-02-08T21:42:19.229053Z","steps":["trace[623383895] 'process raft request' (duration: 698.37929ms)","trace[623383895] 'compare' (duration: 956.366673ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:19.229132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:17.573466Z","time spent":"1.655634302s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:19.229373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.641142693s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-08T21:42:19.229411Z","caller":"traceutil/trace.go:171","msg":"trace[1537423064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:727; }","duration":"1.641200665s","start":"2026-02-08T21:42:17.588201Z","end":"2026-02-08T21:42:19.229401Z","steps":["trace[1537423064] 'agreement among raft nodes before linearized reading' (duration: 1.641089262s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:19.229445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:17.58819Z","time spent":"1.641249315s","remote":"127.0.0.1:56442","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-08T21:42:19.282971Z","caller":"traceutil/trace.go:171","msg":"trace[1690755581] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"1.054915213s","start":"2026-02-08T21:42:18.22797Z","end":"2026-02-08T21:42:19.282886Z","steps":["trace[1690755581] 'process raft request' (duration: 1.054792361s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:19.283117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:18.227946Z","time spent":"1.055087496s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:19.28362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.65836118s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:42:19.283656Z","caller":"traceutil/trace.go:171","msg":"trace[609851375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:728; }","duration":"1.658430982s","start":"2026-02-08T21:42:17.625214Z","end":"2026-02-08T21:42:19.283645Z","steps":["trace[609851375] 'agreement among raft nodes before linearized reading' (duration: 1.65832027s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:19.283685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:17.625201Z","time spent":"1.658477273s","remote":"127.0.0.1:56442","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-08T21:42:19.283902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.555194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T21:42:19.283933Z","caller":"traceutil/trace.go:171","msg":"trace[949676861] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:728; }","duration":"667.611386ms","start":"2026-02-08T21:42:18.616312Z","end":"2026-02-08T21:42:19.283924Z","steps":["trace[949676861] 'agreement among raft nodes before linearized reading' (duration: 667.531854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:19.283994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:18.616299Z","time spent":"667.688407ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.212\" "} {"level":"warn","ts":"2026-02-08T21:42:19.284141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.769337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:19.28417Z","caller":"traceutil/trace.go:171","msg":"trace[1231430972] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:728; }","duration":"794.827118ms","start":"2026-02-08T21:42:18.489335Z","end":"2026-02-08T21:42:19.284162Z","steps":["trace[1231430972] 'agreement among raft nodes before linearized reading' (duration: 794.779627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:19.284202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:18.48932Z","time spent":"794.875079ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:42:19.28433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.96783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:19.28439Z","caller":"traceutil/trace.go:171","msg":"trace[2092135254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"916.048793ms","start":"2026-02-08T21:42:18.368332Z","end":"2026-02-08T21:42:19.284381Z","steps":["trace[2092135254] 'agreement among raft nodes before linearized reading' (duration: 915.979181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:19.284416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:18.368319Z","time spent":"916.091333ms","remote":"127.0.0.1:56176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:42:19.284626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.348433081s","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-08T21:42:19.284655Z","caller":"traceutil/trace.go:171","msg":"trace[1434079685] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:728; }","duration":"1.348484012s","start":"2026-02-08T21:42:17.936162Z","end":"2026-02-08T21:42:19.284646Z","steps":["trace[1434079685] 'agreement among raft nodes before linearized reading' (duration: 1.34839933s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:19.284681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:17.93615Z","time spent":"1.348525044s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-08T21:42:19.376966Z","caller":"traceutil/trace.go:171","msg":"trace[321379794] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"142.688602ms","start":"2026-02-08T21:42:19.234265Z","end":"2026-02-08T21:42:19.376953Z","steps":["trace[321379794] 'process raft request' (duration: 140.791171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:22.368242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.224082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:22.36831Z","caller":"traceutil/trace.go:171","msg":"trace[2135472300] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"785.628123ms","start":"2026-02-08T21:42:21.582669Z","end":"2026-02-08T21:42:22.368297Z","steps":["trace[2135472300] 'process raft request' (duration: 673.299288ms)","trace[2135472300] 'compare' (duration: 112.152321ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:22.368405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:21.582659Z","time spent":"785.671823ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:22.691815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.56521ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-02-08T21:42:22.692181Z","caller":"traceutil/trace.go:171","msg":"trace[979223200] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:762; }","duration":"320.045074ms","start":"2026-02-08T21:42:22.372124Z","end":"2026-02-08T21:42:22.692169Z","steps":["trace[979223200] 'read index received' (duration: 177.061635ms)","trace[979223200] 'applied index is now lower than readState.Index' (duration: 142.982839ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:22.692247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.116785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:22.692265Z","caller":"traceutil/trace.go:171","msg":"trace[465317485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"320.167027ms","start":"2026-02-08T21:42:22.372092Z","end":"2026-02-08T21:42:22.692259Z","steps":["trace[465317485] 'agreement among raft nodes before linearized reading' (duration: 320.110335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:22.692286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:22.372077Z","time spent":"320.202547ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:42:22.692411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:21.713545Z","time spent":"978.865032ms","remote":"127.0.0.1:56246","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-08T21:42:22.692562Z","caller":"traceutil/trace.go:171","msg":"trace[1237346452] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"873.364363ms","start":"2026-02-08T21:42:21.81919Z","end":"2026-02-08T21:42:22.692555Z","steps":["trace[1237346452] 'process raft request' (duration: 872.690598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:22.692615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:21.81917Z","time spent":"873.409283ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:22.69275Z","caller":"traceutil/trace.go:171","msg":"trace[1077683000] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"617.468527ms","start":"2026-02-08T21:42:22.075275Z","end":"2026-02-08T21:42:22.692743Z","steps":["trace[1077683000] 'process raft request' (duration: 616.860033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:22.692781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:22.075244Z","time spent":"617.521098ms","remote":"127.0.0.1:56350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:33.715974Z","caller":"traceutil/trace.go:171","msg":"trace[1797317990] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"105.953059ms","start":"2026-02-08T21:42:33.610003Z","end":"2026-02-08T21:42:33.715956Z","steps":["trace[1797317990] 'process raft request' (duration: 105.820005ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:35.579186Z","caller":"traceutil/trace.go:171","msg":"trace[1260470169] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"190.247977ms","start":"2026-02-08T21:42:35.388922Z","end":"2026-02-08T21:42:35.57917Z","steps":["trace[1260470169] 'process raft request' (duration: 189.846397ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:35.781856Z","caller":"traceutil/trace.go:171","msg":"trace[2090050714] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"198.087884ms","start":"2026-02-08T21:42:35.583751Z","end":"2026-02-08T21:42:35.781839Z","steps":["trace[2090050714] 'process raft request' (duration: 197.754387ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:35.782124Z","caller":"traceutil/trace.go:171","msg":"trace[1197979241] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"197.44176ms","start":"2026-02-08T21:42:35.584675Z","end":"2026-02-08T21:42:35.782117Z","steps":["trace[1197979241] 'process raft request' (duration: 196.952409ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:38.164057Z","caller":"traceutil/trace.go:171","msg":"trace[1160057510] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"377.06654ms","start":"2026-02-08T21:42:37.786973Z","end":"2026-02-08T21:42:38.164039Z","steps":["trace[1160057510] 'read index received' (duration: 376.916817ms)","trace[1160057510] 'applied index is now lower than readState.Index' (duration: 149.003µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:38.164152Z","caller":"traceutil/trace.go:171","msg":"trace[136587928] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"577.952853ms","start":"2026-02-08T21:42:37.586193Z","end":"2026-02-08T21:42:38.164145Z","steps":["trace[136587928] 'process raft request' (duration: 577.752079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:38.164245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:37.586181Z","time spent":"577.990824ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:38.164396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.422148ms","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-08T21:42:38.164416Z","caller":"traceutil/trace.go:171","msg":"trace[164547794] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:785; }","duration":"377.506709ms","start":"2026-02-08T21:42:37.786903Z","end":"2026-02-08T21:42:38.16441Z","steps":["trace[164547794] 'agreement among raft nodes before linearized reading' (duration: 377.427497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:38.164466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:37.786886Z","time spent":"377.575042ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:38.16463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.04059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-08T21:42:38.164682Z","caller":"traceutil/trace.go:171","msg":"trace[1783767442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:785; }","duration":"377.122142ms","start":"2026-02-08T21:42:37.787547Z","end":"2026-02-08T21:42:38.164669Z","steps":["trace[1783767442] 'agreement among raft nodes before linearized reading' (duration: 376.961539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:38.164709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:37.787538Z","time spent":"377.165553ms","remote":"127.0.0.1:56442","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-08T21:42:38.164735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.195799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:42:38.164759Z","caller":"traceutil/trace.go:171","msg":"trace[101188040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:785; }","duration":"373.238079ms","start":"2026-02-08T21:42:37.791514Z","end":"2026-02-08T21:42:38.164752Z","steps":["trace[101188040] 'agreement among raft nodes before linearized reading' (duration: 373.179838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:38.164777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:37.791505Z","time spent":"373.268781ms","remote":"127.0.0.1:56442","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":"info","ts":"2026-02-08T21:42:39.90099Z","caller":"traceutil/trace.go:171","msg":"trace[2084190032] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"367.989797ms","start":"2026-02-08T21:42:39.532965Z","end":"2026-02-08T21:42:39.900954Z","steps":["trace[2084190032] 'process raft request' (duration: 367.871434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:39.901109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:39.532949Z","time spent":"368.096879ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:40.476897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.196536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:40.477028Z","caller":"traceutil/trace.go:171","msg":"trace[1146537854] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:821; }","duration":"100.86458ms","start":"2026-02-08T21:42:40.376154Z","end":"2026-02-08T21:42:40.477019Z","steps":["trace[1146537854] 'read index received' (duration: 73.701µs)","trace[1146537854] 'applied index is now lower than readState.Index' (duration: 100.790269ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:40.477073Z","caller":"traceutil/trace.go:171","msg":"trace[27111287] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"264.815606ms","start":"2026-02-08T21:42:40.212253Z","end":"2026-02-08T21:42:40.477068Z","steps":["trace[27111287] 'process raft request' (duration: 264.746975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:40.477222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.075655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:40.477237Z","caller":"traceutil/trace.go:171","msg":"trace[1263510080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"101.151547ms","start":"2026-02-08T21:42:40.37608Z","end":"2026-02-08T21:42:40.477232Z","steps":["trace[1263510080] 'agreement among raft nodes before linearized reading' (duration: 101.113196ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:40.477185Z","caller":"traceutil/trace.go:171","msg":"trace[1479269983] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"267.824781ms","start":"2026-02-08T21:42:40.209245Z","end":"2026-02-08T21:42:40.47707Z","steps":["trace[1479269983] 'process raft request' (duration: 267.707699ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:40.477594Z","caller":"traceutil/trace.go:171","msg":"trace[1773152744] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"268.435454ms","start":"2026-02-08T21:42:40.209153Z","end":"2026-02-08T21:42:40.477588Z","steps":["trace[1773152744] 'process raft request' (duration: 160.499993ms)","trace[1773152744] 'compare' (duration: 107.135604ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:42.561552Z","caller":"traceutil/trace.go:171","msg":"trace[947325996] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"191.180516ms","start":"2026-02-08T21:42:42.370348Z","end":"2026-02-08T21:42:42.561528Z","steps":["trace[947325996] 'read index received' (duration: 190.999802ms)","trace[947325996] 'applied index is now lower than readState.Index' (duration: 179.644µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:42.561848Z","caller":"traceutil/trace.go:171","msg":"trace[1610043075] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"305.094536ms","start":"2026-02-08T21:42:42.256737Z","end":"2026-02-08T21:42:42.561832Z","steps":["trace[1610043075] 'process raft request' (duration: 304.665417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:42.561964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:42.256726Z","time spent":"305.159287ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:42.563388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.672068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:42.563866Z","caller":"traceutil/trace.go:171","msg":"trace[621705538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"193.530357ms","start":"2026-02-08T21:42:42.370316Z","end":"2026-02-08T21:42:42.563847Z","steps":["trace[621705538] 'agreement among raft nodes before linearized reading' (duration: 192.604457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:43.93857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205513167,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:42:44.187043Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.096190783s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T21:42:44.187282Z","caller":"traceutil/trace.go:171","msg":"trace[1421154170] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"1.09652649s","start":"2026-02-08T21:42:43.090738Z","end":"2026-02-08T21:42:44.187265Z","steps":["trace[1421154170] 'process raft request' (duration: 1.096412717s)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:44.18734Z","caller":"traceutil/trace.go:171","msg":"trace[175880293] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:831; }","duration":"749.303675ms","start":"2026-02-08T21:42:43.438025Z","end":"2026-02-08T21:42:44.187328Z","steps":["trace[175880293] 'read index received' (duration: 749.298805ms)","trace[175880293] 'applied index is now lower than readState.Index' (duration: 4.02µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:44.187372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:43.090719Z","time spent":"1.096596982s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:44.187443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.412158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:44.187467Z","caller":"traceutil/trace.go:171","msg":"trace[1074341258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"749.466739ms","start":"2026-02-08T21:42:43.437993Z","end":"2026-02-08T21:42:44.187459Z","steps":["trace[1074341258] 'agreement among raft nodes before linearized reading' (duration: 749.370386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:44.187489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:43.437977Z","time spent":"749.5073ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:42:45.227173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.263388ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-08T21:42:45.227267Z","caller":"traceutil/trace.go:171","msg":"trace[177039621] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"1.038473683s","start":"2026-02-08T21:42:44.188775Z","end":"2026-02-08T21:42:45.227249Z","steps":["trace[177039621] 'read index received' (duration: 310.071902ms)","trace[177039621] 'applied index is now lower than readState.Index' (duration: 728.400521ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:45.227659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038874332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:45.2277Z","caller":"traceutil/trace.go:171","msg":"trace[634569969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"1.038937712s","start":"2026-02-08T21:42:44.188755Z","end":"2026-02-08T21:42:45.227693Z","steps":["trace[634569969] 'agreement among raft nodes before linearized reading' (duration: 1.038869751s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.227723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:44.188745Z","time spent":"1.038972473s","remote":"127.0.0.1:56176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:42:45.22775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.887382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:42:45.227816Z","caller":"traceutil/trace.go:171","msg":"trace[607927632] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:802; }","duration":"790.006305ms","start":"2026-02-08T21:42:44.437794Z","end":"2026-02-08T21:42:45.2278Z","steps":["trace[607927632] 'agreement among raft nodes before linearized reading' (duration: 789.874922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.227857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:44.437779Z","time spent":"790.068736ms","remote":"127.0.0.1:56380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:42:45.228012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.835131ms","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-08T21:42:45.228052Z","caller":"traceutil/trace.go:171","msg":"trace[1161676182] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"645.891892ms","start":"2026-02-08T21:42:44.582153Z","end":"2026-02-08T21:42:45.228045Z","steps":["trace[1161676182] 'agreement among raft nodes before linearized reading' (duration: 645.766249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.22807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:44.582145Z","time spent":"645.921193ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:42:45.228206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.949651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-08T21:42:45.228223Z","caller":"traceutil/trace.go:171","msg":"trace[1953797414] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"644.966551ms","start":"2026-02-08T21:42:44.583251Z","end":"2026-02-08T21:42:45.228217Z","steps":["trace[1953797414] 'agreement among raft nodes before linearized reading' (duration: 644.91502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.228239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:44.583214Z","time spent":"645.021663ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:42:45.22825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.534786ms","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-08T21:42:45.228291Z","caller":"traceutil/trace.go:171","msg":"trace[1405943288] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:802; }","duration":"646.597827ms","start":"2026-02-08T21:42:44.581682Z","end":"2026-02-08T21:42:45.22828Z","steps":["trace[1405943288] 'agreement among raft nodes before linearized reading' (duration: 646.450014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.228326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:44.58167Z","time spent":"646.645268ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:45.228453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.16999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:42:45.228478Z","caller":"traceutil/trace.go:171","msg":"trace[1105149514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"648.228921ms","start":"2026-02-08T21:42:44.580241Z","end":"2026-02-08T21:42:45.22847Z","steps":["trace[1105149514] 'agreement among raft nodes before linearized reading' (duration: 648.153819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.228503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:44.580188Z","time spent":"648.309492ms","remote":"127.0.0.1:56442","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-08T21:42:45.228596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.762716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-08T21:42:45.228626Z","caller":"traceutil/trace.go:171","msg":"trace[484313915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"651.827077ms","start":"2026-02-08T21:42:44.576791Z","end":"2026-02-08T21:42:45.228618Z","steps":["trace[484313915] 'agreement among raft nodes before linearized reading' (duration: 651.754455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.228649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:44.576772Z","time spent":"651.871059ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:00.024339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.221506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:00.024421Z","caller":"traceutil/trace.go:171","msg":"trace[1917537954] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"655.729072ms","start":"2026-02-08T21:42:59.36868Z","end":"2026-02-08T21:43:00.02441Z","steps":["trace[1917537954] 'read index received' (duration: 495.369673ms)","trace[1917537954] 'applied index is now lower than readState.Index' (duration: 160.358599ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:00.024487Z","caller":"traceutil/trace.go:171","msg":"trace[1280649445] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"1.141578971s","start":"2026-02-08T21:42:58.882898Z","end":"2026-02-08T21:43:00.024477Z","steps":["trace[1280649445] 'process raft request' (duration: 981.158721ms)","trace[1280649445] 'compare' (duration: 159.838778ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:00.024525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:58.882886Z","time spent":"1.141618973s","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:00.024686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.006238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:00.024706Z","caller":"traceutil/trace.go:171","msg":"trace[1781063664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:892; }","duration":"656.047779ms","start":"2026-02-08T21:42:59.368651Z","end":"2026-02-08T21:43:00.024699Z","steps":["trace[1781063664] 'agreement among raft nodes before linearized reading' (duration: 655.992308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:00.024724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:59.368638Z","time spent":"656.082629ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:00.024898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.161412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-08T21:43:00.024917Z","caller":"traceutil/trace.go:171","msg":"trace[498156627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:892; }","duration":"596.210792ms","start":"2026-02-08T21:42:59.4287Z","end":"2026-02-08T21:43:00.024911Z","steps":["trace[498156627] 'agreement among raft nodes before linearized reading' (duration: 596.133971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:00.024936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:59.428684Z","time spent":"596.246104ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:43:00.025125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.245504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:43:00.025163Z","caller":"traceutil/trace.go:171","msg":"trace[1856872381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:892; }","duration":"581.297125ms","start":"2026-02-08T21:42:59.44386Z","end":"2026-02-08T21:43:00.025157Z","steps":["trace[1856872381] 'agreement among raft nodes before linearized reading' (duration: 581.224293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:00.025181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:59.443855Z","time spent":"581.321985ms","remote":"127.0.0.1:56442","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-08T21:43:00.025319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.138195ms","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-08T21:43:00.025369Z","caller":"traceutil/trace.go:171","msg":"trace[945173564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:892; }","duration":"591.215327ms","start":"2026-02-08T21:42:59.434141Z","end":"2026-02-08T21:43:00.025356Z","steps":["trace[945173564] 'agreement among raft nodes before linearized reading' (duration: 591.068544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:00.025392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:59.434132Z","time spent":"591.255417ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:43:00.025505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.658002ms","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-08T21:43:00.025527Z","caller":"traceutil/trace.go:171","msg":"trace[1004170481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:892; }","duration":"581.703393ms","start":"2026-02-08T21:42:59.443817Z","end":"2026-02-08T21:43:00.025521Z","steps":["trace[1004170481] 'agreement among raft nodes before linearized reading' (duration: 581.641612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:00.025531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.550605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-08T21:43:00.025547Z","caller":"traceutil/trace.go:171","msg":"trace[551706787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:892; }","duration":"585.586797ms","start":"2026-02-08T21:42:59.439955Z","end":"2026-02-08T21:43:00.025542Z","steps":["trace[551706787] 'agreement among raft nodes before linearized reading' (duration: 585.541656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:00.025545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:59.443806Z","time spent":"581.735684ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:00.025562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:59.439945Z","time spent":"585.614257ms","remote":"127.0.0.1:56442","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-08T21:43:00.546349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205513366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:01.525757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.130342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:01.525837Z","caller":"traceutil/trace.go:171","msg":"trace[774909201] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"1.490966161s","start":"2026-02-08T21:43:00.03484Z","end":"2026-02-08T21:43:01.525806Z","steps":["trace[774909201] 'process raft request' (duration: 924.739047ms)","trace[774909201] 'compare' (duration: 565.782464ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:01.525921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:00.03483Z","time spent":"1.491070854s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:01.83644Z","caller":"traceutil/trace.go:171","msg":"trace[1265083270] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:926; }","duration":"1.790606411s","start":"2026-02-08T21:43:00.045818Z","end":"2026-02-08T21:43:01.836425Z","steps":["trace[1265083270] 'read index received' (duration: 913.770514ms)","trace[1265083270] 'applied index is now lower than readState.Index' (duration: 876.834587ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:01.836458Z","caller":"traceutil/trace.go:171","msg":"trace[54871845] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"1.801562453s","start":"2026-02-08T21:43:00.034886Z","end":"2026-02-08T21:43:01.836448Z","steps":["trace[54871845] 'process raft request' (duration: 1.701622502s)","trace[54871845] 'compare' (duration: 99.703776ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:01.836512Z","caller":"traceutil/trace.go:171","msg":"trace[1486641229] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"1.801555224s","start":"2026-02-08T21:43:00.034953Z","end":"2026-02-08T21:43:01.836508Z","steps":["trace[1486641229] 'process raft request' (duration: 1.80138956s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:01.836527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:00.034872Z","time spent":"1.801612965s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:01.836544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:00.034945Z","time spent":"1.801576904s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:01.836626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.464993498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:01.836642Z","caller":"traceutil/trace.go:171","msg":"trace[1244417827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:898; }","duration":"1.465040368s","start":"2026-02-08T21:43:00.371597Z","end":"2026-02-08T21:43:01.836637Z","steps":["trace[1244417827] 'agreement among raft nodes before linearized reading' (duration: 1.465014418s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:01.836654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:00.371579Z","time spent":"1.46507225s","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T21:43:01.836738Z","caller":"traceutil/trace.go:171","msg":"trace[1333883602] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"1.790974309s","start":"2026-02-08T21:43:00.045759Z","end":"2026-02-08T21:43:01.836734Z","steps":["trace[1333883602] 'process raft request' (duration: 1.790612082s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:01.836762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:00.045748Z","time spent":"1.79100039s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:01.836805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.790986159s","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-08T21:43:01.836821Z","caller":"traceutil/trace.go:171","msg":"trace[1331401606] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:898; }","duration":"1.791001139s","start":"2026-02-08T21:43:00.045815Z","end":"2026-02-08T21:43:01.836816Z","steps":["trace[1331401606] 'agreement among raft nodes before linearized reading' (duration: 1.790971258s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:01.836827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.260479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-08T21:43:01.836833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:00.045796Z","time spent":"1.7910339s","remote":"127.0.0.1:56444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-02-08T21:43:01.836838Z","caller":"traceutil/trace.go:171","msg":"trace[1662093357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:898; }","duration":"260.29637ms","start":"2026-02-08T21:43:01.576538Z","end":"2026-02-08T21:43:01.836834Z","steps":["trace[1662093357] 'agreement among raft nodes before linearized reading' (duration: 260.27737ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:01.836901Z","caller":"traceutil/trace.go:171","msg":"trace[2130666306] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"1.789516888s","start":"2026-02-08T21:43:00.04738Z","end":"2026-02-08T21:43:01.836896Z","steps":["trace[2130666306] 'process raft request' (duration: 1.789007887s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:01.836925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:00.047372Z","time spent":"1.789539238s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:01.83701Z","caller":"traceutil/trace.go:171","msg":"trace[2051915653] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"1.258593176s","start":"2026-02-08T21:43:00.578412Z","end":"2026-02-08T21:43:01.837005Z","steps":["trace[2051915653] 'process raft request' (duration: 1.257993863s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:01.837036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:00.57839Z","time spent":"1.258631516s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:04.179374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.422283ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-08T21:43:04.179467Z","caller":"traceutil/trace.go:171","msg":"trace[1930698350] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:933; }","duration":"338.238693ms","start":"2026-02-08T21:43:03.841212Z","end":"2026-02-08T21:43:04.17945Z","steps":["trace[1930698350] 'read index received' (duration: 84.684787ms)","trace[1930698350] 'applied index is now lower than readState.Index' (duration: 253.552426ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:04.179838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.61424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-08T21:43:04.179873Z","caller":"traceutil/trace.go:171","msg":"trace[470035682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:899; }","duration":"338.672601ms","start":"2026-02-08T21:43:03.841188Z","end":"2026-02-08T21:43:04.179861Z","steps":["trace[470035682] 'agreement among raft nodes before linearized reading' (duration: 338.513398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:04.179955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:03.841175Z","time spent":"338.721482ms","remote":"127.0.0.1:56442","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-08T21:43:04.180182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.932557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:43:04.180209Z","caller":"traceutil/trace.go:171","msg":"trace[1182059208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:899; }","duration":"338.967217ms","start":"2026-02-08T21:43:03.841233Z","end":"2026-02-08T21:43:04.1802Z","steps":["trace[1182059208] 'agreement among raft nodes before linearized reading' (duration: 338.889876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:04.180233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:03.84123Z","time spent":"338.997318ms","remote":"127.0.0.1:56442","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-08T21:43:04.180442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.827144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-08T21:43:04.180469Z","caller":"traceutil/trace.go:171","msg":"trace[1803981796] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:899; }","duration":"338.862786ms","start":"2026-02-08T21:43:03.841598Z","end":"2026-02-08T21:43:04.180461Z","steps":["trace[1803981796] 'agreement among raft nodes before linearized reading' (duration: 338.784164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:04.180493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:03.841594Z","time spent":"338.894006ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:43:04.180651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.357576ms","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-08T21:43:04.180677Z","caller":"traceutil/trace.go:171","msg":"trace[480673106] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:899; }","duration":"339.387926ms","start":"2026-02-08T21:43:03.841281Z","end":"2026-02-08T21:43:04.180669Z","steps":["trace[480673106] 'agreement among raft nodes before linearized reading' (duration: 339.316045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:04.1807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:03.841278Z","time spent":"339.416707ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-08T21:43:04.419186Z","caller":"traceutil/trace.go:171","msg":"trace[1880568381] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"211.546072ms","start":"2026-02-08T21:43:04.207625Z","end":"2026-02-08T21:43:04.419172Z","steps":["trace[1880568381] 'process raft request' (duration: 181.761516ms)","trace[1880568381] 'compare' (duration: 29.245013ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:04.419344Z","caller":"traceutil/trace.go:171","msg":"trace[1860920321] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"209.675571ms","start":"2026-02-08T21:43:04.209661Z","end":"2026-02-08T21:43:04.419337Z","steps":["trace[1860920321] 'process raft request' (duration: 209.347494ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:04.419399Z","caller":"traceutil/trace.go:171","msg":"trace[340115081] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"207.449613ms","start":"2026-02-08T21:43:04.211946Z","end":"2026-02-08T21:43:04.419396Z","steps":["trace[340115081] 'process raft request' (duration: 207.089816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:08.87705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205513414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:09.377534Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205513414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:09.878195Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205513414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:10.026804Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.654343405s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T21:43:10.027121Z","caller":"traceutil/trace.go:171","msg":"trace[1868119371] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"1.650909042s","start":"2026-02-08T21:43:08.376192Z","end":"2026-02-08T21:43:10.027101Z","steps":["trace[1868119371] 'read index received' (duration: 1.650722498s)","trace[1868119371] 'applied index is now lower than readState.Index' (duration: 185.764µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:10.027498Z","caller":"traceutil/trace.go:171","msg":"trace[223870211] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"1.715367897s","start":"2026-02-08T21:43:08.312117Z","end":"2026-02-08T21:43:10.027485Z","steps":["trace[223870211] 'process raft request' (duration: 1.714869745s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:10.027616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:08.312101Z","time spent":"1.715434119s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:10.360818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.904612931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-08T21:43:10.360885Z","caller":"traceutil/trace.go:171","msg":"trace[516495014] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:911; }","duration":"1.904722754s","start":"2026-02-08T21:43:08.456146Z","end":"2026-02-08T21:43:10.360869Z","steps":["trace[516495014] 'agreement among raft nodes before linearized reading' (duration: 1.571320195s)","trace[516495014] 'range keys from in-memory index tree' (duration: 333.244516ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:10.360914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:08.456133Z","time spent":"1.904774285s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:43:10.361058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.984863603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:10.36108Z","caller":"traceutil/trace.go:171","msg":"trace[792961562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"1.984899973s","start":"2026-02-08T21:43:08.376174Z","end":"2026-02-08T21:43:10.361074Z","steps":["trace[792961562] 'agreement among raft nodes before linearized reading' (duration: 1.651535855s)","trace[792961562] 'range keys from in-memory index tree' (duration: 333.335587ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:10.3611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:08.376167Z","time spent":"1.984929075s","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:10.361219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.654954ms","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-08T21:43:10.361241Z","caller":"traceutil/trace.go:171","msg":"trace[138207743] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:911; }","duration":"801.710496ms","start":"2026-02-08T21:43:09.559524Z","end":"2026-02-08T21:43:10.361234Z","steps":["trace[138207743] 'agreement among raft nodes before linearized reading' (duration: 468.193975ms)","trace[138207743] 'range keys from in-memory index tree' (duration: 333.46644ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:10.361262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:09.559503Z","time spent":"801.752956ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:10.361354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127687896s","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-08T21:43:10.361376Z","caller":"traceutil/trace.go:171","msg":"trace[142006597] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:911; }","duration":"1.127731866s","start":"2026-02-08T21:43:09.233638Z","end":"2026-02-08T21:43:10.36137Z","steps":["trace[142006597] 'agreement among raft nodes before linearized reading' (duration: 794.084943ms)","trace[142006597] 'count revisions from in-memory index tree' (duration: 333.617803ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:10.361397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:09.233626Z","time spent":"1.127765248s","remote":"127.0.0.1:56362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:10.361513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.741551235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T21:43:10.36156Z","caller":"traceutil/trace.go:171","msg":"trace[196481603] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:911; }","duration":"1.741620466s","start":"2026-02-08T21:43:08.619933Z","end":"2026-02-08T21:43:10.361554Z","steps":["trace[196481603] 'agreement among raft nodes before linearized reading' (duration: 1.407795539s)","trace[196481603] 'range keys from in-memory index tree' (duration: 333.746006ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:10.36158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:08.619921Z","time spent":"1.741655347s","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.212\" "} {"level":"warn","ts":"2026-02-08T21:43:10.361701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.90547374s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-08T21:43:10.361722Z","caller":"traceutil/trace.go:171","msg":"trace[2147086205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:911; }","duration":"1.905501631s","start":"2026-02-08T21:43:08.456214Z","end":"2026-02-08T21:43:10.361716Z","steps":["trace[2147086205] 'agreement among raft nodes before linearized reading' (duration: 1.57152002s)","trace[2147086205] 'range keys from in-memory index tree' (duration: 333.93028ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:10.361744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:08.456212Z","time spent":"1.905524791s","remote":"127.0.0.1:56442","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-08T21:43:10.361845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.905631353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:43:10.361866Z","caller":"traceutil/trace.go:171","msg":"trace[855929423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:911; }","duration":"1.905657084s","start":"2026-02-08T21:43:08.456203Z","end":"2026-02-08T21:43:10.36186Z","steps":["trace[855929423] 'agreement among raft nodes before linearized reading' (duration: 1.57153721s)","trace[855929423] 'range keys from in-memory index tree' (duration: 334.071893ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:10.361886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:08.4562Z","time spent":"1.905682684s","remote":"127.0.0.1:56442","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-08T21:43:10.878072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205513420,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:10.952805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:10.364102Z","time spent":"588.699092ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-08T21:43:11.273019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.528371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:11.273427Z","caller":"traceutil/trace.go:171","msg":"trace[194305948] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"319.651425ms","start":"2026-02-08T21:43:10.953763Z","end":"2026-02-08T21:43:11.273415Z","steps":["trace[194305948] 'process raft request' (duration: 319.597564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:11.27352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:10.953746Z","time spent":"319.749708ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:11.273766Z","caller":"traceutil/trace.go:171","msg":"trace[515042819] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"906.10905ms","start":"2026-02-08T21:43:10.367649Z","end":"2026-02-08T21:43:11.273758Z","steps":["trace[515042819] 'process raft request' (duration: 798.783842ms)","trace[515042819] 'compare' (duration: 106.119883ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:11.274133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:10.367632Z","time spent":"906.477479ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:11.273788Z","caller":"traceutil/trace.go:171","msg":"trace[42613765] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"898.934877ms","start":"2026-02-08T21:43:10.374849Z","end":"2026-02-08T21:43:11.273783Z","steps":["trace[42613765] 'process raft request' (duration: 898.381215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:11.274773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:10.374828Z","time spent":"899.922148ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:11.273805Z","caller":"traceutil/trace.go:171","msg":"trace[2686446] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"898.883425ms","start":"2026-02-08T21:43:10.374916Z","end":"2026-02-08T21:43:11.2738Z","steps":["trace[2686446] 'process raft request' (duration: 898.357004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:11.275204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:10.374912Z","time spent":"900.271185ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:11.273819Z","caller":"traceutil/trace.go:171","msg":"trace[298360819] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"896.436684ms","start":"2026-02-08T21:43:10.377378Z","end":"2026-02-08T21:43:11.273815Z","steps":["trace[298360819] 'process raft request' (duration: 895.950054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:11.275351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:10.377366Z","time spent":"897.964577ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:11.273834Z","caller":"traceutil/trace.go:171","msg":"trace[1037157911] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:947; }","duration":"896.338952ms","start":"2026-02-08T21:43:10.377489Z","end":"2026-02-08T21:43:11.273828Z","steps":["trace[1037157911] 'read index received' (duration: 575.645993ms)","trace[1037157911] 'applied index is now lower than readState.Index' (duration: 320.692238ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:11.273862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.370962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:11.275511Z","caller":"traceutil/trace.go:171","msg":"trace[846762886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"898.030888ms","start":"2026-02-08T21:43:10.377469Z","end":"2026-02-08T21:43:11.2755Z","steps":["trace[846762886] 'agreement among raft nodes before linearized reading' (duration: 896.374503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:11.275716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:10.377463Z","time spent":"898.239972ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:12.475272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101426765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:12.475331Z","caller":"traceutil/trace.go:171","msg":"trace[421033940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"1.101530717s","start":"2026-02-08T21:43:11.373788Z","end":"2026-02-08T21:43:12.475319Z","steps":["trace[421033940] 'range keys from in-memory index tree' (duration: 1.101364924s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:12.475361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:11.373771Z","time spent":"1.101584699s","remote":"127.0.0.1:56176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T21:43:12.476645Z","caller":"traceutil/trace.go:171","msg":"trace[1244734909] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"445.801126ms","start":"2026-02-08T21:43:12.030835Z","end":"2026-02-08T21:43:12.476636Z","steps":["trace[1244734909] 'read index received' (duration: 444.258722ms)","trace[1244734909] 'applied index is now lower than readState.Index' (duration: 1.541753ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:12.476748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.914538ms","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-08T21:43:12.476765Z","caller":"traceutil/trace.go:171","msg":"trace[290281952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:917; }","duration":"445.933228ms","start":"2026-02-08T21:43:12.030826Z","end":"2026-02-08T21:43:12.47676Z","steps":["trace[290281952] 'agreement among raft nodes before linearized reading' (duration: 445.838556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:12.476782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:12.030796Z","time spent":"445.982119ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-08T21:43:12.476922Z","caller":"traceutil/trace.go:171","msg":"trace[641555421] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"488.545056ms","start":"2026-02-08T21:43:11.98837Z","end":"2026-02-08T21:43:12.476915Z","steps":["trace[641555421] 'process raft request' (duration: 486.773358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:12.476974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:11.988352Z","time spent":"488.585118ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:12.881049Z","caller":"traceutil/trace.go:171","msg":"trace[361722668] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"393.818087ms","start":"2026-02-08T21:43:12.487212Z","end":"2026-02-08T21:43:12.88103Z","steps":["trace[361722668] 'read index received' (duration: 327.704997ms)","trace[361722668] 'applied index is now lower than readState.Index' (duration: 66.11219ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:12.881148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.919969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:12.881179Z","caller":"traceutil/trace.go:171","msg":"trace[1436051149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"393.97623ms","start":"2026-02-08T21:43:12.487186Z","end":"2026-02-08T21:43:12.881163Z","steps":["trace[1436051149] 'agreement among raft nodes before linearized reading' (duration: 393.909879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:12.881214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:12.487142Z","time spent":"394.066452ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T21:43:12.881418Z","caller":"traceutil/trace.go:171","msg":"trace[1882927103] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"398.444866ms","start":"2026-02-08T21:43:12.482963Z","end":"2026-02-08T21:43:12.881407Z","steps":["trace[1882927103] 'process raft request' (duration: 332.091221ms)","trace[1882927103] 'compare' (duration: 65.612119ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:12.881475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:12.482907Z","time spent":"398.528467ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:13.510629Z","caller":"traceutil/trace.go:171","msg":"trace[10690611] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"227.059931ms","start":"2026-02-08T21:43:13.283555Z","end":"2026-02-08T21:43:13.510615Z","steps":["trace[10690611] 'process raft request' (duration: 226.956339ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:13.517036Z","caller":"traceutil/trace.go:171","msg":"trace[964837802] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"230.217868ms","start":"2026-02-08T21:43:13.286802Z","end":"2026-02-08T21:43:13.51702Z","steps":["trace[964837802] 'process raft request' (duration: 230.109406ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:13.517172Z","caller":"traceutil/trace.go:171","msg":"trace[2039274186] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"229.228668ms","start":"2026-02-08T21:43:13.287939Z","end":"2026-02-08T21:43:13.517167Z","steps":["trace[2039274186] 'process raft request' (duration: 229.057395ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:13.51732Z","caller":"traceutil/trace.go:171","msg":"trace[2032975128] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"227.424219ms","start":"2026-02-08T21:43:13.289881Z","end":"2026-02-08T21:43:13.517305Z","steps":["trace[2032975128] 'process raft request' (duration: 227.270176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:14.254867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.555573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:43:14.254913Z","caller":"traceutil/trace.go:171","msg":"trace[119845443] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:922; }","duration":"522.648754ms","start":"2026-02-08T21:43:13.732254Z","end":"2026-02-08T21:43:14.254903Z","steps":["trace[119845443] 'count revisions from in-memory index tree' (duration: 522.490821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:14.254962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:13.732235Z","time spent":"522.720816ms","remote":"127.0.0.1:56664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":106,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:14.549044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.692732ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-08T21:43:14.549114Z","caller":"traceutil/trace.go:171","msg":"trace[1456819698] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"178.876714ms","start":"2026-02-08T21:43:14.370225Z","end":"2026-02-08T21:43:14.549101Z","steps":["trace[1456819698] 'read index received' (duration: 44.324955ms)","trace[1456819698] 'applied index is now lower than readState.Index' (duration: 134.550899ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:14.549158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.941236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:14.549171Z","caller":"traceutil/trace.go:171","msg":"trace[2067660784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"178.981976ms","start":"2026-02-08T21:43:14.370184Z","end":"2026-02-08T21:43:14.549166Z","steps":["trace[2067660784] 'agreement among raft nodes before linearized reading' (duration: 178.945185ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:15.134557Z","caller":"traceutil/trace.go:171","msg":"trace[2089259054] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"246.160729ms","start":"2026-02-08T21:43:14.888381Z","end":"2026-02-08T21:43:15.134542Z","steps":["trace[2089259054] 'process raft request' (duration: 245.885503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:15.418372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.449367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:15.418616Z","caller":"traceutil/trace.go:171","msg":"trace[1258063112] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"275.967914ms","start":"2026-02-08T21:43:15.142637Z","end":"2026-02-08T21:43:15.418605Z","steps":["trace[1258063112] 'process raft request' (duration: 95.237161ms)","trace[1258063112] 'compare' (duration: 180.371085ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:17.706173Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205513463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:18.206822Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205513463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:18.461043Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.319566594s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T21:43:18.461275Z","caller":"traceutil/trace.go:171","msg":"trace[1354407100] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"1.255083967s","start":"2026-02-08T21:43:17.20615Z","end":"2026-02-08T21:43:18.461234Z","steps":["trace[1354407100] 'read index received' (duration: 1.254961585s)","trace[1354407100] 'applied index is now lower than readState.Index' (duration: 121.662µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:18.461543Z","caller":"traceutil/trace.go:171","msg":"trace[1509884080] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"1.320170268s","start":"2026-02-08T21:43:17.141365Z","end":"2026-02-08T21:43:18.461536Z","steps":["trace[1509884080] 'process raft request' (duration: 1.319786609s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:18.461622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:17.14135Z","time spent":"1.320210059s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:18.461787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25563729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:43:18.461828Z","caller":"traceutil/trace.go:171","msg":"trace[1377077882] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:929; }","duration":"1.255706582s","start":"2026-02-08T21:43:17.206115Z","end":"2026-02-08T21:43:18.461822Z","steps":["trace[1377077882] 'agreement among raft nodes before linearized reading' (duration: 1.255648731s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:18.461846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:17.20609Z","time spent":"1.255749964s","remote":"127.0.0.1:56350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:18.462148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082953849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:18.462165Z","caller":"traceutil/trace.go:171","msg":"trace[460026750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"1.08297147s","start":"2026-02-08T21:43:17.379188Z","end":"2026-02-08T21:43:18.46216Z","steps":["trace[460026750] 'agreement among raft nodes before linearized reading' (duration: 1.082944179s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:18.46218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:17.379146Z","time spent":"1.083028622s","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:18.462346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.172472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:43:18.46238Z","caller":"traceutil/trace.go:171","msg":"trace[1681349197] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:929; }","duration":"376.216912ms","start":"2026-02-08T21:43:18.086158Z","end":"2026-02-08T21:43:18.462375Z","steps":["trace[1681349197] 'agreement among raft nodes before linearized reading' (duration: 376.166271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:18.462395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:18.086153Z","time spent":"376.237073ms","remote":"127.0.0.1:56602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:18.462503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.7325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:18.462521Z","caller":"traceutil/trace.go:171","msg":"trace[436881838] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:929; }","duration":"380.793282ms","start":"2026-02-08T21:43:18.081721Z","end":"2026-02-08T21:43:18.462514Z","steps":["trace[436881838] 'agreement among raft nodes before linearized reading' (duration: 380.750981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:18.462536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:18.081708Z","time spent":"380.823622ms","remote":"127.0.0.1:56578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:18.462676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.252175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:43:18.46269Z","caller":"traceutil/trace.go:171","msg":"trace[712718812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:929; }","duration":"927.267136ms","start":"2026-02-08T21:43:17.535419Z","end":"2026-02-08T21:43:18.462686Z","steps":["trace[712718812] 'agreement among raft nodes before linearized reading' (duration: 927.199324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:18.462702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:17.535398Z","time spent":"927.300937ms","remote":"127.0.0.1:56442","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-08T21:43:18.462791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.658765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-08T21:43:18.462808Z","caller":"traceutil/trace.go:171","msg":"trace[1704069310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:929; }","duration":"927.689146ms","start":"2026-02-08T21:43:17.535115Z","end":"2026-02-08T21:43:18.462804Z","steps":["trace[1704069310] 'agreement among raft nodes before linearized reading' (duration: 927.643034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:18.462822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:17.535107Z","time spent":"927.711227ms","remote":"127.0.0.1:56442","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-08T21:43:18.47937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.075313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-08T21:43:18.479413Z","caller":"traceutil/trace.go:171","msg":"trace[1780157572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:929; }","duration":"945.162605ms","start":"2026-02-08T21:43:17.534239Z","end":"2026-02-08T21:43:18.479402Z","steps":["trace[1780157572] 'agreement among raft nodes before linearized reading' (duration: 945.029602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:18.479437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:17.534224Z","time spent":"945.208807ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:43:19.12084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205513467,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:20.082135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.553572747s","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-08T21:43:20.082251Z","caller":"traceutil/trace.go:171","msg":"trace[1446517282] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:929; }","duration":"2.553690869s","start":"2026-02-08T21:43:17.528543Z","end":"2026-02-08T21:43:20.082234Z","steps":["trace[1446517282] 'agreement among raft nodes before linearized reading' (duration: 950.993776ms)","trace[1446517282] 'range keys from in-memory index tree' (duration: 1.602501329s)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:20.082282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:17.528527Z","time spent":"2.55374763s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:20.08241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600839769s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:20.082431Z","caller":"traceutil/trace.go:171","msg":"trace[640735834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"1.600870821s","start":"2026-02-08T21:43:18.481555Z","end":"2026-02-08T21:43:20.082426Z","steps":["trace[640735834] 'range keys from in-memory index tree' (duration: 1.600802239s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:20.082451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:18.481544Z","time spent":"1.600903121s","remote":"127.0.0.1:56176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:20.08315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.986375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:20.083235Z","caller":"traceutil/trace.go:171","msg":"trace[1076419218] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:966; }","duration":"1.462662616s","start":"2026-02-08T21:43:18.620566Z","end":"2026-02-08T21:43:20.083229Z","steps":["trace[1076419218] 'read index received' (duration: 526.585118ms)","trace[1076419218] 'applied index is now lower than readState.Index' (duration: 936.077068ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:20.08329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.462741507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T21:43:20.083301Z","caller":"traceutil/trace.go:171","msg":"trace[485190939] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:932; }","duration":"1.462753707s","start":"2026-02-08T21:43:18.620544Z","end":"2026-02-08T21:43:20.083297Z","steps":["trace[485190939] 'agreement among raft nodes before linearized reading' (duration: 1.462703306s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:20.083313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:18.620472Z","time spent":"1.46283891s","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.212\" "} {"level":"info","ts":"2026-02-08T21:43:20.083449Z","caller":"traceutil/trace.go:171","msg":"trace[1245589033] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"1.596534376s","start":"2026-02-08T21:43:18.486908Z","end":"2026-02-08T21:43:20.083442Z","steps":["trace[1245589033] 'process raft request' (duration: 660.234473ms)","trace[1245589033] 'compare' (duration: 935.496124ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:20.083477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:18.486891Z","time spent":"1.596569737s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:20.083549Z","caller":"traceutil/trace.go:171","msg":"trace[1934255109] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"1.594285712s","start":"2026-02-08T21:43:18.489259Z","end":"2026-02-08T21:43:20.083545Z","steps":["trace[1934255109] 'process raft request' (duration: 1.593927974s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:20.083571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:18.489251Z","time spent":"1.594308413s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:20.083606Z","caller":"traceutil/trace.go:171","msg":"trace[914379058] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"1.594029006s","start":"2026-02-08T21:43:18.489573Z","end":"2026-02-08T21:43:20.083602Z","steps":["trace[914379058] 'process raft request' (duration: 1.593642407s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:20.083625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:18.489559Z","time spent":"1.594055577s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:20.601789Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205513471,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:21.102328Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205513471,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:21.17336Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.080707215s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-08T21:43:21.311693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.191014ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-08T21:43:21.311835Z","caller":"traceutil/trace.go:171","msg":"trace[489932242] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:969; }","duration":"1.210472848s","start":"2026-02-08T21:43:20.101351Z","end":"2026-02-08T21:43:21.311824Z","steps":["trace[489932242] 'read index received' (duration: 1.07215348s)","trace[489932242] 'applied index is now lower than readState.Index' (duration: 138.318828ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:21.311885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:20.092555Z","time spent":"1.21932802s","remote":"127.0.0.1:56196","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-08T21:43:21.312047Z","caller":"traceutil/trace.go:171","msg":"trace[925045982] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"1.216350509s","start":"2026-02-08T21:43:20.095692Z","end":"2026-02-08T21:43:21.312042Z","steps":["trace[925045982] 'process raft request' (duration: 1.216065142s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:21.312105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:20.095679Z","time spent":"1.216380879s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:21.312172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210820596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:21.312185Z","caller":"traceutil/trace.go:171","msg":"trace[1980667342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"1.210855327s","start":"2026-02-08T21:43:20.101325Z","end":"2026-02-08T21:43:21.31218Z","steps":["trace[1980667342] 'agreement among raft nodes before linearized reading' (duration: 1.210833407s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:21.312197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:20.101312Z","time spent":"1.210882287s","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:21.312301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.923813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-08T21:43:21.312314Z","caller":"traceutil/trace.go:171","msg":"trace[208372622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:933; }","duration":"824.975134ms","start":"2026-02-08T21:43:20.487334Z","end":"2026-02-08T21:43:21.31231Z","steps":["trace[208372622] 'agreement among raft nodes before linearized reading' (duration: 824.922113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:21.312327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:20.487314Z","time spent":"825.010334ms","remote":"127.0.0.1:56442","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-08T21:43:21.312429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.932994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:21.312443Z","caller":"traceutil/trace.go:171","msg":"trace[547393264] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:933; }","duration":"352.947024ms","start":"2026-02-08T21:43:20.959491Z","end":"2026-02-08T21:43:21.312438Z","steps":["trace[547393264] 'agreement among raft nodes before linearized reading' (duration: 352.925784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:21.312464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:20.959457Z","time spent":"352.995274ms","remote":"127.0.0.1:56338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:21.312536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.926275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:43:21.312549Z","caller":"traceutil/trace.go:171","msg":"trace[1709824355] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:933; }","duration":"428.969467ms","start":"2026-02-08T21:43:20.883575Z","end":"2026-02-08T21:43:21.312545Z","steps":["trace[1709824355] 'agreement among raft nodes before linearized reading' (duration: 428.942156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:21.312561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:20.883557Z","time spent":"428.999937ms","remote":"127.0.0.1:56346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:21.635676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.811967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:21.635811Z","caller":"traceutil/trace.go:171","msg":"trace[1875984432] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:971; }","duration":"315.721ms","start":"2026-02-08T21:43:21.320078Z","end":"2026-02-08T21:43:21.635799Z","steps":["trace[1875984432] 'read index received' (duration: 169.811061ms)","trace[1875984432] 'applied index is now lower than readState.Index' (duration: 145.909359ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:21.635872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.794422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:21.635884Z","caller":"traceutil/trace.go:171","msg":"trace[2094148303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"315.821093ms","start":"2026-02-08T21:43:21.320059Z","end":"2026-02-08T21:43:21.63588Z","steps":["trace[2094148303] 'agreement among raft nodes before linearized reading' (duration: 315.779272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:21.635904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:21.32005Z","time spent":"315.848244ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T21:43:21.636048Z","caller":"traceutil/trace.go:171","msg":"trace[4290652] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"320.828073ms","start":"2026-02-08T21:43:21.315211Z","end":"2026-02-08T21:43:21.63604Z","steps":["trace[4290652] 'process raft request' (duration: 174.593486ms)","trace[4290652] 'compare' (duration: 145.637733ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:21.636099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:21.315204Z","time spent":"320.875975ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:21.636245Z","caller":"traceutil/trace.go:171","msg":"trace[1626700093] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"320.805174ms","start":"2026-02-08T21:43:21.315435Z","end":"2026-02-08T21:43:21.63624Z","steps":["trace[1626700093] 'process raft request' (duration: 320.326641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:21.636279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:21.315423Z","time spent":"320.839174ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:22.99054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.048323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:22.990705Z","caller":"traceutil/trace.go:171","msg":"trace[619402914] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:935; }","duration":"724.251358ms","start":"2026-02-08T21:43:22.266438Z","end":"2026-02-08T21:43:22.990689Z","steps":["trace[619402914] 'count revisions from in-memory index tree' (duration: 723.9711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:22.990774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.284767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:43:22.990795Z","caller":"traceutil/trace.go:171","msg":"trace[1933636561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:935; }","duration":"897.311898ms","start":"2026-02-08T21:43:22.093464Z","end":"2026-02-08T21:43:22.990789Z","steps":["trace[1933636561] 'range keys from in-memory index tree' (duration: 897.243356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:22.990809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:22.093459Z","time spent":"897.345958ms","remote":"127.0.0.1:56442","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-08T21:43:22.990857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:22.266419Z","time spent":"724.36387ms","remote":"127.0.0.1:56452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:22.991149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.281607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:22.991168Z","caller":"traceutil/trace.go:171","msg":"trace[1595393875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"623.332418ms","start":"2026-02-08T21:43:22.367831Z","end":"2026-02-08T21:43:22.991163Z","steps":["trace[1595393875] 'range keys from in-memory index tree' (duration: 623.226064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:22.99118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:22.367815Z","time spent":"623.362108ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:22.99074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.950513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-08T21:43:22.990937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.423505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-08T21:43:22.991519Z","caller":"traceutil/trace.go:171","msg":"trace[821974141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:935; }","duration":"894.028949ms","start":"2026-02-08T21:43:22.097485Z","end":"2026-02-08T21:43:22.991514Z","steps":["trace[821974141] 'range keys from in-memory index tree' (duration: 893.356164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:22.991539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:22.09748Z","time spent":"894.05191ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-08T21:43:22.991373Z","caller":"traceutil/trace.go:171","msg":"trace[78766021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:935; }","duration":"898.610199ms","start":"2026-02-08T21:43:22.092751Z","end":"2026-02-08T21:43:22.991361Z","steps":["trace[78766021] 'range keys from in-memory index tree' (duration: 897.84034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:22.991774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:22.092737Z","time spent":"899.023478ms","remote":"127.0.0.1:56442","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-08T21:43:23.394051Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205513488,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:23.89749Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205513488,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:24.179644Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.346191452s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T21:43:24.179927Z","caller":"traceutil/trace.go:171","msg":"trace[1621527628] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"1.346578152s","start":"2026-02-08T21:43:22.833334Z","end":"2026-02-08T21:43:24.179913Z","steps":["trace[1621527628] 'process raft request' (duration: 1.34647615s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:24.179999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:22.833312Z","time spent":"1.346637933s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:24.445827Z","caller":"traceutil/trace.go:171","msg":"trace[1668026981] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:974; }","duration":"1.552356967s","start":"2026-02-08T21:43:22.893454Z","end":"2026-02-08T21:43:24.445811Z","steps":["trace[1668026981] 'read index received' (duration: 1.28681984s)","trace[1668026981] 'applied index is now lower than readState.Index' (duration: 265.536617ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:24.445913Z","caller":"traceutil/trace.go:171","msg":"trace[1621401868] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"1.449456669s","start":"2026-02-08T21:43:22.99645Z","end":"2026-02-08T21:43:24.445906Z","steps":["trace[1621401868] 'process raft request' (duration: 1.417285757s)","trace[1621401868] 'compare' (duration: 31.951696ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:24.445985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:22.996432Z","time spent":"1.449499661s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:24.446061Z","caller":"traceutil/trace.go:171","msg":"trace[1179873086] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"1.449078771s","start":"2026-02-08T21:43:22.996978Z","end":"2026-02-08T21:43:24.446057Z","steps":["trace[1179873086] 'process raft request' (duration: 1.448794613s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:24.446084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:22.996969Z","time spent":"1.449102801s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:24.446118Z","caller":"traceutil/trace.go:171","msg":"trace[614677843] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"1.447860511s","start":"2026-02-08T21:43:22.998253Z","end":"2026-02-08T21:43:24.446114Z","steps":["trace[614677843] 'process raft request' (duration: 1.447540413s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:24.446137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:22.998241Z","time spent":"1.447884351s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:24.446189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.552737867s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:43:24.446204Z","caller":"traceutil/trace.go:171","msg":"trace[1242877008] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:939; }","duration":"1.552783018s","start":"2026-02-08T21:43:22.893417Z","end":"2026-02-08T21:43:24.4462Z","steps":["trace[1242877008] 'agreement among raft nodes before linearized reading' (duration: 1.552751367s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:24.446221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:22.893388Z","time spent":"1.552829219s","remote":"127.0.0.1:56570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:24.446328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.451927929s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:24.44634Z","caller":"traceutil/trace.go:171","msg":"trace[55463492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"1.451956049s","start":"2026-02-08T21:43:22.99438Z","end":"2026-02-08T21:43:24.446336Z","steps":["trace[55463492] 'agreement among raft nodes before linearized reading' (duration: 1.451936299s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:24.446351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:22.994368Z","time spent":"1.45198056s","remote":"127.0.0.1:56176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:24.446468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.968733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:24.446482Z","caller":"traceutil/trace.go:171","msg":"trace[1883047017] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:939; }","duration":"732.026725ms","start":"2026-02-08T21:43:23.714451Z","end":"2026-02-08T21:43:24.446478Z","steps":["trace[1883047017] 'agreement among raft nodes before linearized reading' (duration: 732.001904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:24.446495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:23.71443Z","time spent":"732.061036ms","remote":"127.0.0.1:56332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:24.446603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.249065ms","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-08T21:43:24.446616Z","caller":"traceutil/trace.go:171","msg":"trace[699585844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:939; }","duration":"806.289345ms","start":"2026-02-08T21:43:23.640323Z","end":"2026-02-08T21:43:24.446612Z","steps":["trace[699585844] 'agreement among raft nodes before linearized reading' (duration: 806.231254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:24.446628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:23.64029Z","time spent":"806.335486ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:43:24.446677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.548165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:43:24.446688Z","caller":"traceutil/trace.go:171","msg":"trace[1506604116] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:939; }","duration":"847.588427ms","start":"2026-02-08T21:43:23.599096Z","end":"2026-02-08T21:43:24.446684Z","steps":["trace[1506604116] 'agreement among raft nodes before linearized reading' (duration: 847.563356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:24.446701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:23.599062Z","time spent":"847.634237ms","remote":"127.0.0.1:56606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:24.446773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033184587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:24.446786Z","caller":"traceutil/trace.go:171","msg":"trace[333597069] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:939; }","duration":"1.033225868s","start":"2026-02-08T21:43:23.413556Z","end":"2026-02-08T21:43:24.446782Z","steps":["trace[333597069] 'agreement among raft nodes before linearized reading' (duration: 1.033203997s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:24.446797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:23.413542Z","time spent":"1.033252508s","remote":"127.0.0.1:56622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:24.446884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129301661s","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-08T21:43:24.446896Z","caller":"traceutil/trace.go:171","msg":"trace[1993352230] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:939; }","duration":"1.129349433s","start":"2026-02-08T21:43:23.317543Z","end":"2026-02-08T21:43:24.446892Z","steps":["trace[1993352230] 'agreement among raft nodes before linearized reading' (duration: 1.129311132s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:24.446907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:23.317525Z","time spent":"1.129378833s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:24.447432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355575588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:24.447458Z","caller":"traceutil/trace.go:171","msg":"trace[1908028508] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:939; }","duration":"1.35563864s","start":"2026-02-08T21:43:23.091813Z","end":"2026-02-08T21:43:24.447452Z","steps":["trace[1908028508] 'agreement among raft nodes before linearized reading' (duration: 1.355591899s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:24.447479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:23.091797Z","time spent":"1.35567791s","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:26.955892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.328633ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-08T21:43:26.955997Z","caller":"traceutil/trace.go:171","msg":"trace[846110812] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"584.396494ms","start":"2026-02-08T21:43:26.371572Z","end":"2026-02-08T21:43:26.955969Z","steps":["trace[846110812] 'read index received' (duration: 353.907106ms)","trace[846110812] 'applied index is now lower than readState.Index' (duration: 230.487968ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:26.956508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.924957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:26.956551Z","caller":"traceutil/trace.go:171","msg":"trace[265246488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"584.994889ms","start":"2026-02-08T21:43:26.371544Z","end":"2026-02-08T21:43:26.956539Z","steps":["trace[265246488] 'agreement among raft nodes before linearized reading' (duration: 584.924737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:26.956587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:26.371531Z","time spent":"585.04812ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:26.956863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.147206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-08T21:43:26.956898Z","caller":"traceutil/trace.go:171","msg":"trace[1918313152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:942; }","duration":"503.220488ms","start":"2026-02-08T21:43:26.453669Z","end":"2026-02-08T21:43:26.956889Z","steps":["trace[1918313152] 'agreement among raft nodes before linearized reading' (duration: 503.092675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:26.95693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:26.453651Z","time spent":"503.271119ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:43:26.958186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.006721ms","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-08T21:43:26.958236Z","caller":"traceutil/trace.go:171","msg":"trace[1231002104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:942; }","duration":"435.098604ms","start":"2026-02-08T21:43:26.523123Z","end":"2026-02-08T21:43:26.958222Z","steps":["trace[1231002104] 'agreement among raft nodes before linearized reading' (duration: 434.93702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:26.95826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:26.523106Z","time spent":"435.150264ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:43:26.958437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.148622ms","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-08T21:43:26.958451Z","caller":"traceutil/trace.go:171","msg":"trace[1830009761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:942; }","duration":"434.164132ms","start":"2026-02-08T21:43:26.524283Z","end":"2026-02-08T21:43:26.958447Z","steps":["trace[1830009761] 'agreement among raft nodes before linearized reading' (duration: 434.113401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:26.958463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:26.524251Z","time spent":"434.209262ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:26.958694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.894928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-08T21:43:26.958707Z","caller":"traceutil/trace.go:171","msg":"trace[466745212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:942; }","duration":"494.920208ms","start":"2026-02-08T21:43:26.463783Z","end":"2026-02-08T21:43:26.958703Z","steps":["trace[466745212] 'agreement among raft nodes before linearized reading' (duration: 494.876807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:26.958721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:26.463752Z","time spent":"494.964619ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:26.958341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.977226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-08T21:43:26.96221Z","caller":"traceutil/trace.go:171","msg":"trace[285827735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:942; }","duration":"497.856309ms","start":"2026-02-08T21:43:26.464336Z","end":"2026-02-08T21:43:26.962193Z","steps":["trace[285827735] 'agreement among raft nodes before linearized reading' (duration: 493.866003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:26.962538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:26.46433Z","time spent":"498.191977ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:27.457083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.231436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:27.457302Z","caller":"traceutil/trace.go:171","msg":"trace[1003676401] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"481.206769ms","start":"2026-02-08T21:43:26.97607Z","end":"2026-02-08T21:43:27.457277Z","steps":["trace[1003676401] 'process raft request' (duration: 216.708247ms)","trace[1003676401] 'compare' (duration: 263.253272ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:27.457392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:26.976053Z","time spent":"481.28959ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:27.457543Z","caller":"traceutil/trace.go:171","msg":"trace[965706575] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"481.412925ms","start":"2026-02-08T21:43:26.976124Z","end":"2026-02-08T21:43:27.457537Z","steps":["trace[965706575] 'process raft request' (duration: 481.053455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:27.457572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:26.976121Z","time spent":"481.433325ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:27.457696Z","caller":"traceutil/trace.go:171","msg":"trace[2018340314] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"481.534607ms","start":"2026-02-08T21:43:26.976148Z","end":"2026-02-08T21:43:27.457683Z","steps":["trace[2018340314] 'process raft request' (duration: 481.106476ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:27.457745Z","caller":"traceutil/trace.go:171","msg":"trace[417827676] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"480.182046ms","start":"2026-02-08T21:43:26.977555Z","end":"2026-02-08T21:43:27.457737Z","steps":["trace[417827676] 'process raft request' (duration: 479.777525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:27.457774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:26.976146Z","time spent":"481.575078ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:27.457786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:26.97755Z","time spent":"480.215376ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:28.206378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.041266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:43:28.206435Z","caller":"traceutil/trace.go:171","msg":"trace[1613713958] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:947; }","duration":"162.146369ms","start":"2026-02-08T21:43:28.044276Z","end":"2026-02-08T21:43:28.206422Z","steps":["trace[1613713958] 'count revisions from in-memory index tree' (duration: 161.974994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:29.091348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.708694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:29.091714Z","caller":"traceutil/trace.go:171","msg":"trace[746469334] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"384.570932ms","start":"2026-02-08T21:43:28.707126Z","end":"2026-02-08T21:43:29.091697Z","steps":["trace[746469334] 'process raft request' (duration: 198.446148ms)","trace[746469334] 'compare' (duration: 185.584291ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:29.091775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:28.707107Z","time spent":"384.636283ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:39.029588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:38.621274Z","time spent":"408.311281ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-08T21:43:39.465553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.951356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:39.465674Z","caller":"traceutil/trace.go:171","msg":"trace[1248940966] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"260.060286ms","start":"2026-02-08T21:43:39.205603Z","end":"2026-02-08T21:43:39.465663Z","steps":["trace[1248940966] 'read index received' (duration: 40.911µs)","trace[1248940966] 'applied index is now lower than readState.Index' (duration: 260.018615ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:39.465803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.197789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-08T21:43:39.465821Z","caller":"traceutil/trace.go:171","msg":"trace[1112267648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1004; }","duration":"260.253511ms","start":"2026-02-08T21:43:39.205562Z","end":"2026-02-08T21:43:39.465816Z","steps":["trace[1112267648] 'agreement among raft nodes before linearized reading' (duration: 260.131808ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:39.465943Z","caller":"traceutil/trace.go:171","msg":"trace[1218350795] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"435.285068ms","start":"2026-02-08T21:43:39.030613Z","end":"2026-02-08T21:43:39.465898Z","steps":["trace[1218350795] 'process raft request' (duration: 83.930952ms)","trace[1218350795] 'compare' (duration: 350.865834ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:39.466111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:39.030594Z","time spent":"435.408921ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:46.869292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.268438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:46.869403Z","caller":"traceutil/trace.go:171","msg":"trace[1582885620] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"482.296386ms","start":"2026-02-08T21:43:46.387089Z","end":"2026-02-08T21:43:46.869385Z","steps":["trace[1582885620] 'process raft request' (duration: 144.875805ms)","trace[1582885620] 'compare' (duration: 337.005141ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:46.869447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:46.387074Z","time spent":"482.354637ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T21:43:47.147679Z","caller":"traceutil/trace.go:171","msg":"trace[826363091] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"267.631727ms","start":"2026-02-08T21:43:46.880028Z","end":"2026-02-08T21:43:47.14766Z","steps":["trace[826363091] 'process raft request' (duration: 211.259816ms)","trace[826363091] 'compare' (duration: 56.174696ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:48.350828Z","caller":"traceutil/trace.go:171","msg":"trace[1143880554] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"107.273243ms","start":"2026-02-08T21:43:48.24353Z","end":"2026-02-08T21:43:48.350804Z","steps":["trace[1143880554] 'process raft request' (duration: 107.106429ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:49.814474Z","caller":"traceutil/trace.go:171","msg":"trace[1408119983] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"208.51501ms","start":"2026-02-08T21:43:49.605931Z","end":"2026-02-08T21:43:49.814446Z","steps":["trace[1408119983] 'read index received' (duration: 208.322476ms)","trace[1408119983] 'applied index is now lower than readState.Index' (duration: 191.394µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:49.814595Z","caller":"traceutil/trace.go:171","msg":"trace[936911623] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"335.255079ms","start":"2026-02-08T21:43:49.47933Z","end":"2026-02-08T21:43:49.814585Z","steps":["trace[936911623] 'process raft request' (duration: 334.953051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:49.814692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:49.479313Z","time spent":"335.30914ms","remote":"127.0.0.1:56362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13248,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:49.814955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.682884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-08T21:43:49.815013Z","caller":"traceutil/trace.go:171","msg":"trace[1684641942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1064; }","duration":"120.803847ms","start":"2026-02-08T21:43:49.694194Z","end":"2026-02-08T21:43:49.814997Z","steps":["trace[1684641942] 'agreement among raft nodes before linearized reading' (duration: 120.599092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:49.815126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.975459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-08T21:43:49.815162Z","caller":"traceutil/trace.go:171","msg":"trace[608694484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1064; }","duration":"103.024201ms","start":"2026-02-08T21:43:49.712128Z","end":"2026-02-08T21:43:49.815152Z","steps":["trace[608694484] 'agreement among raft nodes before linearized reading' (duration: 102.906397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:49.815209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.060046ms","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-08T21:43:49.815233Z","caller":"traceutil/trace.go:171","msg":"trace[1466307014] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1064; }","duration":"104.123448ms","start":"2026-02-08T21:43:49.711103Z","end":"2026-02-08T21:43:49.815226Z","steps":["trace[1466307014] 'agreement among raft nodes before linearized reading' (duration: 104.055766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:49.815325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.57991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:43:49.815346Z","caller":"traceutil/trace.go:171","msg":"trace[33652772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1064; }","duration":"117.699142ms","start":"2026-02-08T21:43:49.69764Z","end":"2026-02-08T21:43:49.815339Z","steps":["trace[33652772] 'agreement among raft nodes before linearized reading' (duration: 117.644581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:49.815417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.481954ms","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-08T21:43:49.815448Z","caller":"traceutil/trace.go:171","msg":"trace[4020239] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1064; }","duration":"209.533945ms","start":"2026-02-08T21:43:49.605906Z","end":"2026-02-08T21:43:49.81544Z","steps":["trace[4020239] 'agreement among raft nodes before linearized reading' (duration: 209.447913ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:45:04.555355Z","caller":"traceutil/trace.go:171","msg":"trace[1569180222] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1431; }","duration":"140.124665ms","start":"2026-02-08T21:45:04.415214Z","end":"2026-02-08T21:45:04.555338Z","steps":["trace[1569180222] 'read index received' (duration: 139.986192ms)","trace[1569180222] 'applied index is now lower than readState.Index' (duration: 137.823µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:45:04.556298Z","caller":"traceutil/trace.go:171","msg":"trace[1875072106] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"220.369911ms","start":"2026-02-08T21:45:04.335917Z","end":"2026-02-08T21:45:04.556287Z","steps":["trace[1875072106] 'process raft request' (duration: 219.285265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:45:04.556671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.449037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-08T21:45:04.556763Z","caller":"traceutil/trace.go:171","msg":"trace[1928717355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1372; }","duration":"141.577269ms","start":"2026-02-08T21:45:04.415176Z","end":"2026-02-08T21:45:04.556753Z","steps":["trace[1928717355] 'agreement among raft nodes before linearized reading' (duration: 141.375005ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:45:04.736391Z","caller":"traceutil/trace.go:171","msg":"trace[670954718] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"131.861191ms","start":"2026-02-08T21:45:04.604504Z","end":"2026-02-08T21:45:04.736365Z","steps":["trace[670954718] 'process raft request' (duration: 61.058417ms)","trace[670954718] 'compare' (duration: 70.532918ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:45:06.72999Z","caller":"traceutil/trace.go:171","msg":"trace[933655724] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"119.399711ms","start":"2026-02-08T21:45:06.610568Z","end":"2026-02-08T21:45:06.729968Z","steps":["trace[933655724] 'process raft request' (duration: 107.452821ms)","trace[933655724] 'compare' (duration: 11.648932ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:45:06.862699Z","caller":"traceutil/trace.go:171","msg":"trace[1385808654] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"106.98771ms","start":"2026-02-08T21:45:06.755692Z","end":"2026-02-08T21:45:06.862679Z","steps":["trace[1385808654] 'process raft request' (duration: 102.201218ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:45:08.891467Z","caller":"traceutil/trace.go:171","msg":"trace[1868225055] linearizableReadLoop","detail":"{readStateIndex:1454; appliedIndex:1453; }","duration":"121.97871ms","start":"2026-02-08T21:45:08.769471Z","end":"2026-02-08T21:45:08.89145Z","steps":["trace[1868225055] 'read index received' (duration: 28.604728ms)","trace[1868225055] 'applied index is now lower than readState.Index' (duration: 93.373422ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:45:08.891546Z","caller":"traceutil/trace.go:171","msg":"trace[1843742947] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"156.736243ms","start":"2026-02-08T21:45:08.734803Z","end":"2026-02-08T21:45:08.89154Z","steps":["trace[1843742947] 'process raft request' (duration: 63.32919ms)","trace[1843742947] 'compare' (duration: 93.24813ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:45:08.891703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.220546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:45:08.891726Z","caller":"traceutil/trace.go:171","msg":"trace[280200250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1393; }","duration":"122.252806ms","start":"2026-02-08T21:45:08.769466Z","end":"2026-02-08T21:45:08.891719Z","steps":["trace[280200250] 'agreement among raft nodes before linearized reading' (duration: 122.179065ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:45:09.000124Z","caller":"traceutil/trace.go:171","msg":"trace[515846751] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"104.532723ms","start":"2026-02-08T21:45:08.895578Z","end":"2026-02-08T21:45:09.000111Z","steps":["trace[515846751] 'process raft request' (duration: 103.427648ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:00.329808Z","caller":"traceutil/trace.go:171","msg":"trace[1345133386] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"102.331942ms","start":"2026-02-08T21:46:00.227458Z","end":"2026-02-08T21:46:00.32979Z","steps":["trace[1345133386] 'process raft request' (duration: 102.227ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:05.232363Z","caller":"traceutil/trace.go:171","msg":"trace[160103835] transaction","detail":"{read_only:false; response_revision:1619; number_of_response:1; }","duration":"109.241109ms","start":"2026-02-08T21:46:05.123095Z","end":"2026-02-08T21:46:05.232337Z","steps":["trace[160103835] 'process raft request' (duration: 109.111406ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:05.753796Z","caller":"traceutil/trace.go:171","msg":"trace[300037680] transaction","detail":"{read_only:false; response_revision:1624; number_of_response:1; }","duration":"115.246957ms","start":"2026-02-08T21:46:05.638531Z","end":"2026-02-08T21:46:05.753778Z","steps":["trace[300037680] 'process raft request' (duration: 115.109715ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:08.576792Z","caller":"traceutil/trace.go:171","msg":"trace[144856526] transaction","detail":"{read_only:false; response_revision:1633; number_of_response:1; }","duration":"110.022674ms","start":"2026-02-08T21:46:08.466743Z","end":"2026-02-08T21:46:08.576766Z","steps":["trace[144856526] 'process raft request' (duration: 109.872071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:40.251411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.543118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-08T21:46:40.251475Z","caller":"traceutil/trace.go:171","msg":"trace[807140966] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1764; }","duration":"268.770513ms","start":"2026-02-08T21:46:39.982692Z","end":"2026-02-08T21:46:40.251462Z","steps":["trace[807140966] 'range keys from in-memory index tree' (duration: 268.422565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:59.380513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.035723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/csi-0ca633bb65a3cf272d1d08a3de1c47834bde90cf0096f0417fbb4c2f0408a2ef\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-02-08T21:46:59.380926Z","caller":"traceutil/trace.go:171","msg":"trace[883604684] range","detail":"{range_begin:/registry/volumeattachments/csi-0ca633bb65a3cf272d1d08a3de1c47834bde90cf0096f0417fbb4c2f0408a2ef; range_end:; response_count:1; response_revision:1862; }","duration":"123.475803ms","start":"2026-02-08T21:46:59.25743Z","end":"2026-02-08T21:46:59.380906Z","steps":["trace[883604684] 'range keys from in-memory index tree' (duration: 122.836369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:03.322367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.783986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-08T21:47:03.32244Z","caller":"traceutil/trace.go:171","msg":"trace[217271076] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1880; }","duration":"238.896868ms","start":"2026-02-08T21:47:03.083524Z","end":"2026-02-08T21:47:03.322421Z","steps":["trace[217271076] 'range keys from in-memory index tree' (duration: 238.616492ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:03.711994Z","caller":"traceutil/trace.go:171","msg":"trace[192189108] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"385.600811ms","start":"2026-02-08T21:47:03.32637Z","end":"2026-02-08T21:47:03.711971Z","steps":["trace[192189108] 'process raft request' (duration: 385.463718ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:03.712035Z","caller":"traceutil/trace.go:171","msg":"trace[1095638136] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1965; }","duration":"339.609201ms","start":"2026-02-08T21:47:03.3724Z","end":"2026-02-08T21:47:03.71201Z","steps":["trace[1095638136] 'read index received' (duration: 339.601511ms)","trace[1095638136] 'applied index is now lower than readState.Index' (duration: 6.04µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:03.712164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:47:03.32635Z","time spent":"385.719993ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:47:03.712233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.808556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:47:03.712266Z","caller":"traceutil/trace.go:171","msg":"trace[760066949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1881; }","duration":"339.890688ms","start":"2026-02-08T21:47:03.372366Z","end":"2026-02-08T21:47:03.712257Z","steps":["trace[760066949] 'agreement among raft nodes before linearized reading' (duration: 339.775046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:03.712302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:47:03.37235Z","time spent":"339.94447ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T21:47:03.940641Z","caller":"traceutil/trace.go:171","msg":"trace[745210881] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1965; }","duration":"228.496755ms","start":"2026-02-08T21:47:03.712121Z","end":"2026-02-08T21:47:03.940618Z","steps":["trace[745210881] 'read index received' (duration: 225.549417ms)","trace[745210881] 'applied index is now lower than readState.Index' (duration: 2.945878ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:03.94328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.770243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:47:03.943476Z","caller":"traceutil/trace.go:171","msg":"trace[1265205798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1881; }","duration":"229.039799ms","start":"2026-02-08T21:47:03.714422Z","end":"2026-02-08T21:47:03.943462Z","steps":["trace[1265205798] 'agreement among raft nodes before linearized reading' (duration: 228.729401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:03.944077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.467868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-08T21:47:03.944982Z","caller":"traceutil/trace.go:171","msg":"trace[1165016307] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1881; }","duration":"224.421751ms","start":"2026-02-08T21:47:03.72055Z","end":"2026-02-08T21:47:03.944972Z","steps":["trace[1165016307] 'agreement among raft nodes before linearized reading' (duration: 223.436188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:03.944435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.663603ms","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-08T21:47:03.945254Z","caller":"traceutil/trace.go:171","msg":"trace[1076963996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1881; }","duration":"279.512303ms","start":"2026-02-08T21:47:03.665733Z","end":"2026-02-08T21:47:03.945245Z","steps":["trace[1076963996] 'agreement among raft nodes before linearized reading' (duration: 275.015558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:10.104392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.759699ms","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-08T21:47:10.104919Z","caller":"traceutil/trace.go:171","msg":"trace[199132930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1912; }","duration":"109.314853ms","start":"2026-02-08T21:47:09.995588Z","end":"2026-02-08T21:47:10.104902Z","steps":["trace[199132930] 'range keys from in-memory index tree' (duration: 108.692778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:10.104736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.45743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-08T21:47:10.105266Z","caller":"traceutil/trace.go:171","msg":"trace[1873303835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1912; }","duration":"104.986383ms","start":"2026-02-08T21:47:10.000267Z","end":"2026-02-08T21:47:10.105253Z","steps":["trace[1873303835] 'range keys from in-memory index tree' (duration: 104.368358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:10.612167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548215205515937,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T21:47:10.982795Z","caller":"traceutil/trace.go:171","msg":"trace[1907986442] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"874.213019ms","start":"2026-02-08T21:47:10.10856Z","end":"2026-02-08T21:47:10.982773Z","steps":["trace[1907986442] 'process raft request' (duration: 874.068116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:10.983008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:47:10.108544Z","time spent":"874.318071ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:47:11.48519Z","caller":"traceutil/trace.go:171","msg":"trace[1202142677] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"1.376313222s","start":"2026-02-08T21:47:10.108868Z","end":"2026-02-08T21:47:11.485182Z","steps":["trace[1202142677] 'process raft request' (duration: 1.326476693s)","trace[1202142677] 'compare' (duration: 49.277917ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:11.485264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:47:10.10886Z","time spent":"1.376357773s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:47:11.485627Z","caller":"traceutil/trace.go:171","msg":"trace[756061698] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"1.375097163s","start":"2026-02-08T21:47:10.110524Z","end":"2026-02-08T21:47:11.485621Z","steps":["trace[756061698] 'process raft request' (duration: 1.374481569s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:11.485654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:47:10.110517Z","time spent":"1.375121994s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:47:11.485714Z","caller":"traceutil/trace.go:171","msg":"trace[977574098] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"1.375615086s","start":"2026-02-08T21:47:10.110095Z","end":"2026-02-08T21:47:11.48571Z","steps":["trace[977574098] 'process raft request' (duration: 1.37488792s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:11.485735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:47:10.110086Z","time spent":"1.375637507s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:47:11.485877Z","caller":"traceutil/trace.go:171","msg":"trace[1205820373] linearizableReadLoop","detail":"{readStateIndex:2002; appliedIndex:1999; }","duration":"1.374713864s","start":"2026-02-08T21:47:10.111157Z","end":"2026-02-08T21:47:11.485871Z","steps":["trace[1205820373] 'read index received' (duration: 872.489108ms)","trace[1205820373] 'applied index is now lower than readState.Index' (duration: 502.224126ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:11.485964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374805578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:47:11.485978Z","caller":"traceutil/trace.go:171","msg":"trace[484357098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1917; }","duration":"1.374838399s","start":"2026-02-08T21:47:10.111135Z","end":"2026-02-08T21:47:11.485974Z","steps":["trace[484357098] 'agreement among raft nodes before linearized reading' (duration: 1.374777297s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:11.485993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:47:10.111128Z","time spent":"1.374861669s","remote":"127.0.0.1:56442","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-08T21:47:11.486069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353603275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:47:11.486082Z","caller":"traceutil/trace.go:171","msg":"trace[134458681] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1917; }","duration":"1.353650986s","start":"2026-02-08T21:47:10.132427Z","end":"2026-02-08T21:47:11.486078Z","steps":["trace[134458681] 'agreement among raft nodes before linearized reading' (duration: 1.353620395s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:11.486095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:47:10.13241Z","time spent":"1.353680747s","remote":"127.0.0.1:56668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:47:11.486198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.922465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:47:11.48621Z","caller":"traceutil/trace.go:171","msg":"trace[2108155438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1917; }","duration":"114.970595ms","start":"2026-02-08T21:47:11.371235Z","end":"2026-02-08T21:47:11.486206Z","steps":["trace[2108155438] 'agreement among raft nodes before linearized reading' (duration: 114.951584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:11.486295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.520131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-08T21:47:11.486307Z","caller":"traceutil/trace.go:171","msg":"trace[921067766] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1917; }","duration":"547.559651ms","start":"2026-02-08T21:47:10.938743Z","end":"2026-02-08T21:47:11.486303Z","steps":["trace[921067766] 'agreement among raft nodes before linearized reading' (duration: 547.52045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:11.486318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:47:10.938729Z","time spent":"547.586052ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-08T21:47:11.486392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065534613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-08T21:47:11.486404Z","caller":"traceutil/trace.go:171","msg":"trace[653741615] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1917; }","duration":"1.065546223s","start":"2026-02-08T21:47:10.420854Z","end":"2026-02-08T21:47:11.4864Z","steps":["trace[653741615] 'agreement among raft nodes before linearized reading' (duration: 1.065511042s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:11.486415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:47:10.420836Z","time spent":"1.065575674s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-08T21:47:11.486477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267010771s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:47:11.486491Z","caller":"traceutil/trace.go:171","msg":"trace[1236125010] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1917; }","duration":"1.267064862s","start":"2026-02-08T21:47:10.219421Z","end":"2026-02-08T21:47:11.486485Z","steps":["trace[1236125010] 'agreement among raft nodes before linearized reading' (duration: 1.267034152s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:11.486503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:47:10.219401Z","time spent":"1.267097943s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-02-08T21:47:11.485076Z","caller":"traceutil/trace.go:171","msg":"trace[1175608862] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"312.324468ms","start":"2026-02-08T21:47:11.172736Z","end":"2026-02-08T21:47:11.485061Z","steps":["trace[1175608862] 'process raft request' (duration: 312.305727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:11.48762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:47:11.172706Z","time spent":"314.893317ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:47:13.124276Z","caller":"traceutil/trace.go:171","msg":"trace[1809594825] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"132.284258ms","start":"2026-02-08T21:47:12.991972Z","end":"2026-02-08T21:47:13.124256Z","steps":["trace[1809594825] 'process raft request' (duration: 64.282246ms)","trace[1809594825] 'compare' (duration: 67.320376ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:13.12482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.956674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-public/\" range_end:\"/registry/secrets/kube-public0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:47:13.124908Z","caller":"traceutil/trace.go:171","msg":"trace[1624826265] range","detail":"{range_begin:/registry/secrets/kube-public/; range_end:/registry/secrets/kube-public0; response_count:0; response_revision:1927; }","duration":"130.083097ms","start":"2026-02-08T21:47:12.994809Z","end":"2026-02-08T21:47:13.124892Z","steps":["trace[1624826265] 'agreement among raft nodes before linearized reading' (duration: 129.963924ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:13.124729Z","caller":"traceutil/trace.go:171","msg":"trace[33838956] linearizableReadLoop","detail":"{readStateIndex:2013; appliedIndex:2011; }","duration":"129.239197ms","start":"2026-02-08T21:47:12.994851Z","end":"2026-02-08T21:47:13.12409Z","steps":["trace[33838956] 'read index received' (duration: 14.003196ms)","trace[33838956] 'applied index is now lower than readState.Index' (duration: 115.235081ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:13.403636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.432995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363638"} {"level":"info","ts":"2026-02-08T21:47:13.403714Z","caller":"traceutil/trace.go:171","msg":"trace[1031013530] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1927; }","duration":"118.552788ms","start":"2026-02-08T21:47:13.285143Z","end":"2026-02-08T21:47:13.403696Z","steps":["trace[1031013530] 'range keys from in-memory index tree' (duration: 118.074317ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:13.661394Z","caller":"traceutil/trace.go:171","msg":"trace[761374492] linearizableReadLoop","detail":"{readStateIndex:2016; appliedIndex:2015; }","duration":"144.804479ms","start":"2026-02-08T21:47:13.516564Z","end":"2026-02-08T21:47:13.661369Z","steps":["trace[761374492] 'read index received' (duration: 37.214316ms)","trace[761374492] 'applied index is now lower than readState.Index' (duration: 107.589073ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:47:13.661711Z","caller":"traceutil/trace.go:171","msg":"trace[1442326490] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"148.72763ms","start":"2026-02-08T21:47:13.512968Z","end":"2026-02-08T21:47:13.661696Z","steps":["trace[1442326490] 'process raft request' (duration: 95.270507ms)","trace[1442326490] 'compare' (duration: 53.012883ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:13.662263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.698019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:47:13.662294Z","caller":"traceutil/trace.go:171","msg":"trace[2036943890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1930; }","duration":"145.730291ms","start":"2026-02-08T21:47:13.516552Z","end":"2026-02-08T21:47:13.662282Z","steps":["trace[2036943890] 'agreement among raft nodes before linearized reading' (duration: 145.68383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:13.662209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.484855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363638"} {"level":"info","ts":"2026-02-08T21:47:13.662548Z","caller":"traceutil/trace.go:171","msg":"trace[1819436120] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1930; }","duration":"133.847723ms","start":"2026-02-08T21:47:13.528692Z","end":"2026-02-08T21:47:13.66254Z","steps":["trace[1819436120] 'agreement among raft nodes before linearized reading' (duration: 133.350091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:13.972672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.403852ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-08T21:47:13.972749Z","caller":"traceutil/trace.go:171","msg":"trace[1124084493] linearizableReadLoop","detail":"{readStateIndex:2018; appliedIndex:2017; }","duration":"209.73197ms","start":"2026-02-08T21:47:13.763002Z","end":"2026-02-08T21:47:13.972734Z","steps":["trace[1124084493] 'read index received' (duration: 216.505µs)","trace[1124084493] 'applied index is now lower than readState.Index' (duration: 209.514425ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:13.973039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.999756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:47:13.973234Z","caller":"traceutil/trace.go:171","msg":"trace[1976050721] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1931; }","duration":"210.17506ms","start":"2026-02-08T21:47:13.762971Z","end":"2026-02-08T21:47:13.973146Z","steps":["trace[1976050721] 'agreement among raft nodes before linearized reading' (duration: 209.970525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:13.973416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.128559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363638"} {"level":"info","ts":"2026-02-08T21:47:13.973451Z","caller":"traceutil/trace.go:171","msg":"trace[2107512084] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1931; }","duration":"201.19309ms","start":"2026-02-08T21:47:13.772247Z","end":"2026-02-08T21:47:13.97344Z","steps":["trace[2107512084] 'agreement among raft nodes before linearized reading' (duration: 200.7491ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:18.863461Z","caller":"traceutil/trace.go:171","msg":"trace[1890013592] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"194.025323ms","start":"2026-02-08T21:47:18.669416Z","end":"2026-02-08T21:47:18.863441Z","steps":["trace[1890013592] 'process raft request' (duration: 192.233151ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:18.863874Z","caller":"traceutil/trace.go:171","msg":"trace[750926624] linearizableReadLoop","detail":"{readStateIndex:2037; appliedIndex:2036; }","duration":"193.110443ms","start":"2026-02-08T21:47:18.670746Z","end":"2026-02-08T21:47:18.863856Z","steps":["trace[750926624] 'read index received' (duration: 190.910052ms)","trace[750926624] 'applied index is now lower than readState.Index' (duration: 2.199261ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:18.86421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.451621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:960"} {"level":"info","ts":"2026-02-08T21:47:18.864276Z","caller":"traceutil/trace.go:171","msg":"trace[545363457] range","detail":"{range_begin:/registry/clusterroles/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:1950; }","duration":"193.539762ms","start":"2026-02-08T21:47:18.670728Z","end":"2026-02-08T21:47:18.864267Z","steps":["trace[545363457] 'agreement among raft nodes before linearized reading' (duration: 193.44178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:18.864408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.840836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-proxier\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-08T21:47:18.864464Z","caller":"traceutil/trace.go:171","msg":"trace[381348172] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-proxier; range_end:; response_count:1; response_revision:1950; }","duration":"137.922899ms","start":"2026-02-08T21:47:18.726532Z","end":"2026-02-08T21:47:18.864455Z","steps":["trace[381348172] 'agreement among raft nodes before linearized reading' (duration: 137.851887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:19.36105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.132311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:47:19.361443Z","caller":"traceutil/trace.go:171","msg":"trace[1043052700] linearizableReadLoop","detail":"{readStateIndex:2040; appliedIndex:2039; }","duration":"166.71619ms","start":"2026-02-08T21:47:19.194709Z","end":"2026-02-08T21:47:19.361425Z","steps":["trace[1043052700] 'read index received' (duration: 17.095758ms)","trace[1043052700] 'applied index is now lower than readState.Index' (duration: 149.618872ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:47:19.361835Z","caller":"traceutil/trace.go:171","msg":"trace[374629012] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"182.718631ms","start":"2026-02-08T21:47:19.178958Z","end":"2026-02-08T21:47:19.361676Z","steps":["trace[374629012] 'process raft request' (duration: 32.893724ms)","trace[374629012] 'compare' (duration: 149.040669ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:19.362587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.859006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:756"} {"level":"info","ts":"2026-02-08T21:47:19.362649Z","caller":"traceutil/trace.go:171","msg":"trace[731376585] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:1952; }","duration":"167.931288ms","start":"2026-02-08T21:47:19.194705Z","end":"2026-02-08T21:47:19.362636Z","steps":["trace[731376585] 'agreement among raft nodes before linearized reading' (duration: 167.821415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:19.372184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.015241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslice-controller\" ","response":"range_response_count:1 size:793"} {"level":"info","ts":"2026-02-08T21:47:19.372313Z","caller":"traceutil/trace.go:171","msg":"trace[397345253] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslice-controller; range_end:; response_count:1; response_revision:1953; }","duration":"153.164944ms","start":"2026-02-08T21:47:19.219126Z","end":"2026-02-08T21:47:19.372291Z","steps":["trace[397345253] 'agreement among raft nodes before linearized reading' (duration: 152.969559ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:19.571611Z","caller":"traceutil/trace.go:171","msg":"trace[1588401851] linearizableReadLoop","detail":"{readStateIndex:2042; appliedIndex:2041; }","duration":"118.327163ms","start":"2026-02-08T21:47:19.453263Z","end":"2026-02-08T21:47:19.57159Z","steps":["trace[1588401851] 'read index received' (duration: 82.328595ms)","trace[1588401851] 'applied index is now lower than readState.Index' (duration: 35.997688ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:47:19.57241Z","caller":"traceutil/trace.go:171","msg":"trace[172430697] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"131.981921ms","start":"2026-02-08T21:47:19.44041Z","end":"2026-02-08T21:47:19.572392Z","steps":["trace[172430697] 'process raft request' (duration: 95.168395ms)","trace[172430697] 'compare' (duration: 35.70027ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:19.572506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.224163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:756"} {"level":"info","ts":"2026-02-08T21:47:19.579535Z","caller":"traceutil/trace.go:171","msg":"trace[232990576] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:1954; }","duration":"122.482609ms","start":"2026-02-08T21:47:19.453233Z","end":"2026-02-08T21:47:19.575715Z","steps":["trace[232990576] 'agreement among raft nodes before linearized reading' (duration: 119.217613ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:28.271965Z","caller":"traceutil/trace.go:171","msg":"trace[1600650973] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"140.909519ms","start":"2026-02-08T21:47:28.131036Z","end":"2026-02-08T21:47:28.271945Z","steps":["trace[1600650973] 'process raft request' (duration: 84.115537ms)","trace[1600650973] 'compare' (duration: 56.437023ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:47:28.27227Z","caller":"traceutil/trace.go:171","msg":"trace[216552500] linearizableReadLoop","detail":"{readStateIndex:2081; appliedIndex:2080; }","duration":"104.429249ms","start":"2026-02-08T21:47:28.167268Z","end":"2026-02-08T21:47:28.271698Z","steps":["trace[216552500] 'read index received' (duration: 47.816373ms)","trace[216552500] 'applied index is now lower than readState.Index' (duration: 56.611976ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:28.272955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.669189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-08T21:47:28.273001Z","caller":"traceutil/trace.go:171","msg":"trace[2131204772] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:1992; }","duration":"105.763921ms","start":"2026-02-08T21:47:28.167225Z","end":"2026-02-08T21:47:28.272989Z","steps":["trace[2131204772] 'agreement among raft nodes before linearized reading' (duration: 105.073535ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:29.401462Z","caller":"traceutil/trace.go:171","msg":"trace[347762280] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"210.034986ms","start":"2026-02-08T21:47:29.191416Z","end":"2026-02-08T21:47:29.401451Z","steps":["trace[347762280] 'process raft request' (duration: 194.473774ms)","trace[347762280] 'compare' (duration: 15.191904ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:47:29.401337Z","caller":"traceutil/trace.go:171","msg":"trace[1844863094] linearizableReadLoop","detail":"{readStateIndex:2085; appliedIndex:2083; }","duration":"205.647535ms","start":"2026-02-08T21:47:29.195675Z","end":"2026-02-08T21:47:29.401323Z","steps":["trace[1844863094] 'read index received' (duration: 194.455µs)","trace[1844863094] 'applied index is now lower than readState.Index' (duration: 205.45222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:29.402391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.704049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-02-08T21:47:29.40242Z","caller":"traceutil/trace.go:171","msg":"trace[557024293] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:1995; }","duration":"206.773121ms","start":"2026-02-08T21:47:29.195639Z","end":"2026-02-08T21:47:29.402412Z","steps":["trace[557024293] 'agreement among raft nodes before linearized reading' (duration: 206.70462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:29.40332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.780511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/default/kube-root-ca.crt\" ","response":"range_response_count:1 size:1738"} {"level":"info","ts":"2026-02-08T21:47:29.403345Z","caller":"traceutil/trace.go:171","msg":"trace[784762986] range","detail":"{range_begin:/registry/configmaps/default/kube-root-ca.crt; range_end:; response_count:1; response_revision:1995; }","duration":"114.848842ms","start":"2026-02-08T21:47:29.28849Z","end":"2026-02-08T21:47:29.403338Z","steps":["trace[784762986] 'agreement among raft nodes before linearized reading' (duration: 114.810852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:29.403451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.425862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/default/\" range_end:\"/registry/daemonsets/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:47:29.403465Z","caller":"traceutil/trace.go:171","msg":"trace[1402231265] range","detail":"{range_begin:/registry/daemonsets/default/; range_end:/registry/daemonsets/default0; response_count:0; response_revision:1995; }","duration":"148.462944ms","start":"2026-02-08T21:47:29.254998Z","end":"2026-02-08T21:47:29.403461Z","steps":["trace[1402231265] 'agreement among raft nodes before linearized reading' (duration: 148.439304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:29.404416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.901459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-node-lease/\" range_end:\"/registry/daemonsets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:47:29.404438Z","caller":"traceutil/trace.go:171","msg":"trace[1783943933] range","detail":"{range_begin:/registry/daemonsets/kube-node-lease/; range_end:/registry/daemonsets/kube-node-lease0; response_count:0; response_revision:1995; }","duration":"167.206661ms","start":"2026-02-08T21:47:29.237224Z","end":"2026-02-08T21:47:29.404431Z","steps":["trace[1783943933] 'agreement among raft nodes before linearized reading' (duration: 165.91489ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:29.88627Z","caller":"traceutil/trace.go:171","msg":"trace[1450069769] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"118.938737ms","start":"2026-02-08T21:47:29.767317Z","end":"2026-02-08T21:47:29.886256Z","steps":["trace[1450069769] 'process raft request' (duration: 98.081431ms)","trace[1450069769] 'compare' (duration: 20.550129ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:30.53903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.445007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/\" range_end:\"/registry/persistentvolumeclaims/default0\" limit:500 ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-02-08T21:47:30.539324Z","caller":"traceutil/trace.go:171","msg":"trace[1022449302] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/; range_end:/registry/persistentvolumeclaims/default0; response_count:1; response_revision:2002; }","duration":"140.609911ms","start":"2026-02-08T21:47:30.39856Z","end":"2026-02-08T21:47:30.53917Z","steps":["trace[1022449302] 'range keys from in-memory index tree' (duration: 140.285274ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:32.597955Z","caller":"traceutil/trace.go:171","msg":"trace[1925925339] linearizableReadLoop","detail":"{readStateIndex:2101; appliedIndex:2100; }","duration":"290.071889ms","start":"2026-02-08T21:47:32.307862Z","end":"2026-02-08T21:47:32.597934Z","steps":["trace[1925925339] 'read index received' (duration: 289.922455ms)","trace[1925925339] 'applied index is now lower than readState.Index' (duration: 148.454µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:47:32.598056Z","caller":"traceutil/trace.go:171","msg":"trace[541415254] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"304.27898ms","start":"2026-02-08T21:47:32.293768Z","end":"2026-02-08T21:47:32.598047Z","steps":["trace[541415254] 'process raft request' (duration: 304.035104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:32.598143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:47:32.293753Z","time spent":"304.32701ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:47:32.598393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.013461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" limit:500 ","response":"range_response_count:12 size:88674"} {"level":"info","ts":"2026-02-08T21:47:32.598435Z","caller":"traceutil/trace.go:171","msg":"trace[1690794323] range","detail":"{range_begin:/registry/pods/kube-system/; range_end:/registry/pods/kube-system0; response_count:12; response_revision:2011; }","duration":"282.059802ms","start":"2026-02-08T21:47:32.316364Z","end":"2026-02-08T21:47:32.598424Z","steps":["trace[1690794323] 'agreement among raft nodes before linearized reading' (duration: 281.847887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:32.598456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.114404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:47:32.598487Z","caller":"traceutil/trace.go:171","msg":"trace[670870540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2011; }","duration":"230.159454ms","start":"2026-02-08T21:47:32.368318Z","end":"2026-02-08T21:47:32.598478Z","steps":["trace[670870540] 'agreement among raft nodes before linearized reading' (duration: 230.114003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:32.598632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.765965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:47:32.598659Z","caller":"traceutil/trace.go:171","msg":"trace[1831959282] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2011; }","duration":"290.817566ms","start":"2026-02-08T21:47:32.307833Z","end":"2026-02-08T21:47:32.59865Z","steps":["trace[1831959282] 'agreement among raft nodes before linearized reading' (duration: 290.776695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:32.598686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.41701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-csi-encryption-kms-config\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-02-08T21:47:32.59872Z","caller":"traceutil/trace.go:171","msg":"trace[1523808212] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-csi-encryption-kms-config; range_end:; response_count:1; response_revision:2011; }","duration":"242.448491ms","start":"2026-02-08T21:47:32.356261Z","end":"2026-02-08T21:47:32.598709Z","steps":["trace[1523808212] 'agreement among raft nodes before linearized reading' (duration: 242.366119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:32.598893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.876629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-csi-config\" ","response":"range_response_count:1 size:919"} {"level":"info","ts":"2026-02-08T21:47:32.59892Z","caller":"traceutil/trace.go:171","msg":"trace[1989592164] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-csi-config; range_end:; response_count:1; response_revision:2011; }","duration":"266.92398ms","start":"2026-02-08T21:47:32.331988Z","end":"2026-02-08T21:47:32.598912Z","steps":["trace[1989592164] 'agreement among raft nodes before linearized reading' (duration: 266.851909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:36.323827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.278297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7045"} {"level":"info","ts":"2026-02-08T21:47:36.324523Z","caller":"traceutil/trace.go:171","msg":"trace[1049424413] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:2024; }","duration":"125.013944ms","start":"2026-02-08T21:47:36.199495Z","end":"2026-02-08T21:47:36.324509Z","steps":["trace[1049424413] 'range keys from in-memory index tree' (duration: 124.173374ms)"],"step_count":1}