{"level":"warn","ts":"2026-02-05T05:25:32.362324Z","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-05T05:25:32.362433Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.212:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.212:2380","--initial-cluster=instance=https://162.253.55.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.212:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-05T05:25:32.362755Z","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-05T05:25:32.362836Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-05T05:25:32.362864Z","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-05T05:25:32.363983Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"]} {"level":"info","ts":"2026-02-05T05:25:32.364129Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.212:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-05T05:25:32.428408Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"63.984917ms"} {"level":"info","ts":"2026-02-05T05:25:32.444288Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d078b1e39c21939c","cluster-id":"cdc9ba8de57e3589"} {"level":"info","ts":"2026-02-05T05:25:32.44438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T05:25:32.444433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 0"} {"level":"info","ts":"2026-02-05T05:25:32.444449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d078b1e39c21939c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T05:25:32.444459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 1"} {"level":"info","ts":"2026-02-05T05:25:32.444525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"warn","ts":"2026-02-05T05:25:32.461805Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T05:25:32.464338Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T05:25:32.467368Z","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-05T05:25:32.472614Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d078b1e39c21939c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T05:25:32.47377Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d078b1e39c21939c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T05:25:32.473969Z","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-05T05:25:32.474042Z","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-05T05:25:32.474061Z","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-05T05:25:32.477609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"info","ts":"2026-02-05T05:25:32.478642Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","added-peer-id":"d078b1e39c21939c","added-peer-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-05T05:25:32.480487Z","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-05T05:25:32.48076Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-05T05:25:32.480774Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-05T05:25:32.483508Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d078b1e39c21939c","initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T05:25:32.483955Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T05:25:32.549797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T05:25:32.54984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T05:25:32.549855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgPreVoteResp from d078b1e39c21939c at term 1"} {"level":"info","ts":"2026-02-05T05:25:32.549866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became candidate at term 2"} {"level":"info","ts":"2026-02-05T05:25:32.549872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgVoteResp from d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-05T05:25:32.54988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became leader at term 2"} {"level":"info","ts":"2026-02-05T05:25:32.549888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d078b1e39c21939c elected leader d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-05T05:25:32.567636Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T05:25:32.567962Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:25:32.567594Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d078b1e39c21939c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.212:2379]}","request-path":"/0/members/d078b1e39c21939c/attributes","cluster-id":"cdc9ba8de57e3589","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T05:25:32.568049Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T05:25:32.572596Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T05:25:32.574808Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.212:2379"} {"level":"info","ts":"2026-02-05T05:25:32.575063Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:25:32.575109Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:25:32.575125Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:25:32.575228Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T05:25:32.575243Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T05:25:45.709307Z","caller":"traceutil/trace.go:171","msg":"trace[1432971544] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"107.811742ms","start":"2026-02-05T05:25:45.601476Z","end":"2026-02-05T05:25:45.709288Z","steps":["trace[1432971544] 'process raft request' (duration: 107.687659ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:25:51.370448Z","caller":"traceutil/trace.go:171","msg":"trace[1419699735] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"174.45326ms","start":"2026-02-05T05:25:51.195964Z","end":"2026-02-05T05:25:51.370418Z","steps":["trace[1419699735] 'read index received' (duration: 174.195274ms)","trace[1419699735] 'applied index is now lower than readState.Index' (duration: 256.586µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:25:51.370673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.668675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T05:25:51.370746Z","caller":"traceutil/trace.go:171","msg":"trace[1426258513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:374; }","duration":"174.798158ms","start":"2026-02-05T05:25:51.195933Z","end":"2026-02-05T05:25:51.370732Z","steps":["trace[1426258513] 'agreement among raft nodes before linearized reading' (duration: 174.658715ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:25:51.370736Z","caller":"traceutil/trace.go:171","msg":"trace[1760473759] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"400.01464ms","start":"2026-02-05T05:25:50.970696Z","end":"2026-02-05T05:25:51.370711Z","steps":["trace[1760473759] 'process raft request' (duration: 399.499428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:51.37138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:50.970675Z","time spent":"400.122503ms","remote":"127.0.0.1:47696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4245,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:25:51.896604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.211819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:25:51.896738Z","caller":"traceutil/trace.go:171","msg":"trace[8690882] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:383; }","duration":"516.699414ms","start":"2026-02-05T05:25:51.380019Z","end":"2026-02-05T05:25:51.896718Z","steps":["trace[8690882] 'read index received' (duration: 120.908181ms)","trace[8690882] 'applied index is now lower than readState.Index' (duration: 395.789953ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:25:51.896796Z","caller":"traceutil/trace.go:171","msg":"trace[1216099576] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"518.26423ms","start":"2026-02-05T05:25:51.378474Z","end":"2026-02-05T05:25:51.896738Z","steps":["trace[1216099576] 'process raft request' (duration: 122.515457ms)","trace[1216099576] 'compare' (duration: 395.068076ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:25:51.896879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.854559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-05T05:25:51.896918Z","caller":"traceutil/trace.go:171","msg":"trace[151580578] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:375; }","duration":"516.913989ms","start":"2026-02-05T05:25:51.379992Z","end":"2026-02-05T05:25:51.896906Z","steps":["trace[151580578] 'agreement among raft nodes before linearized reading' (duration: 516.832958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:51.896972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:51.379984Z","time spent":"516.974821ms","remote":"127.0.0.1:47696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4284,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-05T05:25:51.896997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:51.378441Z","time spent":"518.429185ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:25:51.897278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.645568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:25:51.897313Z","caller":"traceutil/trace.go:171","msg":"trace[1360898258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:375; }","duration":"432.709339ms","start":"2026-02-05T05:25:51.464595Z","end":"2026-02-05T05:25:51.897304Z","steps":["trace[1360898258] 'agreement among raft nodes before linearized reading' (duration: 432.648628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:51.897349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:51.464579Z","time spent":"432.75776ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T05:25:52.508244Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548133863465069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:25:54.120398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488982961s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:25:54.120535Z","caller":"traceutil/trace.go:171","msg":"trace[926303810] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"2.216669462s","start":"2026-02-05T05:25:51.903836Z","end":"2026-02-05T05:25:54.120506Z","steps":["trace[926303810] 'process raft request' (duration: 727.450976ms)","trace[926303810] 'compare' (duration: 1.488586852s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:25:54.120605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:51.903814Z","time spent":"2.216759155s","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:25:54.389169Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.757892596s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T05:25:54.407004Z","caller":"traceutil/trace.go:171","msg":"trace[1712220971] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:384; }","duration":"2.39977323s","start":"2026-02-05T05:25:52.007191Z","end":"2026-02-05T05:25:54.406965Z","steps":["trace[1712220971] 'read index received' (duration: 624.116717ms)","trace[1712220971] 'applied index is now lower than readState.Index' (duration: 1.775655123s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:25:54.410415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.403213518s","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-05T05:25:54.410477Z","caller":"traceutil/trace.go:171","msg":"trace[917317327] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"2.403336741s","start":"2026-02-05T05:25:52.007126Z","end":"2026-02-05T05:25:54.410462Z","steps":["trace[917317327] 'agreement among raft nodes before linearized reading' (duration: 2.40201627s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:54.410517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:52.007098Z","time spent":"2.403411733s","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-05T05:25:54.412195Z","caller":"traceutil/trace.go:171","msg":"trace[1973058052] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"2.502847392s","start":"2026-02-05T05:25:51.909326Z","end":"2026-02-05T05:25:54.412173Z","steps":["trace[1973058052] 'process raft request' (duration: 2.48050439s)","trace[1973058052] 'compare' (duration: 16.447127ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:25:54.412288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:51.909299Z","time spent":"2.502929554s","remote":"127.0.0.1:47696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4053,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:25:54.478013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.897482895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T05:25:54.478137Z","caller":"traceutil/trace.go:171","msg":"trace[740762317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:377; }","duration":"1.89767395s","start":"2026-02-05T05:25:52.580435Z","end":"2026-02-05T05:25:54.478109Z","steps":["trace[740762317] 'agreement among raft nodes before linearized reading' (duration: 1.897486045s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:54.478189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:52.580408Z","time spent":"1.897767301s","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:25:54.478042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.793840159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:25:54.478673Z","caller":"traceutil/trace.go:171","msg":"trace[29721858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"1.794525054s","start":"2026-02-05T05:25:52.684101Z","end":"2026-02-05T05:25:54.478626Z","steps":["trace[29721858] 'agreement among raft nodes before linearized reading' (duration: 1.793792708s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:54.478737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:52.684061Z","time spent":"1.794660798s","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:25:54.478744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.937828641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-05T05:25:54.478783Z","caller":"traceutil/trace.go:171","msg":"trace[977141605] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:377; }","duration":"1.937908063s","start":"2026-02-05T05:25:52.540864Z","end":"2026-02-05T05:25:54.478772Z","steps":["trace[977141605] 'agreement among raft nodes before linearized reading' (duration: 1.937816801s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:54.478813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:52.540842Z","time spent":"1.937964694s","remote":"127.0.0.1:47932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4058,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-05T05:25:54.479142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.864197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T05:25:54.479179Z","caller":"traceutil/trace.go:171","msg":"trace[1574669516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:377; }","duration":"574.897888ms","start":"2026-02-05T05:25:53.904268Z","end":"2026-02-05T05:25:54.479166Z","steps":["trace[1574669516] 'agreement among raft nodes before linearized reading' (duration: 574.785415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:54.480442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:53.904192Z","time spent":"576.220899ms","remote":"127.0.0.1:47742","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-05T05:26:09.259244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.117654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4062"} {"level":"info","ts":"2026-02-05T05:26:09.259344Z","caller":"traceutil/trace.go:171","msg":"trace[1180749670] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:475; }","duration":"948.272927ms","start":"2026-02-05T05:26:08.311051Z","end":"2026-02-05T05:26:09.259324Z","steps":["trace[1180749670] 'range keys from in-memory index tree' (duration: 948.005111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:09.259433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:08.311028Z","time spent":"948.352729ms","remote":"127.0.0.1:47694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4086,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-05T05:26:09.259555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.58428ms","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-05T05:26:09.259746Z","caller":"traceutil/trace.go:171","msg":"trace[1260332077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:475; }","duration":"683.784516ms","start":"2026-02-05T05:26:08.575861Z","end":"2026-02-05T05:26:09.259646Z","steps":["trace[1260332077] 'range keys from in-memory index tree' (duration: 683.432807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:09.259789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.965764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-05T05:26:09.259807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:08.575842Z","time spent":"683.951459ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-05T05:26:09.259824Z","caller":"traceutil/trace.go:171","msg":"trace[2116744049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:475; }","duration":"576.023295ms","start":"2026-02-05T05:26:08.683791Z","end":"2026-02-05T05:26:09.259814Z","steps":["trace[2116744049] 'range keys from in-memory index tree' (duration: 575.920233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:09.259938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:08.683779Z","time spent":"576.147817ms","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:26:09.260146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.503363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T05:26:09.260174Z","caller":"traceutil/trace.go:171","msg":"trace[1889129092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"640.559805ms","start":"2026-02-05T05:26:08.619605Z","end":"2026-02-05T05:26:09.260165Z","steps":["trace[1889129092] 'range keys from in-memory index tree' (duration: 640.37288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:09.260208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:08.619524Z","time spent":"640.674117ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:26:09.260306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.476448ms","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-05T05:26:09.260342Z","caller":"traceutil/trace.go:171","msg":"trace[641095661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"635.523878ms","start":"2026-02-05T05:26:08.624807Z","end":"2026-02-05T05:26:09.260331Z","steps":["trace[641095661] 'range keys from in-memory index tree' (duration: 635.408376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:09.260369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:08.6248Z","time spent":"635.563049ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-05T05:26:09.419787Z","caller":"traceutil/trace.go:171","msg":"trace[1938099527] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"148.012333ms","start":"2026-02-05T05:26:09.271757Z","end":"2026-02-05T05:26:09.419769Z","steps":["trace[1938099527] 'process raft request' (duration: 147.981752ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:09.420086Z","caller":"traceutil/trace.go:171","msg":"trace[560796984] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"148.617406ms","start":"2026-02-05T05:26:09.271435Z","end":"2026-02-05T05:26:09.420052Z","steps":["trace[560796984] 'process raft request' (duration: 148.180457ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:09.420214Z","caller":"traceutil/trace.go:171","msg":"trace[1173150441] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"148.723949ms","start":"2026-02-05T05:26:09.271482Z","end":"2026-02-05T05:26:09.420206Z","steps":["trace[1173150441] 'process raft request' (duration: 148.216387ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:10.406225Z","caller":"traceutil/trace.go:171","msg":"trace[1188617326] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"197.394155ms","start":"2026-02-05T05:26:10.208814Z","end":"2026-02-05T05:26:10.406208Z","steps":["trace[1188617326] 'process raft request' (duration: 197.297573ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:11.541023Z","caller":"traceutil/trace.go:171","msg":"trace[841558730] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"116.264435ms","start":"2026-02-05T05:26:11.42474Z","end":"2026-02-05T05:26:11.541004Z","steps":["trace[841558730] 'read index received' (duration: 116.126012ms)","trace[841558730] 'applied index is now lower than readState.Index' (duration: 137.943µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:11.541249Z","caller":"traceutil/trace.go:171","msg":"trace[1532538965] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"202.353139ms","start":"2026-02-05T05:26:11.33889Z","end":"2026-02-05T05:26:11.541243Z","steps":["trace[1532538965] 'process raft request' (duration: 202.023361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:11.541439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.680395ms","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-05T05:26:11.541461Z","caller":"traceutil/trace.go:171","msg":"trace[696875496] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:482; }","duration":"116.716755ms","start":"2026-02-05T05:26:11.424735Z","end":"2026-02-05T05:26:11.541452Z","steps":["trace[696875496] 'agreement among raft nodes before linearized reading' (duration: 116.620533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:11.544958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.122505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T05:26:11.544993Z","caller":"traceutil/trace.go:171","msg":"trace[68000954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:482; }","duration":"117.173916ms","start":"2026-02-05T05:26:11.427812Z","end":"2026-02-05T05:26:11.544986Z","steps":["trace[68000954] 'agreement among raft nodes before linearized reading' (duration: 113.710417ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:12.99922Z","caller":"traceutil/trace.go:171","msg":"trace[1555192937] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"586.386382ms","start":"2026-02-05T05:26:12.412802Z","end":"2026-02-05T05:26:12.999188Z","steps":["trace[1555192937] 'process raft request' (duration: 586.229958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:12.999371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:12.412789Z","time spent":"586.505014ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:13.047969Z","caller":"traceutil/trace.go:171","msg":"trace[1667315029] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:498; }","duration":"364.748111ms","start":"2026-02-05T05:26:12.683199Z","end":"2026-02-05T05:26:13.047947Z","steps":["trace[1667315029] 'read index received' (duration: 316.162316ms)","trace[1667315029] 'applied index is now lower than readState.Index' (duration: 48.584525ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:13.048084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.860073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:13.048114Z","caller":"traceutil/trace.go:171","msg":"trace[1832696255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"364.954645ms","start":"2026-02-05T05:26:12.683151Z","end":"2026-02-05T05:26:13.048105Z","steps":["trace[1832696255] 'agreement among raft nodes before linearized reading' (duration: 364.872573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:13.048149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:12.683134Z","time spent":"365.004776ms","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T05:26:14.004382Z","caller":"traceutil/trace.go:171","msg":"trace[1079449007] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"431.380039ms","start":"2026-02-05T05:26:13.572981Z","end":"2026-02-05T05:26:14.004361Z","steps":["trace[1079449007] 'read index received' (duration: 431.217035ms)","trace[1079449007] 'applied index is now lower than readState.Index' (duration: 162.364µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:14.004464Z","caller":"traceutil/trace.go:171","msg":"trace[1511770127] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"445.117614ms","start":"2026-02-05T05:26:13.559339Z","end":"2026-02-05T05:26:14.004456Z","steps":["trace[1511770127] 'process raft request' (duration: 444.912669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:14.004552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:13.559327Z","time spent":"445.158115ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:26:14.004663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.602502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T05:26:14.00472Z","caller":"traceutil/trace.go:171","msg":"trace[956986444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:486; }","duration":"427.695324ms","start":"2026-02-05T05:26:13.577009Z","end":"2026-02-05T05:26:14.004704Z","steps":["trace[956986444] 'agreement among raft nodes before linearized reading' (duration: 427.601401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:14.004749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:13.576989Z","time spent":"427.754375ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:26:14.004813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.828579ms","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-05T05:26:14.004842Z","caller":"traceutil/trace.go:171","msg":"trace[1120473304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:486; }","duration":"431.8754ms","start":"2026-02-05T05:26:13.572958Z","end":"2026-02-05T05:26:14.004833Z","steps":["trace[1120473304] 'agreement among raft nodes before linearized reading' (duration: 431.824849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:14.004861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:13.572948Z","time spent":"431.909031ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-05T05:26:14.213266Z","caller":"traceutil/trace.go:171","msg":"trace[1075388598] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"203.976066ms","start":"2026-02-05T05:26:14.009269Z","end":"2026-02-05T05:26:14.213245Z","steps":["trace[1075388598] 'process raft request' (duration: 164.291307ms)","trace[1075388598] 'compare' (duration: 39.471424ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:15.086337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.757021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:15.086445Z","caller":"traceutil/trace.go:171","msg":"trace[1495883804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"402.877214ms","start":"2026-02-05T05:26:14.683518Z","end":"2026-02-05T05:26:15.086395Z","steps":["trace[1495883804] 'range keys from in-memory index tree' (duration: 402.68469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:15.086488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:14.683499Z","time spent":"402.980197ms","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:26:19.81042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129141833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:19.810498Z","caller":"traceutil/trace.go:171","msg":"trace[715775683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"1.129250665s","start":"2026-02-05T05:26:18.681228Z","end":"2026-02-05T05:26:19.810478Z","steps":["trace[715775683] 'range keys from in-memory index tree' (duration: 1.129092932s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:19.810539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:18.681215Z","time spent":"1.129313316s","remote":"127.0.0.1:47518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:26:19.811002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.880557ms","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-05T05:26:19.811079Z","caller":"traceutil/trace.go:171","msg":"trace[721837459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:499; }","duration":"657.007841ms","start":"2026-02-05T05:26:19.15405Z","end":"2026-02-05T05:26:19.811058Z","steps":["trace[721837459] 'range keys from in-memory index tree' (duration: 656.632872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:19.811119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:19.154028Z","time spent":"657.082853ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-05T05:26:20.07544Z","caller":"traceutil/trace.go:171","msg":"trace[578192062] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"253.676474ms","start":"2026-02-05T05:26:19.821727Z","end":"2026-02-05T05:26:20.075403Z","steps":["trace[578192062] 'read index received' (duration: 253.299386ms)","trace[578192062] 'applied index is now lower than readState.Index' (duration: 375.488µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:20.075566Z","caller":"traceutil/trace.go:171","msg":"trace[710467630] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"258.581187ms","start":"2026-02-05T05:26:19.816954Z","end":"2026-02-05T05:26:20.075535Z","steps":["trace[710467630] 'process raft request' (duration: 258.051515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:20.075722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.973841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:20.078085Z","caller":"traceutil/trace.go:171","msg":"trace[1768536319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"256.362177ms","start":"2026-02-05T05:26:19.821702Z","end":"2026-02-05T05:26:20.078064Z","steps":["trace[1768536319] 'agreement among raft nodes before linearized reading' (duration: 253.938211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:20.753175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.116704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:26:20.753248Z","caller":"traceutil/trace.go:171","msg":"trace[368134637] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"622.222433ms","start":"2026-02-05T05:26:20.131014Z","end":"2026-02-05T05:26:20.753236Z","steps":["trace[368134637] 'read index received' (duration: 446.964686ms)","trace[368134637] 'applied index is now lower than readState.Index' (duration: 175.257157ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:20.753392Z","caller":"traceutil/trace.go:171","msg":"trace[2126269352] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"669.542128ms","start":"2026-02-05T05:26:20.083839Z","end":"2026-02-05T05:26:20.753381Z","steps":["trace[2126269352] 'process raft request' (duration: 494.161218ms)","trace[2126269352] 'compare' (duration: 175.000591ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:20.753512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:20.083823Z","time spent":"669.667962ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:26:20.753798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.809037ms","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-05T05:26:20.753818Z","caller":"traceutil/trace.go:171","msg":"trace[1179804474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:501; }","duration":"622.859919ms","start":"2026-02-05T05:26:20.130951Z","end":"2026-02-05T05:26:20.753811Z","steps":["trace[1179804474] 'agreement among raft nodes before linearized reading' (duration: 622.744886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:20.753838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:20.130919Z","time spent":"622.91335ms","remote":"127.0.0.1:47742","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-05T05:26:20.753936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.103867ms","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-05T05:26:20.754084Z","caller":"traceutil/trace.go:171","msg":"trace[173871064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:501; }","duration":"501.283941ms","start":"2026-02-05T05:26:20.252773Z","end":"2026-02-05T05:26:20.754057Z","steps":["trace[173871064] 'agreement among raft nodes before linearized reading' (duration: 500.82354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:20.754145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:20.252685Z","time spent":"501.449115ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T05:26:21.003316Z","caller":"traceutil/trace.go:171","msg":"trace[145741879] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"237.044254ms","start":"2026-02-05T05:26:20.766243Z","end":"2026-02-05T05:26:21.003287Z","steps":["trace[145741879] 'process raft request' (duration: 235.58692ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:21.005758Z","caller":"traceutil/trace.go:171","msg":"trace[271334040] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"237.954034ms","start":"2026-02-05T05:26:20.767788Z","end":"2026-02-05T05:26:21.005742Z","steps":["trace[271334040] 'process raft request' (duration: 237.841711ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:21.005889Z","caller":"traceutil/trace.go:171","msg":"trace[594905685] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"210.748762ms","start":"2026-02-05T05:26:20.795125Z","end":"2026-02-05T05:26:21.005874Z","steps":["trace[594905685] 'process raft request' (duration: 210.582037ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:21.470039Z","caller":"traceutil/trace.go:171","msg":"trace[1115279216] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"158.939113ms","start":"2026-02-05T05:26:21.311067Z","end":"2026-02-05T05:26:21.470006Z","steps":["trace[1115279216] 'process raft request' (duration: 158.722089ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:23.124573Z","caller":"traceutil/trace.go:171","msg":"trace[763263707] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"109.467949ms","start":"2026-02-05T05:26:23.015077Z","end":"2026-02-05T05:26:23.124545Z","steps":["trace[763263707] 'process raft request' (duration: 109.004329ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:24.844323Z","caller":"traceutil/trace.go:171","msg":"trace[1441078765] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"107.30006ms","start":"2026-02-05T05:26:24.736996Z","end":"2026-02-05T05:26:24.844296Z","steps":["trace[1441078765] 'process raft request' (duration: 107.085126ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:24.997625Z","caller":"traceutil/trace.go:171","msg":"trace[87485857] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"130.366428ms","start":"2026-02-05T05:26:24.867231Z","end":"2026-02-05T05:26:24.997597Z","steps":["trace[87485857] 'process raft request' (duration: 73.208458ms)","trace[87485857] 'compare' (duration: 57.039568ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:26.426731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.06814ms","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-05T05:26:26.426809Z","caller":"traceutil/trace.go:171","msg":"trace[1857686692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"246.208085ms","start":"2026-02-05T05:26:26.180578Z","end":"2026-02-05T05:26:26.426786Z","steps":["trace[1857686692] 'range keys from in-memory index tree' (duration: 245.888347ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:28.280676Z","caller":"traceutil/trace.go:171","msg":"trace[257559598] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"164.192626ms","start":"2026-02-05T05:26:28.116465Z","end":"2026-02-05T05:26:28.280658Z","steps":["trace[257559598] 'process raft request' (duration: 164.061073ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:28.338111Z","caller":"traceutil/trace.go:171","msg":"trace[366375710] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"151.04375ms","start":"2026-02-05T05:26:28.187041Z","end":"2026-02-05T05:26:28.338085Z","steps":["trace[366375710] 'process raft request' (duration: 151.008369ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:28.338484Z","caller":"traceutil/trace.go:171","msg":"trace[389452297] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"218.63532ms","start":"2026-02-05T05:26:28.119841Z","end":"2026-02-05T05:26:28.338476Z","steps":["trace[389452297] 'process raft request' (duration: 218.064027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:31.72936Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548133863465517,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:26:32.192949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.73179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:26:32.193073Z","caller":"traceutil/trace.go:171","msg":"trace[1245828071] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.035528034s","start":"2026-02-05T05:26:31.157522Z","end":"2026-02-05T05:26:32.19305Z","steps":["trace[1245828071] 'process raft request' (duration: 817.578439ms)","trace[1245828071] 'compare' (duration: 217.400723ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:32.193141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:31.157494Z","time spent":"1.035613346s","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:32.264128Z","caller":"traceutil/trace.go:171","msg":"trace[1555123843] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:623; }","duration":"1.035193645s","start":"2026-02-05T05:26:31.228911Z","end":"2026-02-05T05:26:32.264105Z","steps":["trace[1555123843] 'read index received' (duration: 746.266674ms)","trace[1555123843] 'applied index is now lower than readState.Index' (duration: 288.926351ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:32.264227Z","caller":"traceutil/trace.go:171","msg":"trace[71714233] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"1.093797652s","start":"2026-02-05T05:26:31.170422Z","end":"2026-02-05T05:26:32.264219Z","steps":["trace[71714233] 'process raft request' (duration: 1.093140738s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:32.264324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:31.170393Z","time spent":"1.093857023s","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":799,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T05:26:32.265332Z","caller":"traceutil/trace.go:171","msg":"trace[1168706019] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.036040595s","start":"2026-02-05T05:26:31.22928Z","end":"2026-02-05T05:26:32.26532Z","steps":["trace[1168706019] 'process raft request' (duration: 1.034473899s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:32.26537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:31.229269Z","time spent":"1.036080736s","remote":"127.0.0.1:47560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T05:26:32.26673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.310551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-05T05:26:32.266763Z","caller":"traceutil/trace.go:171","msg":"trace[1614921052] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:610; }","duration":"881.431354ms","start":"2026-02-05T05:26:31.385316Z","end":"2026-02-05T05:26:32.266748Z","steps":["trace[1614921052] 'agreement among raft nodes before linearized reading' (duration: 881.283691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:32.266783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:31.385266Z","time spent":"881.511516ms","remote":"127.0.0.1:47560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10401,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-05T05:26:32.26696Z","caller":"traceutil/trace.go:171","msg":"trace[1565708616] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"1.027008209s","start":"2026-02-05T05:26:31.23994Z","end":"2026-02-05T05:26:32.266948Z","steps":["trace[1565708616] 'process raft request' (duration: 1.023979791s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:32.267007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:31.239911Z","time spent":"1.027061171s","remote":"127.0.0.1:47560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:32.268304Z","caller":"traceutil/trace.go:171","msg":"trace[1253344817] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"958.591942ms","start":"2026-02-05T05:26:31.309674Z","end":"2026-02-05T05:26:32.268266Z","steps":["trace[1253344817] 'process raft request' (duration: 954.399816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:32.26851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:31.309651Z","time spent":"958.754485ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:26:32.269016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040101226s","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-05T05:26:32.269055Z","caller":"traceutil/trace.go:171","msg":"trace[1044665978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"1.040165638s","start":"2026-02-05T05:26:31.228871Z","end":"2026-02-05T05:26:32.269036Z","steps":["trace[1044665978] 'agreement among raft nodes before linearized reading' (duration: 1.039886672s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:32.26908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:31.228854Z","time spent":"1.040219529s","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:26:32.272995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.502467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-05T05:26:32.273041Z","caller":"traceutil/trace.go:171","msg":"trace[1355274822] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:610; }","duration":"487.546198ms","start":"2026-02-05T05:26:31.785482Z","end":"2026-02-05T05:26:32.273029Z","steps":["trace[1355274822] 'agreement among raft nodes before linearized reading' (duration: 484.306644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:32.273064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:31.785386Z","time spent":"487.669671ms","remote":"127.0.0.1:47560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188519,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-05T05:26:32.277951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.156069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-05T05:26:32.285863Z","caller":"traceutil/trace.go:171","msg":"trace[681978104] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:610; }","duration":"700.088999ms","start":"2026-02-05T05:26:31.585753Z","end":"2026-02-05T05:26:32.285842Z","steps":["trace[681978104] 'agreement among raft nodes before linearized reading' (duration: 692.138029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:32.285916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:31.585738Z","time spent":"700.163641ms","remote":"127.0.0.1:47560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5201,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-05T05:26:32.278872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.695009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:32.286095Z","caller":"traceutil/trace.go:171","msg":"trace[1072086664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"603.958883ms","start":"2026-02-05T05:26:31.682129Z","end":"2026-02-05T05:26:32.286088Z","steps":["trace[1072086664] 'agreement among raft nodes before linearized reading' (duration: 596.700679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:32.286177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:31.682111Z","time spent":"604.018374ms","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:26:32.278972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.950587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T05:26:32.286355Z","caller":"traceutil/trace.go:171","msg":"trace[1728439397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:610; }","duration":"802.390015ms","start":"2026-02-05T05:26:31.483957Z","end":"2026-02-05T05:26:32.286347Z","steps":["trace[1728439397] 'agreement among raft nodes before linearized reading' (duration: 794.945757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:32.286418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:31.483936Z","time spent":"802.474567ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-05T05:26:32.596728Z","caller":"traceutil/trace.go:171","msg":"trace[893884573] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"142.364183ms","start":"2026-02-05T05:26:32.454334Z","end":"2026-02-05T05:26:32.596698Z","steps":["trace[893884573] 'process raft request' (duration: 100.495625ms)","trace[893884573] 'compare' (duration: 41.564281ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:32.60248Z","caller":"traceutil/trace.go:171","msg":"trace[674441268] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:631; }","duration":"117.228275ms","start":"2026-02-05T05:26:32.485233Z","end":"2026-02-05T05:26:32.602461Z","steps":["trace[674441268] 'read index received' (duration: 69.665818ms)","trace[674441268] 'applied index is now lower than readState.Index' (duration: 47.561706ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:32.602668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.409348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:26:32.60269Z","caller":"traceutil/trace.go:171","msg":"trace[911989060] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"117.474279ms","start":"2026-02-05T05:26:32.485206Z","end":"2026-02-05T05:26:32.602681Z","steps":["trace[911989060] 'agreement among raft nodes before linearized reading' (duration: 117.336396ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:32.602917Z","caller":"traceutil/trace.go:171","msg":"trace[449694731] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"146.479456ms","start":"2026-02-05T05:26:32.456432Z","end":"2026-02-05T05:26:32.602911Z","steps":["trace[449694731] 'process raft request' (duration: 145.683388ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:32.603201Z","caller":"traceutil/trace.go:171","msg":"trace[657655961] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"131.336713ms","start":"2026-02-05T05:26:32.471858Z","end":"2026-02-05T05:26:32.603195Z","steps":["trace[657655961] 'process raft request' (duration: 130.431983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:38.715078Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548133863465632,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:26:39.216245Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548133863465632,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:26:39.305671Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.457975787s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T05:26:39.306132Z","caller":"traceutil/trace.go:171","msg":"trace[1467419784] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"1.091354837s","start":"2026-02-05T05:26:38.21475Z","end":"2026-02-05T05:26:39.306105Z","steps":["trace[1467419784] 'read index received' (duration: 1.09107962s)","trace[1467419784] 'applied index is now lower than readState.Index' (duration: 274.006µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:39.306306Z","caller":"traceutil/trace.go:171","msg":"trace[1438113588] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.458741705s","start":"2026-02-05T05:26:37.847549Z","end":"2026-02-05T05:26:39.306291Z","steps":["trace[1438113588] 'process raft request' (duration: 1.458377177s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:39.306451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:37.847529Z","time spent":"1.458809297s","remote":"127.0.0.1:47696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5008,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:26:39.907663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.692890635s","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-05T05:26:39.907748Z","caller":"traceutil/trace.go:171","msg":"trace[250827636] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:656; }","duration":"1.693051889s","start":"2026-02-05T05:26:38.214673Z","end":"2026-02-05T05:26:39.907725Z","steps":["trace[250827636] 'agreement among raft nodes before linearized reading' (duration: 1.09194363s)","trace[250827636] 'range keys from in-memory index tree' (duration: 600.904594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:39.907786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:38.214607Z","time spent":"1.693170502s","remote":"127.0.0.1:47742","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-05T05:26:39.907986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.477979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T05:26:39.908005Z","caller":"traceutil/trace.go:171","msg":"trace[1448017770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:656; }","duration":"1.478040002s","start":"2026-02-05T05:26:38.429958Z","end":"2026-02-05T05:26:39.907998Z","steps":["trace[1448017770] 'agreement among raft nodes before linearized reading' (duration: 876.687708ms)","trace[1448017770] 'range keys from in-memory index tree' (duration: 601.275563ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:39.908026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:38.429913Z","time spent":"1.478107453s","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:26:39.908082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.42052284s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T05:26:39.908106Z","caller":"traceutil/trace.go:171","msg":"trace[1269009028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:656; }","duration":"1.42057299s","start":"2026-02-05T05:26:38.487523Z","end":"2026-02-05T05:26:39.908096Z","steps":["trace[1269009028] 'agreement among raft nodes before linearized reading' (duration: 819.203365ms)","trace[1269009028] 'range keys from in-memory index tree' (duration: 601.295293ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:39.908123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:38.487503Z","time spent":"1.420614891s","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T05:26:39.908199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.822677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:39.908216Z","caller":"traceutil/trace.go:171","msg":"trace[562540794] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:656; }","duration":"655.870569ms","start":"2026-02-05T05:26:39.25234Z","end":"2026-02-05T05:26:39.90821Z","steps":["trace[562540794] 'agreement among raft nodes before linearized reading' (duration: 54.3445ms)","trace[562540794] 'count revisions from in-memory index tree' (duration: 601.494987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:39.90823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:39.2523Z","time spent":"655.925969ms","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:26:39.908313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.317582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T05:26:39.908326Z","caller":"traceutil/trace.go:171","msg":"trace[455740462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"737.357262ms","start":"2026-02-05T05:26:39.170964Z","end":"2026-02-05T05:26:39.908321Z","steps":["trace[455740462] 'agreement among raft nodes before linearized reading' (duration: 135.730112ms)","trace[455740462] 'range keys from in-memory index tree' (duration: 601.583959ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:39.908338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:39.170949Z","time spent":"737.386873ms","remote":"127.0.0.1:47742","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-05T05:26:39.908397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.224125263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:39.908421Z","caller":"traceutil/trace.go:171","msg":"trace[2070505922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"1.224175174s","start":"2026-02-05T05:26:38.684238Z","end":"2026-02-05T05:26:39.908414Z","steps":["trace[2070505922] 'agreement among raft nodes before linearized reading' (duration: 622.475982ms)","trace[2070505922] 'range keys from in-memory index tree' (duration: 601.667192ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:39.90844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:38.684219Z","time spent":"1.224216685s","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:26:39.908557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215898317s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:26:39.908577Z","caller":"traceutil/trace.go:171","msg":"trace[1978918270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:656; }","duration":"1.215938268s","start":"2026-02-05T05:26:38.692631Z","end":"2026-02-05T05:26:39.90857Z","steps":["trace[1978918270] 'agreement among raft nodes before linearized reading' (duration: 614.071492ms)","trace[1978918270] 'range keys from in-memory index tree' (duration: 601.814885ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:39.908601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:38.692618Z","time spent":"1.215977278s","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T05:26:39.908747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.969292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:26:39.908959Z","caller":"traceutil/trace.go:171","msg":"trace[801450167] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"587.712844ms","start":"2026-02-05T05:26:39.321237Z","end":"2026-02-05T05:26:39.90895Z","steps":["trace[801450167] 'process raft request' (duration: 218.486866ms)","trace[801450167] 'compare' (duration: 367.96559ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:39.909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:39.321217Z","time spent":"587.762796ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:39.909097Z","caller":"traceutil/trace.go:171","msg":"trace[1175249001] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"586.913116ms","start":"2026-02-05T05:26:39.322177Z","end":"2026-02-05T05:26:39.90909Z","steps":["trace[1175249001] 'read index received' (duration: 217.690969ms)","trace[1175249001] 'applied index is now lower than readState.Index' (duration: 369.221267ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:39.90914Z","caller":"traceutil/trace.go:171","msg":"trace[1080232827] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"586.222561ms","start":"2026-02-05T05:26:39.322912Z","end":"2026-02-05T05:26:39.909134Z","steps":["trace[1080232827] 'process raft request' (duration: 585.891764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:39.90917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:39.322855Z","time spent":"586.296653ms","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1291,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:26:39.909401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.238404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-8wxvr\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-02-05T05:26:39.909497Z","caller":"traceutil/trace.go:171","msg":"trace[986412488] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-8wxvr; range_end:; response_count:1; response_revision:658; }","duration":"587.355747ms","start":"2026-02-05T05:26:39.322134Z","end":"2026-02-05T05:26:39.90949Z","steps":["trace[986412488] 'agreement among raft nodes before linearized reading' (duration: 586.978608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:39.909519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:39.322128Z","time spent":"587.384108ms","remote":"127.0.0.1:47696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4721,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-8wxvr\" "} {"level":"info","ts":"2026-02-05T05:26:40.183568Z","caller":"traceutil/trace.go:171","msg":"trace[1943064246] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:680; }","duration":"251.624366ms","start":"2026-02-05T05:26:39.931886Z","end":"2026-02-05T05:26:40.18351Z","steps":["trace[1943064246] 'read index received' (duration: 3.586721ms)","trace[1943064246] 'applied index is now lower than readState.Index' (duration: 248.036805ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:40.183712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.80216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:40.18374Z","caller":"traceutil/trace.go:171","msg":"trace[1730994312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"251.873922ms","start":"2026-02-05T05:26:39.931857Z","end":"2026-02-05T05:26:40.183731Z","steps":["trace[1730994312] 'agreement among raft nodes before linearized reading' (duration: 251.78079ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:40.184008Z","caller":"traceutil/trace.go:171","msg":"trace[62150778] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"256.636481ms","start":"2026-02-05T05:26:39.927362Z","end":"2026-02-05T05:26:40.183998Z","steps":["trace[62150778] 'process raft request' (duration: 236.689148ms)","trace[62150778] 'compare' (duration: 19.249746ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:40.184106Z","caller":"traceutil/trace.go:171","msg":"trace[274776907] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"256.710062ms","start":"2026-02-05T05:26:39.927389Z","end":"2026-02-05T05:26:40.184099Z","steps":["trace[274776907] 'process raft request' (duration: 256.015776ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:40.184182Z","caller":"traceutil/trace.go:171","msg":"trace[1116062151] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"249.927309ms","start":"2026-02-05T05:26:39.934249Z","end":"2026-02-05T05:26:40.184176Z","steps":["trace[1116062151] 'process raft request' (duration: 249.187131ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:40.184338Z","caller":"traceutil/trace.go:171","msg":"trace[1458588187] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"250.01103ms","start":"2026-02-05T05:26:39.934319Z","end":"2026-02-05T05:26:40.18433Z","steps":["trace[1458588187] 'process raft request' (duration: 249.14243ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:40.184859Z","caller":"traceutil/trace.go:171","msg":"trace[1180077442] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"240.149917ms","start":"2026-02-05T05:26:39.944702Z","end":"2026-02-05T05:26:40.184852Z","steps":["trace[1180077442] 'process raft request' (duration: 240.114566ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:40.493714Z","caller":"traceutil/trace.go:171","msg":"trace[215651565] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"298.522269ms","start":"2026-02-05T05:26:40.195165Z","end":"2026-02-05T05:26:40.493687Z","steps":["trace[215651565] 'process raft request' (duration: 240.160377ms)","trace[215651565] 'compare' (duration: 57.798619ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:40.494116Z","caller":"traceutil/trace.go:171","msg":"trace[727500453] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"298.309533ms","start":"2026-02-05T05:26:40.195317Z","end":"2026-02-05T05:26:40.493627Z","steps":["trace[727500453] 'read index received' (duration: 240.021883ms)","trace[727500453] 'applied index is now lower than readState.Index' (duration: 58.28606ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:40.494324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.981268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-4dp6f\" ","response":"range_response_count:1 size:4733"} {"level":"info","ts":"2026-02-05T05:26:40.494363Z","caller":"traceutil/trace.go:171","msg":"trace[1888535135] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-4dp6f; range_end:; response_count:1; response_revision:665; }","duration":"299.052719ms","start":"2026-02-05T05:26:40.195296Z","end":"2026-02-05T05:26:40.494349Z","steps":["trace[1888535135] 'agreement among raft nodes before linearized reading' (duration: 298.871985ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:40.54717Z","caller":"traceutil/trace.go:171","msg":"trace[1560586657] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"351.775794ms","start":"2026-02-05T05:26:40.195373Z","end":"2026-02-05T05:26:40.547149Z","steps":["trace[1560586657] 'process raft request' (duration: 351.504678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:40.547362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:40.195368Z","time spent":"351.895636ms","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:40.55258Z","caller":"traceutil/trace.go:171","msg":"trace[590095349] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"349.262487ms","start":"2026-02-05T05:26:40.197814Z","end":"2026-02-05T05:26:40.547076Z","steps":["trace[590095349] 'process raft request' (duration: 349.200606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:40.552658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:40.197805Z","time spent":"354.812413ms","remote":"127.0.0.1:47966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:40.728866Z","caller":"traceutil/trace.go:171","msg":"trace[815609097] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"141.865912ms","start":"2026-02-05T05:26:40.586979Z","end":"2026-02-05T05:26:40.728845Z","steps":["trace[815609097] 'process raft request' (duration: 141.417142ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:40.729078Z","caller":"traceutil/trace.go:171","msg":"trace[2093174869] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"137.255817ms","start":"2026-02-05T05:26:40.591816Z","end":"2026-02-05T05:26:40.729072Z","steps":["trace[2093174869] 'process raft request' (duration: 136.703325ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:40.729209Z","caller":"traceutil/trace.go:171","msg":"trace[1634491314] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"132.163912ms","start":"2026-02-05T05:26:40.597038Z","end":"2026-02-05T05:26:40.729202Z","steps":["trace[1634491314] 'process raft request' (duration: 131.508598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:41.27167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.804651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:26:41.271774Z","caller":"traceutil/trace.go:171","msg":"trace[351339974] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"528.338101ms","start":"2026-02-05T05:26:40.743419Z","end":"2026-02-05T05:26:41.271757Z","steps":["trace[351339974] 'read index received' (duration: 234.442548ms)","trace[351339974] 'applied index is now lower than readState.Index' (duration: 293.894003ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:41.271992Z","caller":"traceutil/trace.go:171","msg":"trace[1021957702] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"529.600939ms","start":"2026-02-05T05:26:40.742378Z","end":"2026-02-05T05:26:41.271979Z","steps":["trace[1021957702] 'process raft request' (duration: 235.396319ms)","trace[1021957702] 'compare' (duration: 293.233499ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:41.272053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:40.742358Z","time spent":"529.662821ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1017,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:26:41.272321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.897134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-xrn8d\" ","response":"range_response_count:1 size:4845"} {"level":"info","ts":"2026-02-05T05:26:41.27235Z","caller":"traceutil/trace.go:171","msg":"trace[1476884423] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-xrn8d; range_end:; response_count:1; response_revision:674; }","duration":"528.944294ms","start":"2026-02-05T05:26:40.743396Z","end":"2026-02-05T05:26:41.27234Z","steps":["trace[1476884423] 'agreement among raft nodes before linearized reading' (duration: 528.817202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:41.273752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:40.743388Z","time spent":"530.310346ms","remote":"127.0.0.1:47696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4869,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-xrn8d\" "} {"level":"info","ts":"2026-02-05T05:26:41.429825Z","caller":"traceutil/trace.go:171","msg":"trace[1339543807] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:696; }","duration":"157.982036ms","start":"2026-02-05T05:26:41.271818Z","end":"2026-02-05T05:26:41.4298Z","steps":["trace[1339543807] 'read index received' (duration: 106.241645ms)","trace[1339543807] 'applied index is now lower than readState.Index' (duration: 51.739381ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:41.429867Z","caller":"traceutil/trace.go:171","msg":"trace[1736592128] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"683.527774ms","start":"2026-02-05T05:26:40.746284Z","end":"2026-02-05T05:26:41.429811Z","steps":["trace[1736592128] 'process raft request' (duration: 631.764363ms)","trace[1736592128] 'compare' (duration: 51.571817ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:41.43002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.330558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3881"} {"level":"warn","ts":"2026-02-05T05:26:41.430034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:40.746269Z","time spent":"683.674707ms","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1612,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:41.430043Z","caller":"traceutil/trace.go:171","msg":"trace[968775738] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:677; }","duration":"679.41037ms","start":"2026-02-05T05:26:40.750626Z","end":"2026-02-05T05:26:41.430036Z","steps":["trace[968775738] 'agreement among raft nodes before linearized reading' (duration: 679.263767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:41.430074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:40.750616Z","time spent":"679.450071ms","remote":"127.0.0.1:47966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3905,"request content":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" "} {"level":"info","ts":"2026-02-05T05:26:41.430469Z","caller":"traceutil/trace.go:171","msg":"trace[654084761] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"577.605506ms","start":"2026-02-05T05:26:40.852857Z","end":"2026-02-05T05:26:41.430462Z","steps":["trace[654084761] 'process raft request' (duration: 576.9074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:41.430495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.072249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-05T05:26:41.430506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:40.852829Z","time spent":"577.655107ms","remote":"127.0.0.1:47694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:41.430521Z","caller":"traceutil/trace.go:171","msg":"trace[1763178265] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:677; }","duration":"439.147571ms","start":"2026-02-05T05:26:40.991367Z","end":"2026-02-05T05:26:41.430515Z","steps":["trace[1763178265] 'agreement among raft nodes before linearized reading' (duration: 439.10408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:41.430538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:40.991343Z","time spent":"439.191492ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-02-05T05:26:41.4306Z","caller":"traceutil/trace.go:171","msg":"trace[2064378776] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"680.471005ms","start":"2026-02-05T05:26:40.750123Z","end":"2026-02-05T05:26:41.430594Z","steps":["trace[2064378776] 'process raft request' (duration: 679.597454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:41.430627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:40.75011Z","time spent":"680.502336ms","remote":"127.0.0.1:47966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:26:46.980116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.730593ms","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-05T05:26:46.980233Z","caller":"traceutil/trace.go:171","msg":"trace[1597078483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:703; }","duration":"768.906647ms","start":"2026-02-05T05:26:46.2113Z","end":"2026-02-05T05:26:46.980207Z","steps":["trace[1597078483] 'range keys from in-memory index tree' (duration: 768.473386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:46.98029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:46.211276Z","time spent":"769.001719ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T05:26:46.980329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.440136ms","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-05T05:26:46.980418Z","caller":"traceutil/trace.go:171","msg":"trace[1898715649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:703; }","duration":"764.568758ms","start":"2026-02-05T05:26:46.215828Z","end":"2026-02-05T05:26:46.980397Z","steps":["trace[1898715649] 'range keys from in-memory index tree' (duration: 764.17892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:46.980466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:46.215815Z","time spent":"764.64274ms","remote":"127.0.0.1:47742","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-05T05:26:46.980586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.763745ms","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-05T05:26:46.980613Z","caller":"traceutil/trace.go:171","msg":"trace[1779085276] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:703; }","duration":"757.803866ms","start":"2026-02-05T05:26:46.222805Z","end":"2026-02-05T05:26:46.980609Z","steps":["trace[1779085276] 'range keys from in-memory index tree' (duration: 757.696634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:46.980626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:46.222793Z","time spent":"757.829706ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T05:26:46.98077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.464899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:46.980785Z","caller":"traceutil/trace.go:171","msg":"trace[1907877790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:703; }","duration":"299.510311ms","start":"2026-02-05T05:26:46.681269Z","end":"2026-02-05T05:26:46.98078Z","steps":["trace[1907877790] 'range keys from in-memory index tree' (duration: 299.422418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:46.98084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.369842ms","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-05T05:26:46.980877Z","caller":"traceutil/trace.go:171","msg":"trace[862205094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:703; }","duration":"744.423832ms","start":"2026-02-05T05:26:46.236442Z","end":"2026-02-05T05:26:46.980866Z","steps":["trace[862205094] 'range keys from in-memory index tree' (duration: 744.27983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:46.980906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:46.236428Z","time spent":"744.471014ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T05:26:47.929909Z","caller":"traceutil/trace.go:171","msg":"trace[817634880] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"938.395445ms","start":"2026-02-05T05:26:46.991484Z","end":"2026-02-05T05:26:47.92988Z","steps":["trace[817634880] 'process raft request' (duration: 938.21683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:47.930083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:46.991465Z","time spent":"938.534898ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:48.055827Z","caller":"traceutil/trace.go:171","msg":"trace[189549856] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"1.063057917s","start":"2026-02-05T05:26:46.992735Z","end":"2026-02-05T05:26:48.055792Z","steps":["trace[189549856] 'process raft request' (duration: 1.059427965s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:48.055994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:46.992691Z","time spent":"1.063223401s","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:48.056094Z","caller":"traceutil/trace.go:171","msg":"trace[24290613] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"1.063248392s","start":"2026-02-05T05:26:46.992823Z","end":"2026-02-05T05:26:48.056072Z","steps":["trace[24290613] 'process raft request' (duration: 1.062931804s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:48.056239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:46.992819Z","time spent":"1.063338994s","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:48.056448Z","caller":"traceutil/trace.go:171","msg":"trace[1772265094] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"1.062781561s","start":"2026-02-05T05:26:46.993658Z","end":"2026-02-05T05:26:48.05644Z","steps":["trace[1772265094] 'process raft request' (duration: 1.062339271s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:48.056496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:46.993649Z","time spent":"1.062822253s","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:48.056581Z","caller":"traceutil/trace.go:171","msg":"trace[1587940602] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:727; }","duration":"554.963114ms","start":"2026-02-05T05:26:47.501608Z","end":"2026-02-05T05:26:48.056571Z","steps":["trace[1587940602] 'read index received' (duration: 428.87944ms)","trace[1587940602] 'applied index is now lower than readState.Index' (duration: 126.082964ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:48.05667Z","caller":"traceutil/trace.go:171","msg":"trace[2048281446] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"592.104055ms","start":"2026-02-05T05:26:47.464558Z","end":"2026-02-05T05:26:48.056662Z","steps":["trace[2048281446] 'process raft request' (duration: 591.842949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:48.056729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:47.464533Z","time spent":"592.167837ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:26:48.056729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.276673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:48.056759Z","caller":"traceutil/trace.go:171","msg":"trace[1579906568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:708; }","duration":"374.344095ms","start":"2026-02-05T05:26:47.682407Z","end":"2026-02-05T05:26:48.056751Z","steps":["trace[1579906568] 'agreement among raft nodes before linearized reading' (duration: 374.296124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:48.056781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:47.682385Z","time spent":"374.391876ms","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:26:48.056946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.338212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T05:26:48.056964Z","caller":"traceutil/trace.go:171","msg":"trace[1948559653] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:708; }","duration":"555.377533ms","start":"2026-02-05T05:26:47.501582Z","end":"2026-02-05T05:26:48.056959Z","steps":["trace[1948559653] 'agreement among raft nodes before linearized reading' (duration: 555.291081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:48.056983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:47.501571Z","time spent":"555.408394ms","remote":"127.0.0.1:47556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.212\" "} {"level":"info","ts":"2026-02-05T05:26:50.158703Z","caller":"traceutil/trace.go:171","msg":"trace[1927389849] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:737; }","duration":"225.042245ms","start":"2026-02-05T05:26:49.93363Z","end":"2026-02-05T05:26:50.158672Z","steps":["trace[1927389849] 'read index received' (duration: 224.709398ms)","trace[1927389849] 'applied index is now lower than readState.Index' (duration: 331.707µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:50.159109Z","caller":"traceutil/trace.go:171","msg":"trace[1147250039] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"246.550242ms","start":"2026-02-05T05:26:49.91255Z","end":"2026-02-05T05:26:50.1591Z","steps":["trace[1147250039] 'process raft request' (duration: 245.878667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:50.159488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.807342ms","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-05T05:26:50.159521Z","caller":"traceutil/trace.go:171","msg":"trace[1150128940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"225.895304ms","start":"2026-02-05T05:26:49.933617Z","end":"2026-02-05T05:26:50.159512Z","steps":["trace[1150128940] 'agreement among raft nodes before linearized reading' (duration: 225.749441ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:02.63199Z","caller":"traceutil/trace.go:171","msg":"trace[553815651] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"209.663767ms","start":"2026-02-05T05:27:02.422307Z","end":"2026-02-05T05:27:02.631971Z","steps":["trace[553815651] 'read index received' (duration: 187.439694ms)","trace[553815651] 'applied index is now lower than readState.Index' (duration: 22.223213ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:02.632377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.037735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-05T05:27:02.632445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.559967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T05:27:02.632471Z","caller":"traceutil/trace.go:171","msg":"trace[599939281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:750; }","duration":"191.635139ms","start":"2026-02-05T05:27:02.440828Z","end":"2026-02-05T05:27:02.632463Z","steps":["trace[599939281] 'agreement among raft nodes before linearized reading' (duration: 191.493606ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:02.632466Z","caller":"traceutil/trace.go:171","msg":"trace[1275473294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"210.161248ms","start":"2026-02-05T05:27:02.422282Z","end":"2026-02-05T05:27:02.632443Z","steps":["trace[1275473294] 'agreement among raft nodes before linearized reading' (duration: 209.888762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:02.632631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.724215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T05:27:02.632647Z","caller":"traceutil/trace.go:171","msg":"trace[1944931909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:750; }","duration":"170.785506ms","start":"2026-02-05T05:27:02.461857Z","end":"2026-02-05T05:27:02.632643Z","steps":["trace[1944931909] 'agreement among raft nodes before linearized reading' (duration: 170.739105ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:02.632706Z","caller":"traceutil/trace.go:171","msg":"trace[1784714099] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"252.275161ms","start":"2026-02-05T05:27:02.380422Z","end":"2026-02-05T05:27:02.632698Z","steps":["trace[1784714099] 'process raft request' (duration: 229.290421ms)","trace[1784714099] 'compare' (duration: 22.171692ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:05.267676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.31419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T05:27:05.267774Z","caller":"traceutil/trace.go:171","msg":"trace[316074553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"577.444164ms","start":"2026-02-05T05:27:04.690301Z","end":"2026-02-05T05:27:05.267745Z","steps":["trace[316074553] 'range keys from in-memory index tree' (duration: 577.116567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:05.267829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:04.690292Z","time spent":"577.529026ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:27:05.269165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.492612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:27:05.26921Z","caller":"traceutil/trace.go:171","msg":"trace[494099270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"629.579504ms","start":"2026-02-05T05:27:04.639622Z","end":"2026-02-05T05:27:05.269202Z","steps":["trace[494099270] 'range keys from in-memory index tree' (duration: 629.347378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:05.269231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:04.639597Z","time spent":"629.629104ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T05:27:05.269459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.250974ms","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-05T05:27:05.269486Z","caller":"traceutil/trace.go:171","msg":"trace[2129529703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"579.311406ms","start":"2026-02-05T05:27:04.690166Z","end":"2026-02-05T05:27:05.269477Z","steps":["trace[2129529703] 'range keys from in-memory index tree' (duration: 579.135012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:05.269536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:04.690144Z","time spent":"579.384557ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T05:27:05.271847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.566936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T05:27:05.271897Z","caller":"traceutil/trace.go:171","msg":"trace[811466823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:755; }","duration":"581.664219ms","start":"2026-02-05T05:27:04.690225Z","end":"2026-02-05T05:27:05.271889Z","steps":["trace[811466823] 'range keys from in-memory index tree' (duration: 581.391401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:05.27192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:04.690199Z","time spent":"581.71516ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T05:27:05.272154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.048885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:05.272249Z","caller":"traceutil/trace.go:171","msg":"trace[2113214323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"570.097376ms","start":"2026-02-05T05:27:04.702075Z","end":"2026-02-05T05:27:05.272172Z","steps":["trace[2113214323] 'range keys from in-memory index tree' (duration: 569.978443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:05.272274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:04.702063Z","time spent":"570.203919ms","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T05:27:06.040795Z","caller":"traceutil/trace.go:171","msg":"trace[1801997840] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"756.6382ms","start":"2026-02-05T05:27:05.284136Z","end":"2026-02-05T05:27:06.040774Z","steps":["trace[1801997840] 'process raft request' (duration: 756.61447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:06.040957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:05.284124Z","time spent":"756.755873ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:06.041215Z","caller":"traceutil/trace.go:171","msg":"trace[1213796999] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"758.937532ms","start":"2026-02-05T05:27:05.282272Z","end":"2026-02-05T05:27:06.041209Z","steps":["trace[1213796999] 'process raft request' (duration: 758.336547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:06.041251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:05.28226Z","time spent":"758.975752ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:06.041314Z","caller":"traceutil/trace.go:171","msg":"trace[1094584848] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"758.942402ms","start":"2026-02-05T05:27:05.282366Z","end":"2026-02-05T05:27:06.041308Z","steps":["trace[1094584848] 'process raft request' (duration: 758.358559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:06.041339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:05.282359Z","time spent":"758.964352ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:06.76029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.229846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:06.760387Z","caller":"traceutil/trace.go:171","msg":"trace[2107091617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"713.377009ms","start":"2026-02-05T05:27:06.046988Z","end":"2026-02-05T05:27:06.760365Z","steps":["trace[2107091617] 'range keys from in-memory index tree' (duration: 712.037269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:06.760431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:06.046973Z","time spent":"713.449251ms","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:27:06.76082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.939649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:06.760863Z","caller":"traceutil/trace.go:171","msg":"trace[1713009426] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:759; }","duration":"625.042462ms","start":"2026-02-05T05:27:06.135803Z","end":"2026-02-05T05:27:06.760846Z","steps":["trace[1713009426] 'count revisions from in-memory index tree' (duration: 624.755114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:06.760896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:06.13575Z","time spent":"625.135704ms","remote":"127.0.0.1:47988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:27:06.76158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.445028ms","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-05T05:27:06.761656Z","caller":"traceutil/trace.go:171","msg":"trace[181104546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:759; }","duration":"325.555791ms","start":"2026-02-05T05:27:06.436086Z","end":"2026-02-05T05:27:06.761642Z","steps":["trace[181104546] 'range keys from in-memory index tree' (duration: 325.277814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:06.761694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:06.436061Z","time spent":"325.623242ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:27:06.761886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.531083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T05:27:06.761948Z","caller":"traceutil/trace.go:171","msg":"trace[434813174] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:759; }","duration":"311.621435ms","start":"2026-02-05T05:27:06.450312Z","end":"2026-02-05T05:27:06.761933Z","steps":["trace[434813174] 'count revisions from in-memory index tree' (duration: 311.483372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:06.761988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:06.450294Z","time spent":"311.683596ms","remote":"127.0.0.1:47914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-05T05:27:07.144061Z","caller":"traceutil/trace.go:171","msg":"trace[521463320] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"366.449085ms","start":"2026-02-05T05:27:06.777585Z","end":"2026-02-05T05:27:07.144035Z","steps":["trace[521463320] 'process raft request' (duration: 366.296081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:07.144209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:06.777549Z","time spent":"366.583818ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:07.928176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.148783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:27:07.928246Z","caller":"traceutil/trace.go:171","msg":"trace[766919840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:760; }","duration":"568.276875ms","start":"2026-02-05T05:27:07.359954Z","end":"2026-02-05T05:27:07.928231Z","steps":["trace[766919840] 'range keys from in-memory index tree' (duration: 567.99371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:07.928275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:07.359927Z","time spent":"568.341617ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T05:27:07.92845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.42478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T05:27:07.928469Z","caller":"traceutil/trace.go:171","msg":"trace[1259802098] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:760; }","duration":"425.513944ms","start":"2026-02-05T05:27:07.502948Z","end":"2026-02-05T05:27:07.928462Z","steps":["trace[1259802098] 'range keys from in-memory index tree' (duration: 425.312767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:07.928486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:07.502919Z","time spent":"425.563595ms","remote":"127.0.0.1:47556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.212\" "} {"level":"warn","ts":"2026-02-05T05:27:07.9286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.710773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T05:27:07.92862Z","caller":"traceutil/trace.go:171","msg":"trace[941177772] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:760; }","duration":"287.772714ms","start":"2026-02-05T05:27:07.64084Z","end":"2026-02-05T05:27:07.928613Z","steps":["trace[941177772] 'count revisions from in-memory index tree' (duration: 287.61475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:07.928705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.944607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:07.928721Z","caller":"traceutil/trace.go:171","msg":"trace[1305045480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"241.997428ms","start":"2026-02-05T05:27:07.686718Z","end":"2026-02-05T05:27:07.928716Z","steps":["trace[1305045480] 'range keys from in-memory index tree' (duration: 241.876856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:08.43502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.840018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:27:08.435456Z","caller":"traceutil/trace.go:171","msg":"trace[57754895] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"476.539369ms","start":"2026-02-05T05:27:07.958892Z","end":"2026-02-05T05:27:08.435431Z","steps":["trace[57754895] 'process raft request' (duration: 358.2026ms)","trace[57754895] 'compare' (duration: 117.734266ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:08.435636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:07.958805Z","time spent":"476.694573ms","remote":"127.0.0.1:47556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:08.436621Z","caller":"traceutil/trace.go:171","msg":"trace[157890983] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"387.086043ms","start":"2026-02-05T05:27:08.049518Z","end":"2026-02-05T05:27:08.436604Z","steps":["trace[157890983] 'read index received' (duration: 267.72641ms)","trace[157890983] 'applied index is now lower than readState.Index' (duration: 119.357033ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:08.436947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.430042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T05:27:08.43698Z","caller":"traceutil/trace.go:171","msg":"trace[64378068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"387.504572ms","start":"2026-02-05T05:27:08.049465Z","end":"2026-02-05T05:27:08.43697Z","steps":["trace[64378068] 'agreement among raft nodes before linearized reading' (duration: 387.293718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:08.437012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:08.049376Z","time spent":"387.629065ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:27:08.440195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.062673ms","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-05T05:27:08.44024Z","caller":"traceutil/trace.go:171","msg":"trace[1530415325] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:763; }","duration":"387.119064ms","start":"2026-02-05T05:27:08.053109Z","end":"2026-02-05T05:27:08.440228Z","steps":["trace[1530415325] 'agreement among raft nodes before linearized reading' (duration: 387.005541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:08.440269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:08.053101Z","time spent":"387.161384ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T05:27:08.440492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.261246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T05:27:08.44051Z","caller":"traceutil/trace.go:171","msg":"trace[919597159] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"383.290698ms","start":"2026-02-05T05:27:08.057214Z","end":"2026-02-05T05:27:08.440504Z","steps":["trace[919597159] 'agreement among raft nodes before linearized reading' (duration: 383.239157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:08.440529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:08.057207Z","time spent":"383.317298ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-05T05:27:19.439347Z","caller":"traceutil/trace.go:171","msg":"trace[1663589569] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"138.288171ms","start":"2026-02-05T05:27:19.301035Z","end":"2026-02-05T05:27:19.439323Z","steps":["trace[1663589569] 'process raft request' (duration: 138.141177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:20.462268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.355152ms","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-05T05:27:20.462359Z","caller":"traceutil/trace.go:171","msg":"trace[1196364926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:847; }","duration":"380.486215ms","start":"2026-02-05T05:27:20.081852Z","end":"2026-02-05T05:27:20.462338Z","steps":["trace[1196364926] 'range keys from in-memory index tree' (duration: 380.198138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:20.462392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:20.081829Z","time spent":"380.555936ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-05T05:27:21.939508Z","caller":"traceutil/trace.go:171","msg":"trace[315289319] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"493.508423ms","start":"2026-02-05T05:27:21.445969Z","end":"2026-02-05T05:27:21.939477Z","steps":["trace[315289319] 'process raft request' (duration: 493.342379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:21.939673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:21.445956Z","time spent":"493.628935ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:23.165603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548133863466100,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T05:27:23.227196Z","caller":"traceutil/trace.go:171","msg":"trace[667147763] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"637.312189ms","start":"2026-02-05T05:27:22.589852Z","end":"2026-02-05T05:27:23.227164Z","steps":["trace[667147763] 'process raft request' (duration: 636.337736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:23.227355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:22.589823Z","time spent":"637.449211ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:23.229647Z","caller":"traceutil/trace.go:171","msg":"trace[101453136] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"637.435291ms","start":"2026-02-05T05:27:22.592189Z","end":"2026-02-05T05:27:23.229624Z","steps":["trace[101453136] 'process raft request' (duration: 636.765526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:23.229808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:22.592165Z","time spent":"637.551253ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:23.485401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.291743ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-05T05:27:23.485738Z","caller":"traceutil/trace.go:171","msg":"trace[469518870] linearizableReadLoop","detail":"{readStateIndex:886; appliedIndex:884; }","duration":"820.157028ms","start":"2026-02-05T05:27:22.665565Z","end":"2026-02-05T05:27:23.485723Z","steps":["trace[469518870] 'read index received' (duration: 563.300673ms)","trace[469518870] 'applied index is now lower than readState.Index' (duration: 256.855635ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:23.486035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.463275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T05:27:23.486052Z","caller":"traceutil/trace.go:171","msg":"trace[138898386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:854; }","duration":"820.518917ms","start":"2026-02-05T05:27:22.665527Z","end":"2026-02-05T05:27:23.486046Z","steps":["trace[138898386] 'agreement among raft nodes before linearized reading' (duration: 820.427375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:23.486076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:22.6655Z","time spent":"820.568867ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T05:27:23.519697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.788836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:23.519767Z","caller":"traceutil/trace.go:171","msg":"trace[2021188494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:855; }","duration":"825.896278ms","start":"2026-02-05T05:27:22.693852Z","end":"2026-02-05T05:27:23.519748Z","steps":["trace[2021188494] 'agreement among raft nodes before linearized reading' (duration: 792.578093ms)","trace[2021188494] 'range keys from in-memory index tree' (duration: 33.212152ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:23.519801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:22.693814Z","time spent":"825.9785ms","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:27:23.519996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.684109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T05:27:23.520013Z","caller":"traceutil/trace.go:171","msg":"trace[370773121] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:855; }","duration":"422.733801ms","start":"2026-02-05T05:27:23.097271Z","end":"2026-02-05T05:27:23.520005Z","steps":["trace[370773121] 'agreement among raft nodes before linearized reading' (duration: 389.291363ms)","trace[370773121] 'count revisions from in-memory index tree' (duration: 30.305356ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:23.520027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:23.097243Z","time spent":"422.780672ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:27:23.520134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.345959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T05:27:23.520148Z","caller":"traceutil/trace.go:171","msg":"trace[520470111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:855; }","duration":"815.35821ms","start":"2026-02-05T05:27:22.704783Z","end":"2026-02-05T05:27:23.520141Z","steps":["trace[520470111] 'agreement among raft nodes before linearized reading' (duration: 784.90927ms)","trace[520470111] 'range keys from in-memory index tree' (duration: 30.389279ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:23.520161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:22.704714Z","time spent":"815.444371ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T05:27:23.736588Z","caller":"traceutil/trace.go:171","msg":"trace[433356882] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"197.845119ms","start":"2026-02-05T05:27:23.538717Z","end":"2026-02-05T05:27:23.736562Z","steps":["trace[433356882] 'process raft request' (duration: 197.714756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:28.25011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.843341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:27:28.250189Z","caller":"traceutil/trace.go:171","msg":"trace[1102539377] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:898; }","duration":"653.629878ms","start":"2026-02-05T05:27:27.596547Z","end":"2026-02-05T05:27:28.250177Z","steps":["trace[1102539377] 'read index received' (duration: 212.692115ms)","trace[1102539377] 'applied index is now lower than readState.Index' (duration: 440.936983ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:28.25024Z","caller":"traceutil/trace.go:171","msg":"trace[1430030751] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"699.377434ms","start":"2026-02-05T05:27:27.550857Z","end":"2026-02-05T05:27:28.250234Z","steps":["trace[1430030751] 'process raft request' (duration: 258.350119ms)","trace[1430030751] 'compare' (duration: 440.684936ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:28.250291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:27.550829Z","time spent":"699.446225ms","remote":"127.0.0.1:47556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:28.250482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.939755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T05:27:28.250503Z","caller":"traceutil/trace.go:171","msg":"trace[426622272] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:866; }","duration":"653.986386ms","start":"2026-02-05T05:27:27.59651Z","end":"2026-02-05T05:27:28.250496Z","steps":["trace[426622272] 'agreement among raft nodes before linearized reading' (duration: 653.897264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:28.250519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:27.596491Z","time spent":"654.024417ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T05:27:28.250604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.010805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:28.250616Z","caller":"traceutil/trace.go:171","msg":"trace[1872943146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:866; }","duration":"570.050017ms","start":"2026-02-05T05:27:27.680562Z","end":"2026-02-05T05:27:28.250612Z","steps":["trace[1872943146] 'agreement among raft nodes before linearized reading' (duration: 570.029266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:28.25063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:27.680541Z","time spent":"570.087077ms","remote":"127.0.0.1:47518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:27:28.250767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.93443ms","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-05T05:27:28.25078Z","caller":"traceutil/trace.go:171","msg":"trace[1305851128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:866; }","duration":"272.978501ms","start":"2026-02-05T05:27:27.977797Z","end":"2026-02-05T05:27:28.250776Z","steps":["trace[1305851128] 'agreement among raft nodes before linearized reading' (duration: 272.93915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:28.250876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.532691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T05:27:28.250891Z","caller":"traceutil/trace.go:171","msg":"trace[1998122729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:866; }","duration":"458.575912ms","start":"2026-02-05T05:27:27.792312Z","end":"2026-02-05T05:27:28.250887Z","steps":["trace[1998122729] 'agreement among raft nodes before linearized reading' (duration: 458.533431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:28.250903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:27.792293Z","time spent":"458.607833ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T05:27:34.560072Z","caller":"traceutil/trace.go:171","msg":"trace[1904523166] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"229.188854ms","start":"2026-02-05T05:27:34.330858Z","end":"2026-02-05T05:27:34.560047Z","steps":["trace[1904523166] 'read index received' (duration: 228.97865ms)","trace[1904523166] 'applied index is now lower than readState.Index' (duration: 209.444µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:34.560332Z","caller":"traceutil/trace.go:171","msg":"trace[1530830875] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"278.939359ms","start":"2026-02-05T05:27:34.281384Z","end":"2026-02-05T05:27:34.560323Z","steps":["trace[1530830875] 'process raft request' (duration: 278.52156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:34.560541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.664995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T05:27:34.560569Z","caller":"traceutil/trace.go:171","msg":"trace[172877684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:884; }","duration":"229.734137ms","start":"2026-02-05T05:27:34.330828Z","end":"2026-02-05T05:27:34.560562Z","steps":["trace[172877684] 'agreement among raft nodes before linearized reading' (duration: 229.630274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:34.56069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.969608ms","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-05T05:27:34.560713Z","caller":"traceutil/trace.go:171","msg":"trace[1976410664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:884; }","duration":"224.006569ms","start":"2026-02-05T05:27:34.3367Z","end":"2026-02-05T05:27:34.560707Z","steps":["trace[1976410664] 'agreement among raft nodes before linearized reading' (duration: 223.952088ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:35.591393Z","caller":"traceutil/trace.go:171","msg":"trace[1192967036] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"295.422106ms","start":"2026-02-05T05:27:35.295949Z","end":"2026-02-05T05:27:35.591371Z","steps":["trace[1192967036] 'read index received' (duration: 295.245032ms)","trace[1192967036] 'applied index is now lower than readState.Index' (duration: 176.424µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:35.591556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.585029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:27:35.591574Z","caller":"traceutil/trace.go:171","msg":"trace[529705622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:887; }","duration":"295.63365ms","start":"2026-02-05T05:27:35.295934Z","end":"2026-02-05T05:27:35.591568Z","steps":["trace[529705622] 'agreement among raft nodes before linearized reading' (duration: 295.514348ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:35.591836Z","caller":"traceutil/trace.go:171","msg":"trace[1632948403] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"300.816616ms","start":"2026-02-05T05:27:35.291014Z","end":"2026-02-05T05:27:35.59183Z","steps":["trace[1632948403] 'process raft request' (duration: 300.249314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:35.591893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:35.291Z","time spent":"300.850556ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:37.003883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.393965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:27:37.003987Z","caller":"traceutil/trace.go:171","msg":"trace[1076912197] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"352.145336ms","start":"2026-02-05T05:27:36.651826Z","end":"2026-02-05T05:27:37.003972Z","steps":["trace[1076912197] 'read index received' (duration: 191.514596ms)","trace[1076912197] 'applied index is now lower than readState.Index' (duration: 160.62918ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:37.00418Z","caller":"traceutil/trace.go:171","msg":"trace[1754552069] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"360.219446ms","start":"2026-02-05T05:27:36.643951Z","end":"2026-02-05T05:27:37.00417Z","steps":["trace[1754552069] 'process raft request' (duration: 199.445642ms)","trace[1754552069] 'compare' (duration: 160.307883ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:37.004229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:36.643941Z","time spent":"360.263787ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:37.004434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.612667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T05:27:37.004452Z","caller":"traceutil/trace.go:171","msg":"trace[1916281801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:890; }","duration":"352.642929ms","start":"2026-02-05T05:27:36.651803Z","end":"2026-02-05T05:27:37.004446Z","steps":["trace[1916281801] 'agreement among raft nodes before linearized reading' (duration: 352.550777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:37.00447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:36.651797Z","time spent":"352.668709ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:27:37.004614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.388858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:37.004633Z","caller":"traceutil/trace.go:171","msg":"trace[1410471537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"323.4519ms","start":"2026-02-05T05:27:36.681175Z","end":"2026-02-05T05:27:37.004627Z","steps":["trace[1410471537] 'agreement among raft nodes before linearized reading' (duration: 323.423119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:37.004649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:36.68115Z","time spent":"323.495051ms","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:27:37.599296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.023468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:27:37.599382Z","caller":"traceutil/trace.go:171","msg":"trace[272534519] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"325.695909ms","start":"2026-02-05T05:27:37.273669Z","end":"2026-02-05T05:27:37.599365Z","steps":["trace[272534519] 'read index received' (duration: 79.421µs)","trace[272534519] 'applied index is now lower than readState.Index' (duration: 325.615548ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:37.599584Z","caller":"traceutil/trace.go:171","msg":"trace[1803809596] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"586.70981ms","start":"2026-02-05T05:27:37.01285Z","end":"2026-02-05T05:27:37.59956Z","steps":["trace[1803809596] 'process raft request' (duration: 233.350316ms)","trace[1803809596] 'compare' (duration: 352.759201ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:37.599621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:37.012817Z","time spent":"586.787462ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:37.599756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.087608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:37.599773Z","caller":"traceutil/trace.go:171","msg":"trace[866053444] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:891; }","duration":"326.143209ms","start":"2026-02-05T05:27:37.273622Z","end":"2026-02-05T05:27:37.599765Z","steps":["trace[866053444] 'agreement among raft nodes before linearized reading' (duration: 326.108168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:37.599789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:37.273594Z","time spent":"326.19014ms","remote":"127.0.0.1:47732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:27:37.943955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.554698ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-05T05:27:37.944056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:37.602641Z","time spent":"341.409868ms","remote":"127.0.0.1:47556","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-05T05:27:38.336565Z","caller":"traceutil/trace.go:171","msg":"trace[2009550333] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:925; }","duration":"712.502667ms","start":"2026-02-05T05:27:37.624033Z","end":"2026-02-05T05:27:38.336536Z","steps":["trace[2009550333] 'read index received' (duration: 168.358682ms)","trace[2009550333] 'applied index is now lower than readState.Index' (duration: 544.143125ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:38.33688Z","caller":"traceutil/trace.go:171","msg":"trace[1085757199] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"733.189618ms","start":"2026-02-05T05:27:37.603681Z","end":"2026-02-05T05:27:38.33687Z","steps":["trace[1085757199] 'process raft request' (duration: 732.663916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:38.336983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:37.603664Z","time spent":"733.241218ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:38.337191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.152372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:27:38.337214Z","caller":"traceutil/trace.go:171","msg":"trace[1829145750] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:892; }","duration":"713.187092ms","start":"2026-02-05T05:27:37.62402Z","end":"2026-02-05T05:27:38.337207Z","steps":["trace[1829145750] 'agreement among raft nodes before linearized reading' (duration: 713.08925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:38.337235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:37.624001Z","time spent":"713.229024ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-05T05:27:38.374826Z","caller":"traceutil/trace.go:171","msg":"trace[1321718919] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"429.62706ms","start":"2026-02-05T05:27:37.945173Z","end":"2026-02-05T05:27:38.3748Z","steps":["trace[1321718919] 'process raft request' (duration: 429.478317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:38.375181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.855308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:38.375218Z","caller":"traceutil/trace.go:171","msg":"trace[1400394285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:893; }","duration":"691.9308ms","start":"2026-02-05T05:27:37.683278Z","end":"2026-02-05T05:27:38.375209Z","steps":["trace[1400394285] 'agreement among raft nodes before linearized reading' (duration: 691.836358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:38.375245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:37.68326Z","time spent":"691.979051ms","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:27:38.37503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:37.94515Z","time spent":"429.769354ms","remote":"127.0.0.1:47556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:38.551398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.09352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:27:38.551507Z","caller":"traceutil/trace.go:171","msg":"trace[44669781] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"174.99342ms","start":"2026-02-05T05:27:38.376497Z","end":"2026-02-05T05:27:38.55149Z","steps":["trace[44669781] 'read index received' (duration: 31.672095ms)","trace[44669781] 'applied index is now lower than readState.Index' (duration: 143.320465ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:38.551669Z","caller":"traceutil/trace.go:171","msg":"trace[633085984] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"209.127759ms","start":"2026-02-05T05:27:38.342529Z","end":"2026-02-05T05:27:38.551657Z","steps":["trace[633085984] 'process raft request' (duration: 65.691681ms)","trace[633085984] 'compare' (duration: 142.981798ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:38.551821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.320297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:38.55185Z","caller":"traceutil/trace.go:171","msg":"trace[2065833329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"175.722796ms","start":"2026-02-05T05:27:38.37611Z","end":"2026-02-05T05:27:38.551833Z","steps":["trace[2065833329] 'agreement among raft nodes before linearized reading' (duration: 175.685935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:38.551981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.794703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-05T05:27:38.552003Z","caller":"traceutil/trace.go:171","msg":"trace[1672457198] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:894; }","duration":"173.830183ms","start":"2026-02-05T05:27:38.378167Z","end":"2026-02-05T05:27:38.551997Z","steps":["trace[1672457198] 'agreement among raft nodes before linearized reading' (duration: 173.789583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:38.552254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.567835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T05:27:38.552279Z","caller":"traceutil/trace.go:171","msg":"trace[725538172] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:894; }","duration":"158.596065ms","start":"2026-02-05T05:27:38.393677Z","end":"2026-02-05T05:27:38.552273Z","steps":["trace[725538172] 'agreement among raft nodes before linearized reading' (duration: 158.552215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:38.814929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.200555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:27:38.815643Z","caller":"traceutil/trace.go:171","msg":"trace[156132930] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"196.345434ms","start":"2026-02-05T05:27:38.619275Z","end":"2026-02-05T05:27:38.81562Z","steps":["trace[156132930] 'process raft request' (duration: 33.352041ms)","trace[156132930] 'compare' (duration: 162.040672ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:38.815782Z","caller":"traceutil/trace.go:171","msg":"trace[1172217441] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"130.253095ms","start":"2026-02-05T05:27:38.685519Z","end":"2026-02-05T05:27:38.815772Z","steps":["trace[1172217441] 'read index received' (duration: 42.741µs)","trace[1172217441] 'applied index is now lower than readState.Index' (duration: 130.209484ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:38.815837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.313836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:38.81589Z","caller":"traceutil/trace.go:171","msg":"trace[2135307467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"130.377217ms","start":"2026-02-05T05:27:38.685475Z","end":"2026-02-05T05:27:38.815852Z","steps":["trace[2135307467] 'agreement among raft nodes before linearized reading' (duration: 130.330516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:41.265983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.891336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:27:41.266048Z","caller":"traceutil/trace.go:171","msg":"trace[1932523880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:899; }","duration":"709.000229ms","start":"2026-02-05T05:27:40.557034Z","end":"2026-02-05T05:27:41.266034Z","steps":["trace[1932523880] 'range keys from in-memory index tree' (duration: 708.703622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:41.266088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:40.557012Z","time spent":"709.07032ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T05:27:41.266288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.434577ms","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-05T05:27:41.266341Z","caller":"traceutil/trace.go:171","msg":"trace[1597982264] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:899; }","duration":"623.492099ms","start":"2026-02-05T05:27:40.642845Z","end":"2026-02-05T05:27:41.266337Z","steps":["trace[1597982264] 'range keys from in-memory index tree' (duration: 623.426217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:41.266502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.261842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:41.26652Z","caller":"traceutil/trace.go:171","msg":"trace[1710271261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"582.333194ms","start":"2026-02-05T05:27:40.684183Z","end":"2026-02-05T05:27:41.266516Z","steps":["trace[1710271261] 'range keys from in-memory index tree' (duration: 582.18568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:41.266533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:40.684162Z","time spent":"582.367414ms","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:27:41.266693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.29516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:41.266717Z","caller":"traceutil/trace.go:171","msg":"trace[540445558] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:899; }","duration":"554.340282ms","start":"2026-02-05T05:27:40.712369Z","end":"2026-02-05T05:27:41.266709Z","steps":["trace[540445558] 'count revisions from in-memory index tree' (duration: 554.248989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:41.266732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:40.712359Z","time spent":"554.367262ms","remote":"127.0.0.1:47726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:27:41.266839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.324652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T05:27:41.266858Z","caller":"traceutil/trace.go:171","msg":"trace[2104366791] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:899; }","duration":"442.390083ms","start":"2026-02-05T05:27:40.824464Z","end":"2026-02-05T05:27:41.266854Z","steps":["trace[2104366791] 'range keys from in-memory index tree' (duration: 442.2347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:41.266877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:40.82444Z","time spent":"442.433714ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T05:27:41.267012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.104941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T05:27:41.267031Z","caller":"traceutil/trace.go:171","msg":"trace[1784503092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:899; }","duration":"224.124862ms","start":"2026-02-05T05:27:41.042902Z","end":"2026-02-05T05:27:41.267027Z","steps":["trace[1784503092] 'range keys from in-memory index tree' (duration: 224.002999ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:41.479651Z","caller":"traceutil/trace.go:171","msg":"trace[1095389940] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"196.294173ms","start":"2026-02-05T05:27:41.283322Z","end":"2026-02-05T05:27:41.479616Z","steps":["trace[1095389940] 'process raft request' (duration: 195.999967ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:41.659537Z","caller":"traceutil/trace.go:171","msg":"trace[641882531] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"375.904436ms","start":"2026-02-05T05:27:41.283613Z","end":"2026-02-05T05:27:41.659517Z","steps":["trace[641882531] 'process raft request' (duration: 338.585006ms)","trace[641882531] 'compare' (duration: 36.809928ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:41.659675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:41.283533Z","time spent":"376.055608ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:41.65976Z","caller":"traceutil/trace.go:171","msg":"trace[1463368743] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"376.071709ms","start":"2026-02-05T05:27:41.28368Z","end":"2026-02-05T05:27:41.659752Z","steps":["trace[1463368743] 'process raft request' (duration: 375.477276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:41.659796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:41.283675Z","time spent":"376.102999ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:41.960237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.874327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:27:41.960362Z","caller":"traceutil/trace.go:171","msg":"trace[571381496] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"273.298925ms","start":"2026-02-05T05:27:41.687043Z","end":"2026-02-05T05:27:41.960342Z","steps":["trace[571381496] 'read index received' (duration: 80.09161ms)","trace[571381496] 'applied index is now lower than readState.Index' (duration: 193.206265ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:41.960469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.421668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:41.960496Z","caller":"traceutil/trace.go:171","msg":"trace[986895484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:903; }","duration":"273.466339ms","start":"2026-02-05T05:27:41.68702Z","end":"2026-02-05T05:27:41.960486Z","steps":["trace[986895484] 'agreement among raft nodes before linearized reading' (duration: 273.372817ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:41.960758Z","caller":"traceutil/trace.go:171","msg":"trace[1569873967] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"295.012997ms","start":"2026-02-05T05:27:41.665736Z","end":"2026-02-05T05:27:41.960749Z","steps":["trace[1569873967] 'process raft request' (duration: 101.527996ms)","trace[1569873967] 'compare' (duration: 192.395956ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:42.61679Z","caller":"traceutil/trace.go:171","msg":"trace[1651678884] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"260.204514ms","start":"2026-02-05T05:27:42.356527Z","end":"2026-02-05T05:27:42.616731Z","steps":["trace[1651678884] 'process raft request' (duration: 259.999659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:42.913588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.902715ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-05T05:27:42.91371Z","caller":"traceutil/trace.go:171","msg":"trace[934539426] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"218.139139ms","start":"2026-02-05T05:27:42.69555Z","end":"2026-02-05T05:27:42.913689Z","steps":["trace[934539426] 'read index received' (duration: 22.05534ms)","trace[934539426] 'applied index is now lower than readState.Index' (duration: 196.082269ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:42.913807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.252812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:42.913836Z","caller":"traceutil/trace.go:171","msg":"trace[1358195522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"218.335944ms","start":"2026-02-05T05:27:42.695488Z","end":"2026-02-05T05:27:42.913823Z","steps":["trace[1358195522] 'agreement among raft nodes before linearized reading' (duration: 218.251572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:58.18564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.865743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:58.1857Z","caller":"traceutil/trace.go:171","msg":"trace[1610189769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"504.960814ms","start":"2026-02-05T05:27:57.680723Z","end":"2026-02-05T05:27:58.185684Z","steps":["trace[1610189769] 'range keys from in-memory index tree' (duration: 504.79434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:58.185728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:57.680688Z","time spent":"505.032896ms","remote":"127.0.0.1:47518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:27:58.185969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.533061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T05:27:58.185988Z","caller":"traceutil/trace.go:171","msg":"trace[1904861657] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1011; }","duration":"444.594532ms","start":"2026-02-05T05:27:57.741388Z","end":"2026-02-05T05:27:58.185982Z","steps":["trace[1904861657] 'range keys from in-memory index tree' (duration: 444.432859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:58.186004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:57.741375Z","time spent":"444.625493ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T05:27:58.186127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.450043ms","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-05T05:27:58.186145Z","caller":"traceutil/trace.go:171","msg":"trace[194753431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"441.475373ms","start":"2026-02-05T05:27:57.744663Z","end":"2026-02-05T05:27:58.186139Z","steps":["trace[194753431] 'range keys from in-memory index tree' (duration: 441.403051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:58.18616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:57.744657Z","time spent":"441.500233ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:27:58.186262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.779646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-05T05:27:58.186279Z","caller":"traceutil/trace.go:171","msg":"trace[1487164671] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1011; }","duration":"188.826438ms","start":"2026-02-05T05:27:57.997447Z","end":"2026-02-05T05:27:58.186273Z","steps":["trace[1487164671] 'range keys from in-memory index tree' (duration: 188.714395ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:58.619422Z","caller":"traceutil/trace.go:171","msg":"trace[1358192101] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"426.247745ms","start":"2026-02-05T05:27:58.193149Z","end":"2026-02-05T05:27:58.619397Z","steps":["trace[1358192101] 'process raft request' (duration: 426.098222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:58.619666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:58.193136Z","time spent":"426.382598ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:58.634256Z","caller":"traceutil/trace.go:171","msg":"trace[24734039] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1051; }","duration":"427.259198ms","start":"2026-02-05T05:27:58.206983Z","end":"2026-02-05T05:27:58.634242Z","steps":["trace[24734039] 'read index received' (duration: 412.312105ms)","trace[24734039] 'applied index is now lower than readState.Index' (duration: 14.946143ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:58.634378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.38541ms","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-05T05:27:58.634396Z","caller":"traceutil/trace.go:171","msg":"trace[1582053485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1014; }","duration":"427.444502ms","start":"2026-02-05T05:27:58.206946Z","end":"2026-02-05T05:27:58.63439Z","steps":["trace[1582053485] 'agreement among raft nodes before linearized reading' (duration: 427.35155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:58.634412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:58.206936Z","time spent":"427.471552ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T05:27:58.634547Z","caller":"traceutil/trace.go:171","msg":"trace[7314802] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"439.161662ms","start":"2026-02-05T05:27:58.195379Z","end":"2026-02-05T05:27:58.634541Z","steps":["trace[7314802] 'process raft request' (duration: 438.780054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:58.634592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:58.195372Z","time spent":"439.186483ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:58.634649Z","caller":"traceutil/trace.go:171","msg":"trace[1216397837] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"439.233013ms","start":"2026-02-05T05:27:58.195412Z","end":"2026-02-05T05:27:58.634645Z","steps":["trace[1216397837] 'process raft request' (duration: 438.808474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:58.634674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:58.195409Z","time spent":"439.251354ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:43.996838Z","caller":"traceutil/trace.go:171","msg":"trace[1157176396] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"111.968797ms","start":"2026-02-05T05:28:43.88485Z","end":"2026-02-05T05:28:43.996818Z","steps":["trace[1157176396] 'process raft request' (duration: 102.745652ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:47.267551Z","caller":"traceutil/trace.go:171","msg":"trace[1137488597] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"123.399142ms","start":"2026-02-05T05:28:47.144124Z","end":"2026-02-05T05:28:47.267523Z","steps":["trace[1137488597] 'process raft request' (duration: 59.83584ms)","trace[1137488597] 'compare' (duration: 63.45868ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:13.554479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.182307ms","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-05T05:29:13.554542Z","caller":"traceutil/trace.go:171","msg":"trace[1866028686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1319; }","duration":"110.307352ms","start":"2026-02-05T05:29:13.444219Z","end":"2026-02-05T05:29:13.554526Z","steps":["trace[1866028686] 'range keys from in-memory index tree' (duration: 109.956052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:24.008612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.270427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:29:24.008743Z","caller":"traceutil/trace.go:171","msg":"trace[1476606128] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"153.511231ms","start":"2026-02-05T05:29:23.855208Z","end":"2026-02-05T05:29:24.008719Z","steps":["trace[1476606128] 'process raft request' (duration: 49.044809ms)","trace[1476606128] 'compare' (duration: 103.940159ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:24.221023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.631169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-05T05:29:24.22111Z","caller":"traceutil/trace.go:171","msg":"trace[2080011044] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1363; }","duration":"111.765812ms","start":"2026-02-05T05:29:24.109326Z","end":"2026-02-05T05:29:24.221092Z","steps":["trace[2080011044] 'range keys from in-memory index tree' (duration: 111.317213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:56.294426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.353582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T05:29:56.296387Z","caller":"traceutil/trace.go:171","msg":"trace[980439147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1490; }","duration":"387.333045ms","start":"2026-02-05T05:29:55.908997Z","end":"2026-02-05T05:29:56.29633Z","steps":["trace[980439147] 'range keys from in-memory index tree' (duration: 385.195668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:56.29649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:55.908978Z","time spent":"387.481718ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T05:29:56.294795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.225832ms","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-05T05:29:56.296956Z","caller":"traceutil/trace.go:171","msg":"trace[1003728700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1490; }","duration":"331.359599ms","start":"2026-02-05T05:29:55.965527Z","end":"2026-02-05T05:29:56.296887Z","steps":["trace[1003728700] 'range keys from in-memory index tree' (duration: 329.11131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:56.297011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:55.965475Z","time spent":"331.523023ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:29:56.294827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.909218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:29:56.297204Z","caller":"traceutil/trace.go:171","msg":"trace[465761383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1490; }","duration":"299.344952ms","start":"2026-02-05T05:29:55.997848Z","end":"2026-02-05T05:29:56.297193Z","steps":["trace[465761383] 'range keys from in-memory index tree' (duration: 296.808206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:56.294861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.077229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T05:29:56.297501Z","caller":"traceutil/trace.go:171","msg":"trace[678839365] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1490; }","duration":"245.745758ms","start":"2026-02-05T05:29:56.051743Z","end":"2026-02-05T05:29:56.297489Z","steps":["trace[678839365] 'count revisions from in-memory index tree' (duration: 243.016678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:56.29574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.6234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-05T05:29:56.297697Z","caller":"traceutil/trace.go:171","msg":"trace[51316598] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1490; }","duration":"140.353371ms","start":"2026-02-05T05:29:56.157334Z","end":"2026-02-05T05:29:56.297687Z","steps":["trace[51316598] 'range keys from in-memory index tree' (duration: 137.417035ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:56.432512Z","caller":"traceutil/trace.go:171","msg":"trace[110491956] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"103.645029ms","start":"2026-02-05T05:29:56.328849Z","end":"2026-02-05T05:29:56.432494Z","steps":["trace[110491956] 'process raft request' (duration: 103.502756ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:56.435626Z","caller":"traceutil/trace.go:171","msg":"trace[1586022456] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"103.460775ms","start":"2026-02-05T05:29:56.332115Z","end":"2026-02-05T05:29:56.435575Z","steps":["trace[1586022456] 'process raft request' (duration: 103.419694ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:56.436061Z","caller":"traceutil/trace.go:171","msg":"trace[1520343074] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"107.094185ms","start":"2026-02-05T05:29:56.328948Z","end":"2026-02-05T05:29:56.436042Z","steps":["trace[1520343074] 'process raft request' (duration: 106.444011ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:56.436513Z","caller":"traceutil/trace.go:171","msg":"trace[1776673988] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"107.529234ms","start":"2026-02-05T05:29:56.328974Z","end":"2026-02-05T05:29:56.436504Z","steps":["trace[1776673988] 'process raft request' (duration: 106.527762ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:01.260274Z","caller":"traceutil/trace.go:171","msg":"trace[2145618257] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"148.893679ms","start":"2026-02-05T05:30:01.111354Z","end":"2026-02-05T05:30:01.260248Z","steps":["trace[2145618257] 'process raft request' (duration: 148.562652ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:14.674206Z","caller":"traceutil/trace.go:171","msg":"trace[912923038] transaction","detail":"{read_only:false; response_revision:1567; number_of_response:1; }","duration":"109.250433ms","start":"2026-02-05T05:30:14.564939Z","end":"2026-02-05T05:30:14.67419Z","steps":["trace[912923038] 'process raft request' (duration: 108.950317ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:18.139884Z","caller":"traceutil/trace.go:171","msg":"trace[873898052] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"103.204869ms","start":"2026-02-05T05:30:18.036658Z","end":"2026-02-05T05:30:18.139863Z","steps":["trace[873898052] 'process raft request' (duration: 103.065796ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:31:05.672224Z","caller":"traceutil/trace.go:171","msg":"trace[1347187744] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"103.367788ms","start":"2026-02-05T05:31:05.566854Z","end":"2026-02-05T05:31:05.670222Z","steps":["trace[1347187744] 'process raft request' (duration: 27.70141ms)","trace[1347187744] 'compare' (duration: 75.561745ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:31:11.378872Z","caller":"traceutil/trace.go:171","msg":"trace[1018560384] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1898; }","duration":"136.279583ms","start":"2026-02-05T05:31:11.242575Z","end":"2026-02-05T05:31:11.378854Z","steps":["trace[1018560384] 'read index received' (duration: 55.116684ms)","trace[1018560384] 'applied index is now lower than readState.Index' (duration: 81.162079ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:31:11.379144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.561899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-02-05T05:31:11.379227Z","caller":"traceutil/trace.go:171","msg":"trace[749784081] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1820; }","duration":"136.676473ms","start":"2026-02-05T05:31:11.242541Z","end":"2026-02-05T05:31:11.379217Z","steps":["trace[749784081] 'agreement among raft nodes before linearized reading' (duration: 136.387296ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:31:11.379493Z","caller":"traceutil/trace.go:171","msg":"trace[651253562] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"205.970261ms","start":"2026-02-05T05:31:11.173514Z","end":"2026-02-05T05:31:11.379484Z","steps":["trace[651253562] 'process raft request' (duration: 124.242458ms)","trace[651253562] 'compare' (duration: 80.875133ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:31:17.487535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.342991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/csi-0ce09b408186ae8cbe2d4d43b91f7c04d89d98f4a235f0cc245adb018558d41f\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-02-05T05:31:17.487602Z","caller":"traceutil/trace.go:171","msg":"trace[1989353456] range","detail":"{range_begin:/registry/volumeattachments/csi-0ce09b408186ae8cbe2d4d43b91f7c04d89d98f4a235f0cc245adb018558d41f; range_end:; response_count:1; response_revision:1844; }","duration":"158.451244ms","start":"2026-02-05T05:31:17.329136Z","end":"2026-02-05T05:31:17.487587Z","steps":["trace[1989353456] 'range keys from in-memory index tree' (duration: 158.186298ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:31:20.839052Z","caller":"traceutil/trace.go:171","msg":"trace[1245208798] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"103.719416ms","start":"2026-02-05T05:31:20.735293Z","end":"2026-02-05T05:31:20.839012Z","steps":["trace[1245208798] 'process raft request' (duration: 103.489581ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:31:35.03642Z","caller":"traceutil/trace.go:171","msg":"trace[1862215472] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"112.53712ms","start":"2026-02-05T05:31:34.923853Z","end":"2026-02-05T05:31:35.03639Z","steps":["trace[1862215472] 'process raft request' (duration: 112.297215ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:31:35.037223Z","caller":"traceutil/trace.go:171","msg":"trace[1606596061] linearizableReadLoop","detail":"{readStateIndex:2014; appliedIndex:2014; }","duration":"103.140524ms","start":"2026-02-05T05:31:34.934053Z","end":"2026-02-05T05:31:35.037194Z","steps":["trace[1606596061] 'read index received' (duration: 103.130913ms)","trace[1606596061] 'applied index is now lower than readState.Index' (duration: 7.201µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:31:35.037585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.477011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:4 size:1383"} {"level":"info","ts":"2026-02-05T05:31:35.037667Z","caller":"traceutil/trace.go:171","msg":"trace[1979166832] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:4; response_revision:1929; }","duration":"103.622694ms","start":"2026-02-05T05:31:34.934032Z","end":"2026-02-05T05:31:35.037655Z","steps":["trace[1979166832] 'agreement among raft nodes before linearized reading' (duration: 103.44208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:31:35.03774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.765132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium\" ","response":"range_response_count:1 size:1654"} {"level":"info","ts":"2026-02-05T05:31:35.037811Z","caller":"traceutil/trace.go:171","msg":"trace[957939288] range","detail":"{range_begin:/registry/clusterroles/cilium; range_end:; response_count:1; response_revision:1929; }","duration":"101.899666ms","start":"2026-02-05T05:31:34.935894Z","end":"2026-02-05T05:31:35.037793Z","steps":["trace[957939288] 'agreement among raft nodes before linearized reading' (duration: 101.62743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:31:42.159629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.052061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pvc-protection-controller\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-02-05T05:31:42.159729Z","caller":"traceutil/trace.go:171","msg":"trace[1027974527] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pvc-protection-controller; range_end:; response_count:1; response_revision:1961; }","duration":"132.192314ms","start":"2026-02-05T05:31:42.027512Z","end":"2026-02-05T05:31:42.159704Z","steps":["trace[1027974527] 'range keys from in-memory index tree' (duration: 131.979069ms)"],"step_count":1}