{"level":"warn","ts":"2026-02-04T22:36:14.266174Z","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-04T22:36:14.266237Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.218: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.218:2380","--initial-cluster=instance=https://162.253.55.218:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.218:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.218: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-04T22:36:14.266398Z","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-04T22:36:14.266413Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-02-04T22:36:14.266479Z","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-04T22:36:14.267108Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"]} {"level":"info","ts":"2026-02-04T22:36:14.267218Z","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.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.218: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-04T22:36:14.271536Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.034688ms"} {"level":"info","ts":"2026-02-04T22:36:14.278818Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bb7d59f4041c8abe","cluster-id":"5330ca29224af3c4"} {"level":"info","ts":"2026-02-04T22:36:14.278874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T22:36:14.278888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 0"} {"level":"info","ts":"2026-02-04T22:36:14.278987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bb7d59f4041c8abe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T22:36:14.278994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 1"} {"level":"info","ts":"2026-02-04T22:36:14.27907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"warn","ts":"2026-02-04T22:36:14.285637Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T22:36:14.293456Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T22:36:14.296161Z","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-04T22:36:14.297887Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bb7d59f4041c8abe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T22:36:14.297994Z","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-04T22:36:14.298025Z","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-04T22:36:14.298033Z","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-04T22:36:14.300864Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bb7d59f4041c8abe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T22:36:14.301117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"info","ts":"2026-02-04T22:36:14.30122Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","added-peer-id":"bb7d59f4041c8abe","added-peer-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-02-04T22:36:14.302798Z","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-04T22:36:14.303294Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-02-04T22:36:14.303306Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-02-04T22:36:14.305956Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bb7d59f4041c8abe","initial-advertise-peer-urls":["https://162.253.55.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T22:36:14.305979Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T22:36:15.079498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T22:36:15.079537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T22:36:15.079566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgPreVoteResp from bb7d59f4041c8abe at term 1"} {"level":"info","ts":"2026-02-04T22:36:15.079643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became candidate at term 2"} {"level":"info","ts":"2026-02-04T22:36:15.079676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgVoteResp from bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-02-04T22:36:15.079708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became leader at term 2"} {"level":"info","ts":"2026-02-04T22:36:15.079722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bb7d59f4041c8abe elected leader bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-02-04T22:36:15.081841Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bb7d59f4041c8abe","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.218:2379]}","request-path":"/0/members/bb7d59f4041c8abe/attributes","cluster-id":"5330ca29224af3c4","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T22:36:15.081962Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T22:36:15.082242Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T22:36:15.089933Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-04T22:36:15.098087Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T22:36:15.099346Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.218:2379"} {"level":"info","ts":"2026-02-04T22:36:15.103411Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T22:36:15.103428Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T22:36:15.103504Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T22:36:15.105156Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T22:36:15.105177Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T22:36:29.774934Z","caller":"traceutil/trace.go:171","msg":"trace[1512304942] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"455.885339ms","start":"2026-02-04T22:36:29.319023Z","end":"2026-02-04T22:36:29.774908Z","steps":["trace[1512304942] 'read index received' (duration: 382.034319ms)","trace[1512304942] 'applied index is now lower than readState.Index' (duration: 73.85003ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:36:29.775092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.040292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T22:36:29.775126Z","caller":"traceutil/trace.go:171","msg":"trace[1396893150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"456.124194ms","start":"2026-02-04T22:36:29.318989Z","end":"2026-02-04T22:36:29.775113Z","steps":["trace[1396893150] 'agreement among raft nodes before linearized reading' (duration: 456.024151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:36:29.775161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:36:29.318974Z","time spent":"456.179215ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T22:36:29.77641Z","caller":"traceutil/trace.go:171","msg":"trace[326433904] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"515.925815ms","start":"2026-02-04T22:36:29.260467Z","end":"2026-02-04T22:36:29.776393Z","steps":["trace[326433904] 'process raft request' (duration: 440.651904ms)","trace[326433904] 'compare' (duration: 73.679637ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:36:29.777113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:36:29.26045Z","time spent":"516.000456ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T22:36:35.329473Z","caller":"traceutil/trace.go:171","msg":"trace[1905414791] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"278.108109ms","start":"2026-02-04T22:36:35.051346Z","end":"2026-02-04T22:36:35.329455Z","steps":["trace[1905414791] 'process raft request' (duration: 277.926175ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:36:35.458186Z","caller":"traceutil/trace.go:171","msg":"trace[613422275] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"123.475708ms","start":"2026-02-04T22:36:35.334694Z","end":"2026-02-04T22:36:35.45817Z","steps":["trace[613422275] 'process raft request' (duration: 121.873523ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:36:35.458324Z","caller":"traceutil/trace.go:171","msg":"trace[566669303] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"121.009624ms","start":"2026-02-04T22:36:35.33731Z","end":"2026-02-04T22:36:35.458319Z","steps":["trace[566669303] 'process raft request' (duration: 120.8338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:36:46.187587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.464038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T22:36:46.187681Z","caller":"traceutil/trace.go:171","msg":"trace[688975228] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"602.281279ms","start":"2026-02-04T22:36:45.585385Z","end":"2026-02-04T22:36:46.187666Z","steps":["trace[688975228] 'read index received' (duration: 307.491744ms)","trace[688975228] 'applied index is now lower than readState.Index' (duration: 294.788565ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T22:36:46.187931Z","caller":"traceutil/trace.go:171","msg":"trace[1749697548] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"602.624318ms","start":"2026-02-04T22:36:45.585292Z","end":"2026-02-04T22:36:46.187916Z","steps":["trace[1749697548] 'process raft request' (duration: 307.624967ms)","trace[1749697548] 'compare' (duration: 294.339775ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:36:46.187989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:36:45.585278Z","time spent":"602.680459ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T22:36:46.188224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.834642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T22:36:46.188253Z","caller":"traceutil/trace.go:171","msg":"trace[1269132658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"602.872063ms","start":"2026-02-04T22:36:45.585372Z","end":"2026-02-04T22:36:46.188244Z","steps":["trace[1269132658] 'agreement among raft nodes before linearized reading' (duration: 602.74934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:36:46.18828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:36:45.585367Z","time spent":"602.906763ms","remote":"127.0.0.1:50356","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-04T22:36:46.188437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.515378ms","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-04T22:36:46.188475Z","caller":"traceutil/trace.go:171","msg":"trace[1470090200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"350.578359ms","start":"2026-02-04T22:36:45.837889Z","end":"2026-02-04T22:36:46.188467Z","steps":["trace[1470090200] 'agreement among raft nodes before linearized reading' (duration: 350.493157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:36:46.188499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:36:45.837875Z","time spent":"350.61847ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-04T22:36:46.380357Z","caller":"traceutil/trace.go:171","msg":"trace[1612991882] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"181.096981ms","start":"2026-02-04T22:36:46.199245Z","end":"2026-02-04T22:36:46.380342Z","steps":["trace[1612991882] 'process raft request' (duration: 181.0692ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:36:46.380558Z","caller":"traceutil/trace.go:171","msg":"trace[1290337022] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"182.014572ms","start":"2026-02-04T22:36:46.198538Z","end":"2026-02-04T22:36:46.380553Z","steps":["trace[1290337022] 'process raft request' (duration: 177.201187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:36:47.83935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.921428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T22:36:47.839429Z","caller":"traceutil/trace.go:171","msg":"trace[1858049304] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"311.271936ms","start":"2026-02-04T22:36:47.528144Z","end":"2026-02-04T22:36:47.839416Z","steps":["trace[1858049304] 'process raft request' (duration: 161.229515ms)","trace[1858049304] 'compare' (duration: 149.818526ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:36:47.839477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:36:47.528132Z","time spent":"311.318588ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T22:36:48.524704Z","caller":"traceutil/trace.go:171","msg":"trace[1159060534] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"207.831179ms","start":"2026-02-04T22:36:48.316856Z","end":"2026-02-04T22:36:48.524687Z","steps":["trace[1159060534] 'read index received' (duration: 207.736047ms)","trace[1159060534] 'applied index is now lower than readState.Index' (duration: 94.552µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T22:36:48.524785Z","caller":"traceutil/trace.go:171","msg":"trace[1410359576] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"324.557848ms","start":"2026-02-04T22:36:48.200221Z","end":"2026-02-04T22:36:48.524779Z","steps":["trace[1410359576] 'process raft request' (duration: 324.349823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:36:48.524852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:36:48.200212Z","time spent":"324.588699ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T22:36:48.524992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.177979ms","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-04T22:36:48.525061Z","caller":"traceutil/trace.go:171","msg":"trace[1308108467] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:519; }","duration":"137.318441ms","start":"2026-02-04T22:36:48.387722Z","end":"2026-02-04T22:36:48.52504Z","steps":["trace[1308108467] 'agreement among raft nodes before linearized reading' (duration: 137.169928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:36:48.525065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.208437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T22:36:48.525104Z","caller":"traceutil/trace.go:171","msg":"trace[1541025680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"208.263959ms","start":"2026-02-04T22:36:48.316831Z","end":"2026-02-04T22:36:48.525095Z","steps":["trace[1541025680] 'agreement among raft nodes before linearized reading' (duration: 208.217188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:36:48.525231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.415784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T22:36:48.525244Z","caller":"traceutil/trace.go:171","msg":"trace[2063507572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:519; }","duration":"137.465625ms","start":"2026-02-04T22:36:48.387775Z","end":"2026-02-04T22:36:48.525241Z","steps":["trace[2063507572] 'agreement among raft nodes before linearized reading' (duration: 137.439874ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:36:50.719134Z","caller":"traceutil/trace.go:171","msg":"trace[1675977077] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"140.973771ms","start":"2026-02-04T22:36:50.578143Z","end":"2026-02-04T22:36:50.719117Z","steps":["trace[1675977077] 'read index received' (duration: 140.820148ms)","trace[1675977077] 'applied index is now lower than readState.Index' (duration: 153.073µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T22:36:50.719302Z","caller":"traceutil/trace.go:171","msg":"trace[1621988405] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"184.563338ms","start":"2026-02-04T22:36:50.534733Z","end":"2026-02-04T22:36:50.719296Z","steps":["trace[1621988405] 'process raft request' (duration: 184.272141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:36:50.719474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.318268ms","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-04T22:36:50.719493Z","caller":"traceutil/trace.go:171","msg":"trace[911268509] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"141.3711ms","start":"2026-02-04T22:36:50.578116Z","end":"2026-02-04T22:36:50.719487Z","steps":["trace[911268509] 'agreement among raft nodes before linearized reading' (duration: 141.291298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:36:50.719626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.3667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T22:36:50.719703Z","caller":"traceutil/trace.go:171","msg":"trace[1382652211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"141.500963ms","start":"2026-02-04T22:36:50.578173Z","end":"2026-02-04T22:36:50.719674Z","steps":["trace[1382652211] 'agreement among raft nodes before linearized reading' (duration: 141.288108ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:36:53.971029Z","caller":"traceutil/trace.go:171","msg":"trace[487779178] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"499.758771ms","start":"2026-02-04T22:36:53.471243Z","end":"2026-02-04T22:36:53.971002Z","steps":["trace[487779178] 'process raft request' (duration: 499.565597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:36:53.971171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:36:53.471203Z","time spent":"499.893604ms","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T22:37:07.13769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.990698ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T22:37:07.137781Z","caller":"traceutil/trace.go:171","msg":"trace[1785102376] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:687; }","duration":"196.09465ms","start":"2026-02-04T22:37:06.941671Z","end":"2026-02-04T22:37:07.137766Z","steps":["trace[1785102376] 'range keys from in-memory index tree' (duration: 195.976468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:07.138254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.866815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T22:37:07.138302Z","caller":"traceutil/trace.go:171","msg":"trace[830377205] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"437.975576ms","start":"2026-02-04T22:37:06.700318Z","end":"2026-02-04T22:37:07.138294Z","steps":["trace[830377205] 'process raft request' (duration: 154.025289ms)","trace[830377205] 'compare' (duration: 283.805714ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:37:07.138335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:06.700304Z","time spent":"438.014006ms","remote":"127.0.0.1:50590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T22:37:07.781264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.119596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T22:37:07.781491Z","caller":"traceutil/trace.go:171","msg":"trace[1610327807] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:711; }","duration":"938.511153ms","start":"2026-02-04T22:37:06.842962Z","end":"2026-02-04T22:37:07.781473Z","steps":["trace[1610327807] 'read index received' (duration: 11.38918ms)","trace[1610327807] 'applied index is now lower than readState.Index' (duration: 927.120873ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T22:37:07.781487Z","caller":"traceutil/trace.go:171","msg":"trace[442508486] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"1.080873436s","start":"2026-02-04T22:37:06.70058Z","end":"2026-02-04T22:37:07.781454Z","steps":["trace[442508486] 'process raft request' (duration: 840.486884ms)","trace[442508486] 'compare' (duration: 239.975233ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:37:07.78164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:06.700575Z","time spent":"1.080993008s","remote":"127.0.0.1:50590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T22:37:07.781688Z","caller":"traceutil/trace.go:171","msg":"trace[163620187] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"1.077800699s","start":"2026-02-04T22:37:06.703876Z","end":"2026-02-04T22:37:07.781677Z","steps":["trace[163620187] 'process raft request' (duration: 1.077470482s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:07.781747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:06.703869Z","time spent":"1.07784729s","remote":"127.0.0.1:50366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1612,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T22:37:07.781951Z","caller":"traceutil/trace.go:171","msg":"trace[1740544140] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"1.077031192s","start":"2026-02-04T22:37:06.70491Z","end":"2026-02-04T22:37:07.781941Z","steps":["trace[1740544140] 'process raft request' (duration: 1.076486161s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:07.781998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:06.704904Z","time spent":"1.077068403s","remote":"127.0.0.1:50286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1017,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T22:37:07.7821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.534531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T22:37:07.782139Z","caller":"traceutil/trace.go:171","msg":"trace[2024783876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:692; }","duration":"931.602562ms","start":"2026-02-04T22:37:06.850525Z","end":"2026-02-04T22:37:07.782128Z","steps":["trace[2024783876] 'agreement among raft nodes before linearized reading' (duration: 931.48491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:07.782168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:06.850505Z","time spent":"931.656703ms","remote":"127.0.0.1:50356","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-04T22:37:07.782316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.082364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-04T22:37:07.782326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.366942ms","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-04T22:37:07.782341Z","caller":"traceutil/trace.go:171","msg":"trace[1719731795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"462.165476ms","start":"2026-02-04T22:37:07.320167Z","end":"2026-02-04T22:37:07.782333Z","steps":["trace[1719731795] 'agreement among raft nodes before linearized reading' (duration: 462.128315ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:07.78236Z","caller":"traceutil/trace.go:171","msg":"trace[680710585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:692; }","duration":"939.426183ms","start":"2026-02-04T22:37:06.842921Z","end":"2026-02-04T22:37:07.782347Z","steps":["trace[680710585] 'agreement among raft nodes before linearized reading' (duration: 939.311201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:07.782366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:07.320149Z","time spent":"462.211826ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T22:37:07.782389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:06.842889Z","time spent":"939.494025ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-04T22:37:07.782556Z","caller":"traceutil/trace.go:171","msg":"trace[1674432235] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"1.077401379s","start":"2026-02-04T22:37:06.705146Z","end":"2026-02-04T22:37:07.782547Z","steps":["trace[1674432235] 'process raft request' (duration: 1.076289126s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:07.782607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:06.70513Z","time spent":"1.077450001s","remote":"127.0.0.1:50550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T22:37:07.787084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.859318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T22:37:07.78712Z","caller":"traceutil/trace.go:171","msg":"trace[1681834844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:692; }","duration":"926.905139ms","start":"2026-02-04T22:37:06.860206Z","end":"2026-02-04T22:37:07.787112Z","steps":["trace[1681834844] 'agreement among raft nodes before linearized reading' (duration: 926.816247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:07.78714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:06.8602Z","time spent":"926.934849ms","remote":"127.0.0.1:50356","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-04T22:37:08.341798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997599930443386048,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T22:37:08.459627Z","caller":"traceutil/trace.go:171","msg":"trace[948518398] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"653.908342ms","start":"2026-02-04T22:37:07.805685Z","end":"2026-02-04T22:37:08.459593Z","steps":["trace[948518398] 'process raft request' (duration: 653.880782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:08.459744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:07.805679Z","time spent":"653.993934ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T22:37:08.459915Z","caller":"traceutil/trace.go:171","msg":"trace[1505785471] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"660.254941ms","start":"2026-02-04T22:37:07.799655Z","end":"2026-02-04T22:37:08.45991Z","steps":["trace[1505785471] 'process raft request' (duration: 659.834042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:08.459941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:07.799642Z","time spent":"660.284172ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T22:37:08.541675Z","caller":"traceutil/trace.go:171","msg":"trace[995118945] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"696.469335ms","start":"2026-02-04T22:37:07.84519Z","end":"2026-02-04T22:37:08.541659Z","steps":["trace[995118945] 'process raft request' (duration: 696.337223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:08.54178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:07.845145Z","time spent":"696.581638ms","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4806,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T22:37:08.541828Z","caller":"traceutil/trace.go:171","msg":"trace[1291379950] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:719; }","duration":"701.016175ms","start":"2026-02-04T22:37:07.84079Z","end":"2026-02-04T22:37:08.541806Z","steps":["trace[1291379950] 'read index received' (duration: 619.329614ms)","trace[1291379950] 'applied index is now lower than readState.Index' (duration: 81.685671ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:37:08.54203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.397149ms","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-04T22:37:08.542049Z","caller":"traceutil/trace.go:171","msg":"trace[617057409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:697; }","duration":"477.445821ms","start":"2026-02-04T22:37:08.064598Z","end":"2026-02-04T22:37:08.542043Z","steps":["trace[617057409] 'agreement among raft nodes before linearized reading' (duration: 477.373739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:08.542063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:08.064583Z","time spent":"477.477461ms","remote":"127.0.0.1:50356","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-04T22:37:08.542096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.98423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4206"} {"level":"info","ts":"2026-02-04T22:37:08.542125Z","caller":"traceutil/trace.go:171","msg":"trace[885089358] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:697; }","duration":"741.036302ms","start":"2026-02-04T22:37:07.801081Z","end":"2026-02-04T22:37:08.542117Z","steps":["trace[885089358] 'agreement among raft nodes before linearized reading' (duration: 740.898579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:08.542146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:07.801074Z","time spent":"741.066643ms","remote":"127.0.0.1:50550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4229,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-04T22:37:08.542177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.474764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-04T22:37:08.542199Z","caller":"traceutil/trace.go:171","msg":"trace[1346093969] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:697; }","duration":"214.517864ms","start":"2026-02-04T22:37:08.327677Z","end":"2026-02-04T22:37:08.542195Z","steps":["trace[1346093969] 'agreement among raft nodes before linearized reading' (duration: 214.468583ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:08.542272Z","caller":"traceutil/trace.go:171","msg":"trace[1497615571] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"697.320004ms","start":"2026-02-04T22:37:07.844944Z","end":"2026-02-04T22:37:08.542264Z","steps":["trace[1497615571] 'process raft request' (duration: 696.666639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:08.542331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:07.844923Z","time spent":"697.363895ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T22:37:26.771611Z","caller":"traceutil/trace.go:171","msg":"trace[27497793] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"125.8159ms","start":"2026-02-04T22:37:26.645773Z","end":"2026-02-04T22:37:26.771589Z","steps":["trace[27497793] 'read index received' (duration: 51.578201ms)","trace[27497793] 'applied index is now lower than readState.Index' (duration: 74.236999ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T22:37:26.771721Z","caller":"traceutil/trace.go:171","msg":"trace[1467394375] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"165.026089ms","start":"2026-02-04T22:37:26.606671Z","end":"2026-02-04T22:37:26.771697Z","steps":["trace[1467394375] 'process raft request' (duration: 90.703169ms)","trace[1467394375] 'compare' (duration: 73.478591ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:37:26.771837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.053365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T22:37:26.771879Z","caller":"traceutil/trace.go:171","msg":"trace[1042925158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:762; }","duration":"126.140716ms","start":"2026-02-04T22:37:26.645731Z","end":"2026-02-04T22:37:26.771872Z","steps":["trace[1042925158] 'agreement among raft nodes before linearized reading' (duration: 125.997663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:26.771982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.864758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-04T22:37:26.772004Z","caller":"traceutil/trace.go:171","msg":"trace[1564130292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:762; }","duration":"103.923818ms","start":"2026-02-04T22:37:26.668073Z","end":"2026-02-04T22:37:26.771997Z","steps":["trace[1564130292] 'agreement among raft nodes before linearized reading' (duration: 103.829126ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:26.929095Z","caller":"traceutil/trace.go:171","msg":"trace[126619210] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"153.700022ms","start":"2026-02-04T22:37:26.775374Z","end":"2026-02-04T22:37:26.929074Z","steps":["trace[126619210] 'process raft request' (duration: 152.899664ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:26.929458Z","caller":"traceutil/trace.go:171","msg":"trace[1926083167] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:790; }","duration":"106.118128ms","start":"2026-02-04T22:37:26.823333Z","end":"2026-02-04T22:37:26.929451Z","steps":["trace[1926083167] 'read index received' (duration: 105.053945ms)","trace[1926083167] 'applied index is now lower than readState.Index' (duration: 1.063493ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:37:26.929553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.202579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T22:37:26.929572Z","caller":"traceutil/trace.go:171","msg":"trace[1505864467] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:764; }","duration":"106.273341ms","start":"2026-02-04T22:37:26.823292Z","end":"2026-02-04T22:37:26.929565Z","steps":["trace[1505864467] 'agreement among raft nodes before linearized reading' (duration: 106.181429ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:26.929702Z","caller":"traceutil/trace.go:171","msg":"trace[1146096774] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"153.313353ms","start":"2026-02-04T22:37:26.776383Z","end":"2026-02-04T22:37:26.929697Z","steps":["trace[1146096774] 'process raft request' (duration: 153.013487ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:33.116129Z","caller":"traceutil/trace.go:171","msg":"trace[1799875303] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"318.290281ms","start":"2026-02-04T22:37:32.797822Z","end":"2026-02-04T22:37:33.116112Z","steps":["trace[1799875303] 'process raft request' (duration: 318.189909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:33.116232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:32.797811Z","time spent":"318.365783ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T22:37:33.116513Z","caller":"traceutil/trace.go:171","msg":"trace[1697092909] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:809; }","duration":"166.821784ms","start":"2026-02-04T22:37:32.949686Z","end":"2026-02-04T22:37:33.116508Z","steps":["trace[1697092909] 'read index received' (duration: 166.819584ms)","trace[1697092909] 'applied index is now lower than readState.Index' (duration: 1.78µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:37:33.116586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.098528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-04T22:37:33.1166Z","caller":"traceutil/trace.go:171","msg":"trace[1700978540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:780; }","duration":"162.126428ms","start":"2026-02-04T22:37:32.954469Z","end":"2026-02-04T22:37:33.116595Z","steps":["trace[1700978540] 'agreement among raft nodes before linearized reading' (duration: 162.067487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:33.116687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.001039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T22:37:33.116698Z","caller":"traceutil/trace.go:171","msg":"trace[1257719803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:780; }","duration":"167.045189ms","start":"2026-02-04T22:37:32.94965Z","end":"2026-02-04T22:37:33.116695Z","steps":["trace[1257719803] 'agreement among raft nodes before linearized reading' (duration: 167.012019ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:33.448157Z","caller":"traceutil/trace.go:171","msg":"trace[1919494337] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"328.639265ms","start":"2026-02-04T22:37:33.119497Z","end":"2026-02-04T22:37:33.448136Z","steps":["trace[1919494337] 'process raft request' (duration: 328.562253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:33.448272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:33.119487Z","time spent":"328.740837ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T22:37:33.462927Z","caller":"traceutil/trace.go:171","msg":"trace[641645827] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"145.362871ms","start":"2026-02-04T22:37:33.31755Z","end":"2026-02-04T22:37:33.462913Z","steps":["trace[641645827] 'read index received' (duration: 130.977998ms)","trace[641645827] 'applied index is now lower than readState.Index' (duration: 14.384243ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:37:33.463015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.453893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T22:37:33.463043Z","caller":"traceutil/trace.go:171","msg":"trace[2113711857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"145.511355ms","start":"2026-02-04T22:37:33.317524Z","end":"2026-02-04T22:37:33.463035Z","steps":["trace[2113711857] 'agreement among raft nodes before linearized reading' (duration: 145.448344ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:33.463293Z","caller":"traceutil/trace.go:171","msg":"trace[1359496690] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"343.066899ms","start":"2026-02-04T22:37:33.120216Z","end":"2026-02-04T22:37:33.463283Z","steps":["trace[1359496690] 'process raft request' (duration: 342.63774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:33.463356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:33.12021Z","time spent":"343.11092ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T22:37:35.309635Z","caller":"traceutil/trace.go:171","msg":"trace[747313086] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"140.241995ms","start":"2026-02-04T22:37:35.169373Z","end":"2026-02-04T22:37:35.309615Z","steps":["trace[747313086] 'process raft request' (duration: 140.135823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:35.874971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.59831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T22:37:35.875031Z","caller":"traceutil/trace.go:171","msg":"trace[762101936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"422.705412ms","start":"2026-02-04T22:37:35.452311Z","end":"2026-02-04T22:37:35.875017Z","steps":["trace[762101936] 'range keys from in-memory index tree' (duration: 422.432116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:35.875061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:35.452288Z","time spent":"422.764473ms","remote":"127.0.0.1:50356","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-04T22:37:35.875231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.467886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-04T22:37:35.875249Z","caller":"traceutil/trace.go:171","msg":"trace[2063320535] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:785; }","duration":"406.503348ms","start":"2026-02-04T22:37:35.46874Z","end":"2026-02-04T22:37:35.875243Z","steps":["trace[2063320535] 'range keys from in-memory index tree' (duration: 406.410986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:35.875266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:35.468732Z","time spent":"406.530018ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-04T22:37:36.160542Z","caller":"traceutil/trace.go:171","msg":"trace[45982700] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"185.621647ms","start":"2026-02-04T22:37:35.974896Z","end":"2026-02-04T22:37:36.160517Z","steps":["trace[45982700] 'read index received' (duration: 185.353771ms)","trace[45982700] 'applied index is now lower than readState.Index' (duration: 266.946µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:37:36.160917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.997725ms","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-04T22:37:36.161162Z","caller":"traceutil/trace.go:171","msg":"trace[562351509] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:787; }","duration":"186.287572ms","start":"2026-02-04T22:37:35.974857Z","end":"2026-02-04T22:37:36.161144Z","steps":["trace[562351509] 'agreement among raft nodes before linearized reading' (duration: 185.851972ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:36.161414Z","caller":"traceutil/trace.go:171","msg":"trace[1502191175] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"269.151077ms","start":"2026-02-04T22:37:35.892251Z","end":"2026-02-04T22:37:36.161402Z","steps":["trace[1502191175] 'process raft request' (duration: 268.076773ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:44.337994Z","caller":"traceutil/trace.go:171","msg":"trace[1022046943] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:841; }","duration":"123.57873ms","start":"2026-02-04T22:37:44.214398Z","end":"2026-02-04T22:37:44.337977Z","steps":["trace[1022046943] 'read index received' (duration: 123.455448ms)","trace[1022046943] 'applied index is now lower than readState.Index' (duration: 122.412µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:37:44.33815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.735704ms","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-04T22:37:44.338173Z","caller":"traceutil/trace.go:171","msg":"trace[1361039039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:810; }","duration":"123.780486ms","start":"2026-02-04T22:37:44.214385Z","end":"2026-02-04T22:37:44.338165Z","steps":["trace[1361039039] 'agreement among raft nodes before linearized reading' (duration: 123.654003ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:44.338315Z","caller":"traceutil/trace.go:171","msg":"trace[333953456] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"125.73121ms","start":"2026-02-04T22:37:44.212578Z","end":"2026-02-04T22:37:44.338309Z","steps":["trace[333953456] 'process raft request' (duration: 125.31073ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:45.210498Z","caller":"traceutil/trace.go:171","msg":"trace[878690438] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"120.943981ms","start":"2026-02-04T22:37:45.089533Z","end":"2026-02-04T22:37:45.210477Z","steps":["trace[878690438] 'process raft request' (duration: 83.174031ms)","trace[878690438] 'compare' (duration: 37.565006ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T22:37:45.210949Z","caller":"traceutil/trace.go:171","msg":"trace[699637036] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"121.36123ms","start":"2026-02-04T22:37:45.089579Z","end":"2026-02-04T22:37:45.21094Z","steps":["trace[699637036] 'process raft request' (duration: 120.855419ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:45.211826Z","caller":"traceutil/trace.go:171","msg":"trace[1008002403] linearizableReadLoop","detail":"{readStateIndex:881; appliedIndex:877; }","duration":"115.045429ms","start":"2026-02-04T22:37:45.096771Z","end":"2026-02-04T22:37:45.211816Z","steps":["trace[1008002403] 'read index received' (duration: 15.436278ms)","trace[1008002403] 'applied index is now lower than readState.Index' (duration: 99.608651ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T22:37:45.211873Z","caller":"traceutil/trace.go:171","msg":"trace[729972262] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"122.191209ms","start":"2026-02-04T22:37:45.089676Z","end":"2026-02-04T22:37:45.211868Z","steps":["trace[729972262] 'process raft request' (duration: 120.833659ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:45.211981Z","caller":"traceutil/trace.go:171","msg":"trace[340326154] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"115.0906ms","start":"2026-02-04T22:37:45.096852Z","end":"2026-02-04T22:37:45.211942Z","steps":["trace[340326154] 'process raft request' (duration: 114.934606ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:45.212148Z","caller":"traceutil/trace.go:171","msg":"trace[1981999318] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"118.815743ms","start":"2026-02-04T22:37:45.093325Z","end":"2026-02-04T22:37:45.212141Z","steps":["trace[1981999318] 'process raft request' (duration: 117.637047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:45.21251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.725404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:9245"} {"level":"info","ts":"2026-02-04T22:37:45.212541Z","caller":"traceutil/trace.go:171","msg":"trace[1532062982] range","detail":"{range_begin:/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:848; }","duration":"115.783905ms","start":"2026-02-04T22:37:45.096749Z","end":"2026-02-04T22:37:45.212533Z","steps":["trace[1532062982] 'agreement among raft nodes before linearized reading' (duration: 115.662072ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:45.236526Z","caller":"traceutil/trace.go:171","msg":"trace[215378651] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"118.477966ms","start":"2026-02-04T22:37:45.118034Z","end":"2026-02-04T22:37:45.236512Z","steps":["trace[215378651] 'process raft request' (duration: 118.297572ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:45.23674Z","caller":"traceutil/trace.go:171","msg":"trace[1531400690] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"115.609322ms","start":"2026-02-04T22:37:45.121122Z","end":"2026-02-04T22:37:45.236731Z","steps":["trace[1531400690] 'process raft request' (duration: 115.337086ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:45.237146Z","caller":"traceutil/trace.go:171","msg":"trace[390755528] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"118.424905ms","start":"2026-02-04T22:37:45.118713Z","end":"2026-02-04T22:37:45.237137Z","steps":["trace[390755528] 'process raft request' (duration: 117.694899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:45.237893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.082904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-xpblr\" ","response":"range_response_count:1 size:9629"} {"level":"info","ts":"2026-02-04T22:37:45.237929Z","caller":"traceutil/trace.go:171","msg":"trace[583534542] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-xpblr; range_end:; response_count:1; response_revision:851; }","duration":"141.132885ms","start":"2026-02-04T22:37:45.096788Z","end":"2026-02-04T22:37:45.237921Z","steps":["trace[583534542] 'agreement among raft nodes before linearized reading' (duration: 141.053693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:45.592563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.284455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T22:37:45.592646Z","caller":"traceutil/trace.go:171","msg":"trace[859083596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:856; }","duration":"203.399237ms","start":"2026-02-04T22:37:45.389227Z","end":"2026-02-04T22:37:45.592626Z","steps":["trace[859083596] 'agreement among raft nodes before linearized reading' (duration: 60.973032ms)","trace[859083596] 'range keys from in-memory index tree' (duration: 142.121288ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T22:37:45.593478Z","caller":"traceutil/trace.go:171","msg":"trace[356920847] linearizableReadLoop","detail":"{readStateIndex:892; appliedIndex:890; }","duration":"143.304955ms","start":"2026-02-04T22:37:45.45016Z","end":"2026-02-04T22:37:45.593465Z","steps":["trace[356920847] 'read index received' (duration: 50.818004ms)","trace[356920847] 'applied index is now lower than readState.Index' (duration: 92.486251ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T22:37:45.593731Z","caller":"traceutil/trace.go:171","msg":"trace[1107364856] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"204.413749ms","start":"2026-02-04T22:37:45.389305Z","end":"2026-02-04T22:37:45.593719Z","steps":["trace[1107364856] 'process raft request' (duration: 111.663552ms)","trace[1107364856] 'compare' (duration: 91.95464ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T22:37:45.593936Z","caller":"traceutil/trace.go:171","msg":"trace[236170396] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"204.375999ms","start":"2026-02-04T22:37:45.389551Z","end":"2026-02-04T22:37:45.593927Z","steps":["trace[236170396] 'process raft request' (duration: 203.860297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:45.594095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.772258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-tzvwh\" ","response":"range_response_count:1 size:10625"} {"level":"info","ts":"2026-02-04T22:37:45.594128Z","caller":"traceutil/trace.go:171","msg":"trace[1012135014] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-tzvwh; range_end:; response_count:1; response_revision:858; }","duration":"204.828669ms","start":"2026-02-04T22:37:45.389286Z","end":"2026-02-04T22:37:45.594115Z","steps":["trace[1012135014] 'agreement among raft nodes before linearized reading' (duration: 204.743627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:45.622728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.200622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:754"} {"level":"info","ts":"2026-02-04T22:37:45.62278Z","caller":"traceutil/trace.go:171","msg":"trace[1056807820] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:858; }","duration":"151.281135ms","start":"2026-02-04T22:37:45.471484Z","end":"2026-02-04T22:37:45.622765Z","steps":["trace[1056807820] 'agreement among raft nodes before linearized reading' (duration: 151.143432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:45.623072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.494299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:754"} {"level":"info","ts":"2026-02-04T22:37:45.623094Z","caller":"traceutil/trace.go:171","msg":"trace[137191758] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:858; }","duration":"151.54156ms","start":"2026-02-04T22:37:45.471546Z","end":"2026-02-04T22:37:45.623088Z","steps":["trace[137191758] 'agreement among raft nodes before linearized reading' (duration: 151.472298ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:45.623328Z","caller":"traceutil/trace.go:171","msg":"trace[775091735] transaction","detail":"{read_only:false; number_of_response:1; response_revision:858; }","duration":"164.759268ms","start":"2026-02-04T22:37:45.458562Z","end":"2026-02-04T22:37:45.623322Z","steps":["trace[775091735] 'process raft request' (duration: 163.93591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:45.626569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.479776ms","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:363642"} {"level":"info","ts":"2026-02-04T22:37:45.626647Z","caller":"traceutil/trace.go:171","msg":"trace[862849169] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:858; }","duration":"114.590429ms","start":"2026-02-04T22:37:45.512016Z","end":"2026-02-04T22:37:45.626607Z","steps":["trace[862849169] 'agreement among raft nodes before linearized reading' (duration: 110.61586ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:45.744509Z","caller":"traceutil/trace.go:171","msg":"trace[760144443] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"138.632599ms","start":"2026-02-04T22:37:45.605857Z","end":"2026-02-04T22:37:45.74449Z","steps":["trace[760144443] 'process raft request' (duration: 138.425135ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:45.745032Z","caller":"traceutil/trace.go:171","msg":"trace[2830709] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"136.511131ms","start":"2026-02-04T22:37:45.608515Z","end":"2026-02-04T22:37:45.745026Z","steps":["trace[2830709] 'process raft request' (duration: 135.928148ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:45.745171Z","caller":"traceutil/trace.go:171","msg":"trace[148412702] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"134.517267ms","start":"2026-02-04T22:37:45.610649Z","end":"2026-02-04T22:37:45.745166Z","steps":["trace[148412702] 'process raft request' (duration: 134.265371ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:45.746887Z","caller":"traceutil/trace.go:171","msg":"trace[1813964688] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:893; }","duration":"120.747987ms","start":"2026-02-04T22:37:45.626131Z","end":"2026-02-04T22:37:45.746879Z","steps":["trace[1813964688] 'read index received' (duration: 118.149839ms)","trace[1813964688] 'applied index is now lower than readState.Index' (duration: 2.596988ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:37:45.746964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.818589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-tzvwh\" ","response":"range_response_count:1 size:12337"} {"level":"info","ts":"2026-02-04T22:37:45.746981Z","caller":"traceutil/trace.go:171","msg":"trace[2115418444] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-tzvwh; range_end:; response_count:1; response_revision:861; }","duration":"120.868219ms","start":"2026-02-04T22:37:45.626106Z","end":"2026-02-04T22:37:45.746975Z","steps":["trace[2115418444] 'agreement among raft nodes before linearized reading' (duration: 120.820998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:45.747071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.815228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-tzvwh\" ","response":"range_response_count:1 size:12337"} {"level":"info","ts":"2026-02-04T22:37:45.747083Z","caller":"traceutil/trace.go:171","msg":"trace[222602442] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-tzvwh; range_end:; response_count:1; response_revision:861; }","duration":"120.842859ms","start":"2026-02-04T22:37:45.626236Z","end":"2026-02-04T22:37:45.747079Z","steps":["trace[222602442] 'agreement among raft nodes before linearized reading' (duration: 120.820218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:45.747215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.482369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb\" ","response":"range_response_count:1 size:10739"} {"level":"info","ts":"2026-02-04T22:37:45.74723Z","caller":"traceutil/trace.go:171","msg":"trace[861957980] range","detail":"{range_begin:/registry/replicasets/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb; range_end:; response_count:1; response_revision:861; }","duration":"107.513459ms","start":"2026-02-04T22:37:45.63971Z","end":"2026-02-04T22:37:45.747224Z","steps":["trace[861957980] 'agreement among raft nodes before linearized reading' (duration: 107.445277ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:48.472993Z","caller":"traceutil/trace.go:171","msg":"trace[1750028443] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:906; }","duration":"101.176496ms","start":"2026-02-04T22:37:48.371745Z","end":"2026-02-04T22:37:48.472922Z","steps":["trace[1750028443] 'read index received' (duration: 100.993432ms)","trace[1750028443] 'applied index is now lower than readState.Index' (duration: 182.214µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:37:48.473277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.515204ms","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-04T22:37:48.473312Z","caller":"traceutil/trace.go:171","msg":"trace[1902255853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:872; }","duration":"101.566136ms","start":"2026-02-04T22:37:48.371735Z","end":"2026-02-04T22:37:48.473301Z","steps":["trace[1902255853] 'agreement among raft nodes before linearized reading' (duration: 101.33371ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:48.473422Z","caller":"traceutil/trace.go:171","msg":"trace[1487761065] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"116.947792ms","start":"2026-02-04T22:37:48.356445Z","end":"2026-02-04T22:37:48.473393Z","steps":["trace[1487761065] 'process raft request' (duration: 116.354478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:50.987363Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997599930443386496,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T22:37:51.01041Z","caller":"traceutil/trace.go:171","msg":"trace[1592925736] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"523.833158ms","start":"2026-02-04T22:37:50.486562Z","end":"2026-02-04T22:37:51.010395Z","steps":["trace[1592925736] 'read index received' (duration: 523.716775ms)","trace[1592925736] 'applied index is now lower than readState.Index' (duration: 115.863µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T22:37:51.010591Z","caller":"traceutil/trace.go:171","msg":"trace[336128620] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"529.391683ms","start":"2026-02-04T22:37:50.481194Z","end":"2026-02-04T22:37:51.010585Z","steps":["trace[336128620] 'process raft request' (duration: 529.121257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:51.010654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:50.481184Z","time spent":"529.428533ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T22:37:51.010791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.226296ms","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-04T22:37:51.010811Z","caller":"traceutil/trace.go:171","msg":"trace[1286494233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:879; }","duration":"524.255057ms","start":"2026-02-04T22:37:50.486551Z","end":"2026-02-04T22:37:51.010806Z","steps":["trace[1286494233] 'agreement among raft nodes before linearized reading' (duration: 524.191925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:51.010829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:50.486546Z","time spent":"524.279707ms","remote":"127.0.0.1:50356","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-04T22:37:51.010928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.87741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T22:37:51.010945Z","caller":"traceutil/trace.go:171","msg":"trace[652617607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:879; }","duration":"136.895451ms","start":"2026-02-04T22:37:50.874046Z","end":"2026-02-04T22:37:51.010942Z","steps":["trace[652617607] 'agreement among raft nodes before linearized reading' (duration: 136.85203ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:51.27584Z","caller":"traceutil/trace.go:171","msg":"trace[1544022054] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"258.776884ms","start":"2026-02-04T22:37:51.017047Z","end":"2026-02-04T22:37:51.275824Z","steps":["trace[1544022054] 'process raft request' (duration: 258.690422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:51.465648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.162615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T22:37:51.46571Z","caller":"traceutil/trace.go:171","msg":"trace[1225761580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:881; }","duration":"144.258767ms","start":"2026-02-04T22:37:51.321439Z","end":"2026-02-04T22:37:51.465698Z","steps":["trace[1225761580] 'range keys from in-memory index tree' (duration: 144.113774ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:54.223289Z","caller":"traceutil/trace.go:171","msg":"trace[1012468841] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"272.158344ms","start":"2026-02-04T22:37:53.951111Z","end":"2026-02-04T22:37:54.22327Z","steps":["trace[1012468841] 'read index received' (duration: 193.925463ms)","trace[1012468841] 'applied index is now lower than readState.Index' (duration: 78.231611ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:37:54.223415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.287057ms","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-04T22:37:54.223432Z","caller":"traceutil/trace.go:171","msg":"trace[2056257791] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:888; }","duration":"272.342359ms","start":"2026-02-04T22:37:53.951085Z","end":"2026-02-04T22:37:54.223428Z","steps":["trace[2056257791] 'agreement among raft nodes before linearized reading' (duration: 272.281407ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:37:57.246356Z","caller":"traceutil/trace.go:171","msg":"trace[157617627] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"117.296121ms","start":"2026-02-04T22:37:57.129049Z","end":"2026-02-04T22:37:57.246345Z","steps":["trace[157617627] 'process raft request' (duration: 117.228829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:57.69257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.164761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T22:37:57.692732Z","caller":"traceutil/trace.go:171","msg":"trace[803118455] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"398.316573ms","start":"2026-02-04T22:37:57.294397Z","end":"2026-02-04T22:37:57.692714Z","steps":["trace[803118455] 'process raft request' (duration: 226.785823ms)","trace[803118455] 'compare' (duration: 170.999768ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:37:57.692819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:57.294386Z","time spent":"398.383855ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T22:37:57.693243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.771649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T22:37:57.693265Z","caller":"traceutil/trace.go:171","msg":"trace[84453294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"376.81149ms","start":"2026-02-04T22:37:57.316447Z","end":"2026-02-04T22:37:57.693259Z","steps":["trace[84453294] 'agreement among raft nodes before linearized reading' (duration: 376.768729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:37:57.693297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:37:57.316439Z","time spent":"376.85506ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T22:37:57.69263Z","caller":"traceutil/trace.go:171","msg":"trace[2084074560] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:933; }","duration":"376.146774ms","start":"2026-02-04T22:37:57.316473Z","end":"2026-02-04T22:37:57.69262Z","steps":["trace[2084074560] 'read index received' (duration: 204.652945ms)","trace[2084074560] 'applied index is now lower than readState.Index' (duration: 171.492839ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T22:38:01.991318Z","caller":"traceutil/trace.go:171","msg":"trace[1927234293] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"146.451315ms","start":"2026-02-04T22:38:01.844853Z","end":"2026-02-04T22:38:01.991304Z","steps":["trace[1927234293] 'read index received' (duration: 146.354043ms)","trace[1927234293] 'applied index is now lower than readState.Index' (duration: 96.322µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:38:01.991424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.559878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T22:38:01.991439Z","caller":"traceutil/trace.go:171","msg":"trace[1846909897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:909; }","duration":"146.611949ms","start":"2026-02-04T22:38:01.844823Z","end":"2026-02-04T22:38:01.991435Z","steps":["trace[1846909897] 'agreement among raft nodes before linearized reading' (duration: 146.534047ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:38:01.991461Z","caller":"traceutil/trace.go:171","msg":"trace[1666528110] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"280.663705ms","start":"2026-02-04T22:38:01.710779Z","end":"2026-02-04T22:38:01.991443Z","steps":["trace[1666528110] 'process raft request' (duration: 280.44132ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:38:02.398494Z","caller":"traceutil/trace.go:171","msg":"trace[1032261006] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"404.314067ms","start":"2026-02-04T22:38:01.994166Z","end":"2026-02-04T22:38:02.39848Z","steps":["trace[1032261006] 'process raft request' (duration: 403.97536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:38:02.398584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:38:01.994154Z","time spent":"404.376609ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T22:38:03.335297Z","caller":"traceutil/trace.go:171","msg":"trace[462095881] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"262.510277ms","start":"2026-02-04T22:38:03.072771Z","end":"2026-02-04T22:38:03.335281Z","steps":["trace[462095881] 'process raft request' (duration: 262.162369ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:38:18.264828Z","caller":"traceutil/trace.go:171","msg":"trace[1861622406] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"125.633027ms","start":"2026-02-04T22:38:18.13917Z","end":"2026-02-04T22:38:18.264803Z","steps":["trace[1861622406] 'read index received' (duration: 125.467713ms)","trace[1861622406] 'applied index is now lower than readState.Index' (duration: 164.184µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T22:38:18.265221Z","caller":"traceutil/trace.go:171","msg":"trace[2123669626] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"172.990343ms","start":"2026-02-04T22:38:18.092215Z","end":"2026-02-04T22:38:18.265205Z","steps":["trace[2123669626] 'process raft request' (duration: 172.478601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:38:18.265603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.414484ms","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:508"} {"level":"info","ts":"2026-02-04T22:38:18.265641Z","caller":"traceutil/trace.go:171","msg":"trace[1977481103] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:995; }","duration":"126.486306ms","start":"2026-02-04T22:38:18.139143Z","end":"2026-02-04T22:38:18.265629Z","steps":["trace[1977481103] 'agreement among raft nodes before linearized reading' (duration: 125.74985ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:38:22.404196Z","caller":"traceutil/trace.go:171","msg":"trace[1749924268] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"120.4052ms","start":"2026-02-04T22:38:22.283769Z","end":"2026-02-04T22:38:22.404174Z","steps":["trace[1749924268] 'process raft request' (duration: 119.948079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:38:23.281291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.965169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T22:38:23.28134Z","caller":"traceutil/trace.go:171","msg":"trace[2071604917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1030; }","duration":"795.02146ms","start":"2026-02-04T22:38:22.486308Z","end":"2026-02-04T22:38:23.28133Z","steps":["trace[2071604917] 'range keys from in-memory index tree' (duration: 794.786895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:38:23.281364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:38:22.486158Z","time spent":"795.201234ms","remote":"127.0.0.1:50356","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-04T22:38:23.281491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.967527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T22:38:23.281508Z","caller":"traceutil/trace.go:171","msg":"trace[1446135623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1030; }","duration":"790.000547ms","start":"2026-02-04T22:38:22.491502Z","end":"2026-02-04T22:38:23.281502Z","steps":["trace[1446135623] 'range keys from in-memory index tree' (duration: 789.905245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:38:23.281524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:38:22.491493Z","time spent":"790.026807ms","remote":"127.0.0.1:50356","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-04T22:38:23.281654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.696045ms","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:533"} {"level":"info","ts":"2026-02-04T22:38:23.281671Z","caller":"traceutil/trace.go:171","msg":"trace[1774956538] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1030; }","duration":"699.735107ms","start":"2026-02-04T22:38:22.581931Z","end":"2026-02-04T22:38:23.281666Z","steps":["trace[1774956538] 'range keys from in-memory index tree' (duration: 699.633384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:38:23.281687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:38:22.58192Z","time spent":"699.763127ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-04T22:38:23.283761Z","caller":"traceutil/trace.go:171","msg":"trace[11600523] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"523.153812ms","start":"2026-02-04T22:38:22.760574Z","end":"2026-02-04T22:38:23.283728Z","steps":["trace[11600523] 'process raft request' (duration: 457.720929ms)","trace[11600523] 'compare' (duration: 62.794844ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:38:23.285057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:38:22.760554Z","time spent":"524.458432ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T22:39:45.848623Z","caller":"traceutil/trace.go:171","msg":"trace[1597217541] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"112.896961ms","start":"2026-02-04T22:39:45.735704Z","end":"2026-02-04T22:39:45.848601Z","steps":["trace[1597217541] 'process raft request' (duration: 42.151887ms)","trace[1597217541] 'compare' (duration: 70.420986ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T22:39:46.052695Z","caller":"traceutil/trace.go:171","msg":"trace[1707564532] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1430; }","duration":"151.566291ms","start":"2026-02-04T22:39:45.901111Z","end":"2026-02-04T22:39:46.052677Z","steps":["trace[1707564532] 'read index received' (duration: 98.976682ms)","trace[1707564532] 'applied index is now lower than readState.Index' (duration: 52.588909ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:39:46.052847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.721445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-04T22:39:46.052883Z","caller":"traceutil/trace.go:171","msg":"trace[1033786493] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1374; }","duration":"151.796627ms","start":"2026-02-04T22:39:45.901079Z","end":"2026-02-04T22:39:46.052876Z","steps":["trace[1033786493] 'agreement among raft nodes before linearized reading' (duration: 151.661603ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:39:46.053133Z","caller":"traceutil/trace.go:171","msg":"trace[1254851056] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"197.477713ms","start":"2026-02-04T22:39:45.855647Z","end":"2026-02-04T22:39:46.053125Z","steps":["trace[1254851056] 'process raft request' (duration: 144.479884ms)","trace[1254851056] 'compare' (duration: 52.383954ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T22:39:46.053214Z","caller":"traceutil/trace.go:171","msg":"trace[24945463] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"196.283336ms","start":"2026-02-04T22:39:45.856925Z","end":"2026-02-04T22:39:46.053208Z","steps":["trace[24945463] 'process raft request' (duration: 195.690352ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:39:46.053279Z","caller":"traceutil/trace.go:171","msg":"trace[925024952] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"163.797813ms","start":"2026-02-04T22:39:45.889474Z","end":"2026-02-04T22:39:46.053272Z","steps":["trace[925024952] 'process raft request' (duration: 163.173918ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:41:26.238246Z","caller":"traceutil/trace.go:171","msg":"trace[1246788930] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1887; }","duration":"206.193285ms","start":"2026-02-04T22:41:26.031965Z","end":"2026-02-04T22:41:26.238159Z","steps":["trace[1246788930] 'read index received' (duration: 205.96289ms)","trace[1246788930] 'applied index is now lower than readState.Index' (duration: 229.585µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T22:41:26.238475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.487012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-04T22:41:26.238523Z","caller":"traceutil/trace.go:171","msg":"trace[423860466] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1808; }","duration":"206.590145ms","start":"2026-02-04T22:41:26.031922Z","end":"2026-02-04T22:41:26.238512Z","steps":["trace[423860466] 'agreement among raft nodes before linearized reading' (duration: 206.403621ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T22:41:26.238825Z","caller":"traceutil/trace.go:171","msg":"trace[794327233] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"351.589132ms","start":"2026-02-04T22:41:25.887226Z","end":"2026-02-04T22:41:26.238815Z","steps":["trace[794327233] 'process raft request' (duration: 350.815845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T22:41:26.238905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T22:41:25.887209Z","time spent":"351.647034ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T22:41:26.441965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.624747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T22:41:26.44304Z","caller":"traceutil/trace.go:171","msg":"trace[525767276] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"197.00707ms","start":"2026-02-04T22:41:26.246003Z","end":"2026-02-04T22:41:26.44301Z","steps":["trace[525767276] 'process raft request' (duration: 65.241097ms)","trace[525767276] 'compare' (duration: 130.30172ms)"],"step_count":2}